builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0006 starttime: 1462581618.59 results: success (0) buildid: 20160506162552 builduid: b72a63e889694b7881d89602fbd1d472 revision: 025d85d8686491f7975b9d2e86b97180e3f9e0fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 17:40:18.594001) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 17:40:18.594849) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 17:40:18.595426) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 17:40:18.614552) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 17:40:18.615144) ========= 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.wKm9xxMYqV/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.PVfL1NTWpf/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-06 17:40:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.60M=0.002s 2016-05-06 17:40:18 (7.60 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.136905 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 17:40:18.775317) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 17:40:18.776126) ========= 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.wKm9xxMYqV/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.PVfL1NTWpf/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.019910 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 17:40:18.828371) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 17:40:18.829208) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 025d85d8686491f7975b9d2e86b97180e3f9e0fa --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-beta --rev 025d85d8686491f7975b9d2e86b97180e3f9e0fa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wKm9xxMYqV/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.PVfL1NTWpf/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-06 17:40:18,904 truncating revision to first 12 chars 2016-05-06 17:40:18,904 Setting DEBUG logging. 2016-05-06 17:40:18,905 attempt 1/10 2016-05-06 17:40:18,905 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/025d85d86864?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 17:40:19,611 unpacking tar archive at: mozilla-beta-025d85d86864/testing/mozharness/ program finished with exit code 0 elapsedTime=0.954545 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 17:40:19.802527) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 17:40:19.802939) ========= script_repo_revision: 025d85d8686491f7975b9d2e86b97180e3f9e0fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 17:40:19.803433) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 17:40:19.803759) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 17:40:19.817420) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-05-06 17:40:19.817772) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wKm9xxMYqV/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.PVfL1NTWpf/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 17:40:19 INFO - MultiFileLogger online at 20160506 17:40:19 in /builds/slave/test 17:40:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:40:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:40:20 INFO - {'append_to_log': False, 17:40:20 INFO - 'base_work_dir': '/builds/slave/test', 17:40:20 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:40:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:40:20 INFO - 'branch': 'Mozilla-Beta', 17:40:20 INFO - 'buildbot_json_path': 'buildprops.json', 17:40:20 INFO - 'config_files': ('talos/mac_config.py',), 17:40:20 INFO - 'default_actions': ('clobber', 17:40:20 INFO - 'read-buildbot-config', 17:40:20 INFO - 'download-and-extract', 17:40:20 INFO - 'populate-webroot', 17:40:20 INFO - 'create-virtualenv', 17:40:20 INFO - 'install', 17:40:20 INFO - 'run-tests'), 17:40:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:40:20 INFO - 'download_minidump_stackwalk': True, 17:40:20 INFO - 'download_symbols': 'ondemand', 17:40:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:40:20 INFO - 'tooltool.py': '/tools/tooltool.py', 17:40:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:40:20 INFO - '/tools/misc-python/virtualenv.py')}, 17:40:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:40:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:40:20 INFO - 'installer_path': 'installer.exe', 17:40:20 INFO - 'log_level': 'info', 17:40:20 INFO - 'log_name': 'talos', 17:40:20 INFO - 'log_to_console': True, 17:40:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:40:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:40:20 INFO - 'opt_config_files': (), 17:40:20 INFO - 'pip_index': False, 17:40:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:40:20 INFO - 'cmd': ('bash', 17:40:20 INFO - '-c', 17:40:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:40:20 INFO - 'enabled': True, 17:40:20 INFO - 'halt_on_failure': False, 17:40:20 INFO - 'name': 'check_screen_resolution'},), 17:40:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:40:20 INFO - 'cmd': ('bash', 17:40:20 INFO - '-c', 17:40:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:40:20 INFO - 'enabled': True, 17:40:20 INFO - 'halt_on_failure': False, 17:40:20 INFO - 'name': 'check_screen_resolution'},), 17:40:20 INFO - 'run_cmd_checks_enabled': True, 17:40:20 INFO - 'sps_profile': False, 17:40:20 INFO - 'sps_profile_interval': 0, 17:40:20 INFO - 'suite': 'dromaeojs', 17:40:20 INFO - 'system_bits': '32', 17:40:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:40:20 INFO - 'title': 't-yosemite-r7-0006', 17:40:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:40:20 INFO - 'use_talos_json': True, 17:40:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:40:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:40:20 INFO - 'work_dir': 'build'} 17:40:20 INFO - ##### 17:40:20 INFO - ##### Running clobber step. 17:40:20 INFO - ##### 17:40:20 INFO - Running pre-action listener: _resource_record_pre_action 17:40:20 INFO - Running main action method: clobber 17:40:20 INFO - rmtree: /builds/slave/test/build 17:40:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:40:21 INFO - Running post-action listener: _resource_record_post_action 17:40:21 INFO - ##### 17:40:21 INFO - ##### Running read-buildbot-config step. 17:40:21 INFO - ##### 17:40:21 INFO - Running pre-action listener: _resource_record_pre_action 17:40:21 INFO - Running main action method: read_buildbot_config 17:40:21 INFO - Using buildbot properties: 17:40:21 INFO - { 17:40:21 INFO - "project": "", 17:40:21 INFO - "product": "firefox", 17:40:21 INFO - "script_repo_revision": "production", 17:40:21 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 17:40:21 INFO - "repository": "", 17:40:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 17:40:21 INFO - "buildid": "20160506162552", 17:40:21 INFO - "pgo_build": "False", 17:40:21 INFO - "basedir": "/builds/slave/test", 17:40:21 INFO - "buildnumber": 2, 17:40:21 INFO - "slavename": "t-yosemite-r7-0006", 17:40:21 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 17:40:21 INFO - "platform": "yosemite_r7", 17:40:21 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 17:40:21 INFO - "branch": "mozilla-beta", 17:40:21 INFO - "repo_path": "releases/mozilla-beta", 17:40:21 INFO - "revision": "025d85d8686491f7975b9d2e86b97180e3f9e0fa", 17:40:21 INFO - "stage_platform": "macosx64", 17:40:21 INFO - "builduid": "b72a63e889694b7881d89602fbd1d472", 17:40:21 INFO - "slavebuilddir": "test" 17:40:21 INFO - } 17:40:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 17:40:21 INFO - Running post-action listener: _resource_record_post_action 17:40:21 INFO - ##### 17:40:21 INFO - ##### Running download-and-extract step. 17:40:21 INFO - ##### 17:40:21 INFO - Running pre-action listener: _resource_record_pre_action 17:40:21 INFO - Running main action method: download_and_extract 17:40:21 INFO - mkdir: /builds/slave/test/build/tests 17:40: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')]} 17:40:21 INFO - https://queue.taskcluster.net/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:40:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 17:40:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 17:40:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 17:40:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 17:40:22 INFO - Downloaded 1241 bytes. 17:40:22 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 17:40:22 INFO - Using the following test package requirements: 17:40:22 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 17:40:22 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 17:40:22 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 17:40:22 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 17:40:22 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 17:40:22 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 17:40:22 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 17:40:22 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 17:40:22 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 17:40:22 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 17:40:22 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 17:40:22 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 17:40:22 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 17:40:22 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 17:40:22 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 17:40:22 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 17:40:22 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 17:40:22 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 17:40:22 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 17:40:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:22 INFO - https://queue.taskcluster.net/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:40:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 17:40:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 17:40:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 17:40:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 17:40:24 INFO - Downloaded 17747432 bytes. 17:40:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:40:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:40:27 INFO - Return code: 0 17:40:27 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 17:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:27 INFO - https://queue.taskcluster.net/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:40:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 17:40:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 17:40:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 17:40:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 17:40:28 INFO - Downloaded 17747432 bytes. 17:40:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:40:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:40:31 INFO - Return code: 0 17:40:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:31 INFO - https://queue.taskcluster.net/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:40:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 17:40:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 17:40:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 17:40:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 17:40:32 INFO - Downloaded 10845194 bytes. 17:40:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:40:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 17:40:32 INFO - Return code: 0 17:40: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')]} 17:40:32 INFO - https://queue.taskcluster.net/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 17:40:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 17:40:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 17:40:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 17:40:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 17:40:35 INFO - Downloaded 88340582 bytes. 17:40:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 17:40:35 INFO - mkdir: /builds/slave/test/properties 17:40:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:40:35 INFO - Writing to file /builds/slave/test/properties/build_url 17:40:35 INFO - Contents: 17:40:35 INFO - build_url:https://queue.taskcluster.net/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 17:40:36 INFO - Running post-action listener: _resource_record_post_action 17:40:36 INFO - Running post-action listener: set_extra_try_arguments 17:40:36 INFO - ##### 17:40:36 INFO - ##### Running populate-webroot step. 17:40:36 INFO - ##### 17:40:36 INFO - Running pre-action listener: _resource_record_pre_action 17:40:36 INFO - Running main action method: populate_webroot 17:40:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:40:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:40:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:40:36 INFO - '--tppagecycles', 17:40:36 INFO - '7'], 17:40:36 INFO - 'tests': ['tsvgm']}}, 17:40:36 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 17:40:36 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 17:40:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:40:36 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 17:40:36 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 17:40:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:40:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:40:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:36 INFO - 'talos_options': ['--e10s'], 17:40:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:40:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:36 INFO - 'tests': ['damp', 'tps']}, 17:40:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:36 INFO - 'talos_options': ['--e10s'], 17:40:36 INFO - 'tests': ['damp', 'tps']}, 17:40:36 INFO - 'g3': {'tests': ['dromaeo_dom']}, 17:40:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:40:36 INFO - 'other': {'tests': ['a11yr', 17:40:36 INFO - 'ts_paint', 17:40:36 INFO - 'tpaint', 17:40:36 INFO - 'sessionrestore', 17:40:36 INFO - 'sessionrestore_no_auto_restore', 17:40:36 INFO - 'tabpaint']}, 17:40:36 INFO - 'other-e10s': {'talos_options': ['--e10s'], 17:40:36 INFO - 'tests': ['a11yr', 17:40:36 INFO - 'ts_paint', 17:40:36 INFO - 'tpaint', 17:40:36 INFO - 'sessionrestore', 17:40:36 INFO - 'sessionrestore_no_auto_restore', 17:40:36 INFO - 'tabpaint']}, 17:40:36 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 17:40:36 INFO - 'tests': ['a11yr', 17:40:36 INFO - 'ts_paint', 17:40:36 INFO - 'tpaint', 17:40:36 INFO - 'sessionrestore', 17:40:36 INFO - 'sessionrestore_no_auto_restore', 17:40:36 INFO - 'tabpaint']}, 17:40:36 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 17:40:36 INFO - 'tests': ['a11yr', 17:40:36 INFO - 'ts_paint', 17:40:36 INFO - 'tpaint', 17:40:36 INFO - 'sessionrestore', 17:40:36 INFO - 'sessionrestore_no_auto_restore', 17:40:36 INFO - 'tabpaint']}, 17:40:36 INFO - 'other_l64': {'tests': ['a11yr', 17:40:36 INFO - 'ts_paint', 17:40:36 INFO - 'tpaint', 17:40:36 INFO - 'sessionrestore', 17:40:36 INFO - 'sessionrestore_no_auto_restore', 17:40:36 INFO - 'tabpaint']}, 17:40:36 INFO - 'other_nol64': {'tests': ['a11yr', 17:40:36 INFO - 'ts_paint', 17:40:36 INFO - 'tpaint', 17:40:36 INFO - 'sessionrestore', 17:40:36 INFO - 'sessionrestore_no_auto_restore', 17:40:36 INFO - 'tabpaint']}, 17:40:36 INFO - 'svgr': {'tests': ['tsvgx', 17:40:36 INFO - 'tsvgr_opacity', 17:40:36 INFO - 'tart', 17:40:36 INFO - 'tscrollx', 17:40:36 INFO - 'cart']}, 17:40:36 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 17:40:36 INFO - 'tests': ['tsvgx', 17:40:36 INFO - 'tsvgr_opacity', 17:40:36 INFO - 'tart', 17:40:36 INFO - 'tscrollx', 17:40:36 INFO - 'cart']}, 17:40:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:36 INFO - 'tests': ['tp5o']}, 17:40:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:36 INFO - 'talos_options': ['--e10s'], 17:40:36 INFO - 'tests': ['tp5o']}, 17:40:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:36 INFO - 'talos_options': ['--xperf_path', 17:40:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:40:36 INFO - 'tests': ['tp5n']}, 17:40:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:40:36 INFO - 'talos_options': ['--e10s', 17:40:36 INFO - '--xperf_path', 17:40:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:40:36 INFO - 'tests': ['tp5n']}}, 17:40:36 INFO - 'talos.zip': {'path': '', 17:40:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:40:36 INFO - Running post-action listener: _resource_record_post_action 17:40:36 INFO - ##### 17:40:36 INFO - ##### Running create-virtualenv step. 17:40:36 INFO - ##### 17:40:36 INFO - Running pre-action listener: _resource_record_pre_action 17:40:36 INFO - Running main action method: create_virtualenv 17:40:36 INFO - Creating virtualenv /builds/slave/test/build/venv 17:40:36 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 17:40:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:40:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:40:37 INFO - Using real prefix '/tools/python27' 17:40:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:40:37 INFO - Installing distribute.............................................................................................................................................................................................done. 17:40:40 INFO - Installing pip.................done. 17:40:40 INFO - Return code: 0 17:40:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:40:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10914da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091544c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf9ccdcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff1d30>, '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 0x108e55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe8b20>, '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.wKm9xxMYqV/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.PVfL1NTWpf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 17:40:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:40:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:40:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKm9xxMYqV/Render', 17:40:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:40 INFO - 'HOME': '/Users/cltbld', 17:40:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:40 INFO - 'LOGNAME': 'cltbld', 17:40:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:40 INFO - 'MOZ_NO_REMOTE': '1', 17:40:40 INFO - 'NO_EM_RESTART': '1', 17:40:40 INFO - 'PAGER': '/bin/cat', 17:40:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:40 INFO - 'PWD': '/builds/slave/test', 17:40:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:40 INFO - 'SHELL': '/bin/bash', 17:40:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PVfL1NTWpf/Listeners', 17:40:40 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 17:40:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:40 INFO - 'USER': 'cltbld', 17:40:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:40 INFO - 'XPC_FLAGS': '0x0', 17:40:40 INFO - 'XPC_SERVICE_NAME': '0', 17:40:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:40 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 17:40:40 INFO - Cleaning up... 17:40:40 INFO - Return code: 0 17:40:40 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:40:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10914da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091544c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf9ccdcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff1d30>, '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 0x108e55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe8b20>, '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.wKm9xxMYqV/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.PVfL1NTWpf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 17:40:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 17:40:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 17:40:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKm9xxMYqV/Render', 17:40:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:40 INFO - 'HOME': '/Users/cltbld', 17:40:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:40 INFO - 'LOGNAME': 'cltbld', 17:40:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:40 INFO - 'MOZ_NO_REMOTE': '1', 17:40:40 INFO - 'NO_EM_RESTART': '1', 17:40:40 INFO - 'PAGER': '/bin/cat', 17:40:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:40 INFO - 'PWD': '/builds/slave/test', 17:40:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:40 INFO - 'SHELL': '/bin/bash', 17:40:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PVfL1NTWpf/Listeners', 17:40:40 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 17:40:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:40 INFO - 'USER': 'cltbld', 17:40:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:40 INFO - 'XPC_FLAGS': '0x0', 17:40:40 INFO - 'XPC_SERVICE_NAME': '0', 17:40:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:41 INFO - Downloading/unpacking psutil>=0.7.1 17:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:40:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:40:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:40:43 INFO - Installing collected packages: psutil 17:40:43 INFO - Running setup.py install for psutil 17:40:43 INFO - building 'psutil._psutil_osx' extension 17:40:43 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 17:40:44 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 17:40:44 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 17:40:44 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 17:40:44 INFO - building 'psutil._psutil_posix' extension 17:40:44 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 17:40:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:40:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:40:44 INFO - ^ 17:40:44 INFO - 1 warning generated. 17:40:44 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 17:40:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:40:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:40:44 INFO - Successfully installed psutil 17:40:44 INFO - Cleaning up... 17:40:44 INFO - Return code: 0 17:40:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:40:44 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')]} 17:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:44 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')]} 17:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:44 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 0x10914da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091544c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf9ccdcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff1d30>, '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 0x108e55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe8b20>, '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.wKm9xxMYqV/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.PVfL1NTWpf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 17:40:44 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 17:40:44 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 17:40:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKm9xxMYqV/Render', 17:40:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:44 INFO - 'HOME': '/Users/cltbld', 17:40:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:44 INFO - 'LOGNAME': 'cltbld', 17:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:44 INFO - 'MOZ_NO_REMOTE': '1', 17:40:44 INFO - 'NO_EM_RESTART': '1', 17:40:44 INFO - 'PAGER': '/bin/cat', 17:40:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:44 INFO - 'PWD': '/builds/slave/test', 17:40:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:44 INFO - 'SHELL': '/bin/bash', 17:40:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PVfL1NTWpf/Listeners', 17:40:44 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 17:40:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:44 INFO - 'USER': 'cltbld', 17:40:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:44 INFO - 'XPC_FLAGS': '0x0', 17:40:44 INFO - 'XPC_SERVICE_NAME': '0', 17:40:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:47 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:40:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:40:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:40:47 INFO - Installing collected packages: mozsystemmonitor 17:40:47 INFO - Running setup.py install for mozsystemmonitor 17:40:47 INFO - Successfully installed mozsystemmonitor 17:40:47 INFO - Cleaning up... 17:40:47 INFO - Return code: 0 17:40:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:40:47 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')]} 17:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:47 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')]} 17:40:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:47 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 0x10914da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091544c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf9ccdcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff1d30>, '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 0x108e55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe8b20>, '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.wKm9xxMYqV/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.PVfL1NTWpf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 17:40:47 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 17:40:47 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 17:40:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKm9xxMYqV/Render', 17:40:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:47 INFO - 'HOME': '/Users/cltbld', 17:40:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:47 INFO - 'LOGNAME': 'cltbld', 17:40:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:47 INFO - 'MOZ_NO_REMOTE': '1', 17:40:47 INFO - 'NO_EM_RESTART': '1', 17:40:47 INFO - 'PAGER': '/bin/cat', 17:40:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:47 INFO - 'PWD': '/builds/slave/test', 17:40:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:47 INFO - 'SHELL': '/bin/bash', 17:40:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PVfL1NTWpf/Listeners', 17:40:47 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 17:40:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:47 INFO - 'USER': 'cltbld', 17:40:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:47 INFO - 'XPC_FLAGS': '0x0', 17:40:47 INFO - 'XPC_SERVICE_NAME': '0', 17:40:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:47 INFO - Downloading/unpacking blobuploader==1.2.4 17:40:47 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 17:40:47 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 17:40:47 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 17:40:47 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 17:40:47 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 17:40:47 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 17:40:50 INFO - Downloading blobuploader-1.2.4.tar.gz 17:40:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:40:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:40:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:51 INFO - Downloading docopt-0.6.1.tar.gz 17:40:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:40:51 INFO - Installing collected packages: blobuploader, requests, docopt 17:40:51 INFO - Running setup.py install for blobuploader 17:40:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:40:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:40:51 INFO - Running setup.py install for requests 17:40:52 INFO - Running setup.py install for docopt 17:40:52 INFO - Successfully installed blobuploader requests docopt 17:40:52 INFO - Cleaning up... 17:40:52 INFO - Return code: 0 17:40:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:40:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:40:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:52 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 0x10914da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091544c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf9ccdcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff1d30>, '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 0x108e55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe8b20>, '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.wKm9xxMYqV/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.PVfL1NTWpf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 17:40:52 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 17:40:52 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 17:40:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKm9xxMYqV/Render', 17:40:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:52 INFO - 'HOME': '/Users/cltbld', 17:40:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:52 INFO - 'LOGNAME': 'cltbld', 17:40:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:52 INFO - 'MOZ_NO_REMOTE': '1', 17:40:52 INFO - 'NO_EM_RESTART': '1', 17:40:52 INFO - 'PAGER': '/bin/cat', 17:40:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:52 INFO - 'PWD': '/builds/slave/test', 17:40:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:52 INFO - 'SHELL': '/bin/bash', 17:40:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PVfL1NTWpf/Listeners', 17:40:52 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 17:40:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:52 INFO - 'USER': 'cltbld', 17:40:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:52 INFO - 'XPC_FLAGS': '0x0', 17:40:52 INFO - 'XPC_SERVICE_NAME': '0', 17:40:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:40:52 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-5qNJLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:40:52 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ab6JwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:40:52 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-KH5GWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:40:52 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-t6j62f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:40:53 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-PvGStf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:40:53 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-i5c1Do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:40:53 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-fwUyIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:40:53 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-DDDGwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:40:53 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ng4ign-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:40:53 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-feKRfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:40:53 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-d1QYm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:40:53 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-zcD86X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:40:53 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-eUnDkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:40:54 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-AVhklk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:40:54 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-71DNLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:40:54 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-NLSzlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:40:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:40:54 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-owmUtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:40:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:40:54 INFO - Running setup.py install for manifestparser 17:40:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:40:54 INFO - Running setup.py install for mozcrash 17:40:54 INFO - Running setup.py install for mozdebug 17:40:54 INFO - Running setup.py install for mozdevice 17:40:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:40:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:40:55 INFO - Running setup.py install for mozfile 17:40:55 INFO - Running setup.py install for mozhttpd 17:40:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:40:55 INFO - Running setup.py install for mozinfo 17:40:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:40:55 INFO - Running setup.py install for mozInstall 17:40:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:40:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:40:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:40:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:40:55 INFO - Running setup.py install for mozleak 17:40:55 INFO - Running setup.py install for mozlog 17:40:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:40:55 INFO - Running setup.py install for moznetwork 17:40:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:40:56 INFO - Running setup.py install for mozprocess 17:40:56 INFO - Running setup.py install for mozprofile 17:40:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:40:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:40:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:40:56 INFO - Running setup.py install for mozrunner 17:40:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:40:56 INFO - Running setup.py install for mozscreenshot 17:40:56 INFO - Running setup.py install for moztest 17:40:56 INFO - Running setup.py install for mozversion 17:40:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:40:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:40:56 INFO - Cleaning up... 17:40:56 INFO - Return code: 0 17:40:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:40: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')]} 17:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:40:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40: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')]} 17:40:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:40:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:40:56 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 0x10914da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091544c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf9ccdcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff1d30>, '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 0x108e55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe8b20>, '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.wKm9xxMYqV/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.PVfL1NTWpf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 17:40:56 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 17:40:56 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 17:40:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKm9xxMYqV/Render', 17:40:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:40:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:40:56 INFO - 'HOME': '/Users/cltbld', 17:40:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:40:56 INFO - 'LOGNAME': 'cltbld', 17:40:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:40:56 INFO - 'MOZ_NO_REMOTE': '1', 17:40:56 INFO - 'NO_EM_RESTART': '1', 17:40:56 INFO - 'PAGER': '/bin/cat', 17:40:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:40:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:40:56 INFO - 'PWD': '/builds/slave/test', 17:40:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:40:56 INFO - 'SHELL': '/bin/bash', 17:40:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PVfL1NTWpf/Listeners', 17:40:56 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 17:40:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:40:56 INFO - 'USER': 'cltbld', 17:40:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:40:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:40:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:40:56 INFO - 'XPC_FLAGS': '0x0', 17:40:56 INFO - 'XPC_SERVICE_NAME': '0', 17:40:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:40:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-OYHFp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:40:57 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)) 17:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:40:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-j1q5It-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:40:57 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)) 17:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:40:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-5VUlI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:40:57 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)) 17:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:40:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-WK6Qj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:40:57 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)) 17:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:40:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-1kFv3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:40:57 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)) 17:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:40:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-DdWP4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:40:57 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)) 17:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:40:57 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-J6A9F4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:40:58 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)) 17:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:40:58 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-QL7viN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:40:58 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)) 17:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:40:58 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-5qXQ3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:40:58 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)) 17:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:40:58 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-gctS2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:40:58 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)) 17:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:40:58 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-lbfxDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:40:58 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)) 17:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:40:58 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-RU8Njq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:40:58 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)) 17:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:40:58 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ZPn2jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:40:58 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)) 17:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:40:58 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-W8uy79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:40:58 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)) 17:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:40:58 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-UhSvDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:40:58 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)) 17:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:40:58 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-mTnFyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:40:58 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)) 17:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:40:58 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-WuGro_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:40:59 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)) 17:40:59 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)) 17:40:59 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)) 17:40:59 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)) 17:40:59 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)) 17:40:59 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)) 17:40:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:40:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:40:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:41:01 INFO - Downloading blessings-1.6.tar.gz 17:41:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:41:01 INFO - Installing collected packages: blessings 17:41:01 INFO - Running setup.py install for blessings 17:41:01 INFO - Successfully installed blessings 17:41:01 INFO - Cleaning up... 17:41:01 INFO - Return code: 0 17:41:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:41:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:41:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:41:02 INFO - Reading from file tmpfile_stdout 17:41:02 INFO - Current package versions: 17:41:02 INFO - blessings == 1.6 17:41:02 INFO - blobuploader == 1.2.4 17:41:02 INFO - docopt == 0.6.1 17:41:02 INFO - manifestparser == 1.1 17:41:02 INFO - mozInstall == 1.12 17:41:02 INFO - mozcrash == 0.17 17:41:02 INFO - mozdebug == 0.1 17:41:02 INFO - mozdevice == 0.48 17:41:02 INFO - mozfile == 1.2 17:41:02 INFO - mozhttpd == 0.7 17:41:02 INFO - mozinfo == 0.9 17:41:02 INFO - mozleak == 0.1 17:41:02 INFO - mozlog == 3.1 17:41:02 INFO - moznetwork == 0.27 17:41:02 INFO - mozprocess == 0.22 17:41:02 INFO - mozprofile == 0.28 17:41:02 INFO - mozrunner == 6.11 17:41:02 INFO - mozscreenshot == 0.1 17:41:02 INFO - mozsystemmonitor == 0.0 17:41:02 INFO - moztest == 0.7 17:41:02 INFO - mozversion == 1.4 17:41:02 INFO - psutil == 3.1.1 17:41:02 INFO - requests == 1.2.3 17:41:02 INFO - wsgiref == 0.1.2 17:41:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:41: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')]} 17:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:41:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41: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')]} 17:41:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:41:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41:02 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 0x10914da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091544c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf9ccdcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff1d30>, '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 0x108e55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe8b20>, '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.wKm9xxMYqV/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.PVfL1NTWpf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 17:41:02 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 17:41:02 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 17:41:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKm9xxMYqV/Render', 17:41:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:41:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:41:02 INFO - 'HOME': '/Users/cltbld', 17:41:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:41:02 INFO - 'LOGNAME': 'cltbld', 17:41:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:02 INFO - 'MOZ_NO_REMOTE': '1', 17:41:02 INFO - 'NO_EM_RESTART': '1', 17:41:02 INFO - 'PAGER': '/bin/cat', 17:41:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:41:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:02 INFO - 'PWD': '/builds/slave/test', 17:41:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:41:02 INFO - 'SHELL': '/bin/bash', 17:41:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PVfL1NTWpf/Listeners', 17:41:02 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 17:41:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:41:02 INFO - 'USER': 'cltbld', 17:41:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:41:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:41:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:02 INFO - 'XPC_FLAGS': '0x0', 17:41:02 INFO - 'XPC_SERVICE_NAME': '0', 17:41:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:41:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:41:02 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)) 17:41:02 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)) 17:41:02 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)) 17:41:02 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)) 17:41:02 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)) 17:41:02 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)) 17:41:02 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)) 17:41:02 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)) 17:41:02 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)) 17:41:02 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)) 17:41:02 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)) 17:41:02 INFO - Cleaning up... 17:41:02 INFO - Return code: 0 17:41:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:41: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')]} 17:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:41:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41: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')]} 17:41:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:41:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10914da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091544c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf9ccdcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff1d30>, '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 0x108e55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe8b20>, '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.wKm9xxMYqV/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.PVfL1NTWpf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 17:41: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', 'jsonschema'] in /builds/slave/test/build 17:41: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 jsonschema 17:41:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKm9xxMYqV/Render', 17:41:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:41:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:41:02 INFO - 'HOME': '/Users/cltbld', 17:41:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:41:02 INFO - 'LOGNAME': 'cltbld', 17:41:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:02 INFO - 'MOZ_NO_REMOTE': '1', 17:41:02 INFO - 'NO_EM_RESTART': '1', 17:41:02 INFO - 'PAGER': '/bin/cat', 17:41:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:41:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:02 INFO - 'PWD': '/builds/slave/test', 17:41:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:41:02 INFO - 'SHELL': '/bin/bash', 17:41:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PVfL1NTWpf/Listeners', 17:41:02 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 17:41:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:41:02 INFO - 'USER': 'cltbld', 17:41:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:41:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:41:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:02 INFO - 'XPC_FLAGS': '0x0', 17:41:02 INFO - 'XPC_SERVICE_NAME': '0', 17:41:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:41:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:41:02 INFO - Downloading/unpacking jsonschema 17:41: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 17:41: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 17:41: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 17:41: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 17:41: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 17:41: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 17:41:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:41:05 INFO - Downloading/unpacking functools32 (from jsonschema) 17:41: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 17:41: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 17:41: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 17:41: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 17:41: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 17:41: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 17:41:05 INFO - Downloading functools32-3.2.3-2.tar.gz 17:41:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:41:05 INFO - warning: no files found matching '*.txt' 17:41:05 INFO - no previously-included directories found matching 'build' 17:41:05 INFO - no previously-included directories found matching 'dist' 17:41:05 INFO - no previously-included directories found matching '.git*' 17:41:05 INFO - Installing collected packages: jsonschema, functools32 17:41:05 INFO - Running setup.py install for functools32 17:41:05 INFO - warning: no files found matching '*.txt' 17:41:05 INFO - no previously-included directories found matching 'build' 17:41:05 INFO - no previously-included directories found matching 'dist' 17:41:05 INFO - no previously-included directories found matching '.git*' 17:41:05 INFO - Successfully installed jsonschema functools32 17:41:05 INFO - Cleaning up... 17:41:05 INFO - Return code: 0 17:41:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:41: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')]} 17:41:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:41:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41: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')]} 17:41:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:41:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:41: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10914da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091544c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf9ccdcf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ff1d30>, '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 0x108e55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe8b20>, '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.wKm9xxMYqV/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.PVfL1NTWpf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 17:41: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', 'flake8'] in /builds/slave/test/build 17:41: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 flake8 17:41:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKm9xxMYqV/Render', 17:41:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:41:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:41:05 INFO - 'HOME': '/Users/cltbld', 17:41:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:41:05 INFO - 'LOGNAME': 'cltbld', 17:41:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:05 INFO - 'MOZ_NO_REMOTE': '1', 17:41:05 INFO - 'NO_EM_RESTART': '1', 17:41:05 INFO - 'PAGER': '/bin/cat', 17:41:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:41:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:05 INFO - 'PWD': '/builds/slave/test', 17:41:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:41:05 INFO - 'SHELL': '/bin/bash', 17:41:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PVfL1NTWpf/Listeners', 17:41:05 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 17:41:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:41:05 INFO - 'USER': 'cltbld', 17:41:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:41:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:41:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:05 INFO - 'XPC_FLAGS': '0x0', 17:41:05 INFO - 'XPC_SERVICE_NAME': '0', 17:41:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:41:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:41:06 INFO - Downloading/unpacking flake8 17:41:06 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 17:41:06 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 17:41:06 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 17:41:06 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 17:41:06 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 17:41:06 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 17:41:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:41:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:41: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 17:41: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 17:41: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 17:41: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 17:41: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 17:41: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 17:41:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:41:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:41: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 17:41: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 17:41: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 17:41: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 17:41: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 17:41: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 17:41:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:41:09 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 17:41:09 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 17:41:09 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 17:41:09 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 17:41:09 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 17:41:09 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 17:41:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:41:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:41:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:41:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:41:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:41:09 INFO - no previously-included directories found matching 'docs/_build' 17:41:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:41:09 INFO - Running setup.py install for pep8 17:41:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:41:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:41:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:41:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:41:09 INFO - no previously-included directories found matching 'docs/_build' 17:41:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:41:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:41:10 INFO - Cleaning up... 17:41:10 INFO - Return code: 0 17:41:10 INFO - Running post-action listener: _resource_record_post_action 17:41:10 INFO - Running post-action listener: _start_resource_monitoring 17:41:10 INFO - Starting resource monitoring. 17:41:10 INFO - ##### 17:41:10 INFO - ##### Running install step. 17:41:10 INFO - ##### 17:41:10 INFO - Running pre-action listener: _resource_record_pre_action 17:41:10 INFO - Running main action method: install 17:41:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:41:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:41:10 INFO - Reading from file tmpfile_stdout 17:41:10 INFO - Detecting whether we're running mozinstall >=1.0... 17:41:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:41:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:41:10 INFO - Reading from file tmpfile_stdout 17:41:10 INFO - Output received: 17:41:10 INFO - Usage: mozinstall [options] installer 17:41:10 INFO - Options: 17:41:10 INFO - -h, --help show this help message and exit 17:41:10 INFO - -d DEST, --destination=DEST 17:41:10 INFO - Directory to install application into. [default: 17:41:10 INFO - "/builds/slave/test"] 17:41:10 INFO - --app=APP Application being installed. [default: firefox] 17:41:10 INFO - mkdir: /builds/slave/test/build/application 17:41:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:41:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 17:41:32 INFO - Reading from file tmpfile_stdout 17:41:32 INFO - Output received: 17:41:32 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 17:41:32 INFO - Running post-action listener: _resource_record_post_action 17:41:32 INFO - ##### 17:41:32 INFO - ##### Running run-tests step. 17:41:32 INFO - ##### 17:41:32 INFO - Running pre-action listener: _resource_record_pre_action 17:41:32 INFO - Running main action method: run_tests 17:41:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:41:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:41:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:41:32 INFO - 2016-05-06 17:41:32.560 screenresolution[1926:25137] starting screenresolution argv=screenresolution get 17:41:32 INFO - 2016-05-06 17:41:32.563 screenresolution[1926:25137] Display 0: 1600x1200x32@60 17:41:32 INFO - 2016-05-06 17:41:32.569 screenresolution[1927:25139] starting screenresolution argv=screenresolution list 17:41:32 INFO - Available Modes on Display 0 17:41:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:41:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:41:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:41:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:41:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:41:32 INFO - Intel Iris: 17:41:32 INFO - Chipset Model: Intel Iris 17:41:32 INFO - Type: GPU 17:41:32 INFO - Bus: Built-In 17:41:32 INFO - VRAM (Dynamic, Max): 1536 MB 17:41:32 INFO - Vendor: Intel (0x8086) 17:41:32 INFO - Device ID: 0x0a2e 17:41:32 INFO - Revision ID: 0x0009 17:41:32 INFO - Displays: 17:41:32 INFO - Display: 17:41:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:41:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:41:32 INFO - Display Serial Number: 1600x1200 60 17:41:32 INFO - Main Display: Yes 17:41:32 INFO - Mirror: Off 17:41:32 INFO - Online: Yes 17:41:32 INFO - Rotation: Supported 17:41:32 INFO - Return code: 0 17:41:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:41:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:41:32 INFO - Python 2.7.3 17:41:32 INFO - Return code: 0 17:41:32 INFO - grabbing minidump binary from tooltool 17:41: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')]} 17:41:32 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 0x108ff1d30>, '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 0x108e55db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108fe8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:41:32 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 17:41:32 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 17:41:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:41:32 INFO - Return code: 0 17:41:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:41:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:41:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:41:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:41:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:41:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:41:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:41:34 INFO - Return code: 0 17:41:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--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 17:41:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0006 --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 17:41:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wKm9xxMYqV/Render', 17:41:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:41:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:41:34 INFO - 'HOME': '/Users/cltbld', 17:41:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:41:34 INFO - 'LOGNAME': 'cltbld', 17:41:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:41:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:41:34 INFO - 'MOZ_NO_REMOTE': '1', 17:41:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:41:34 INFO - 'NO_EM_RESTART': '1', 17:41:34 INFO - 'PAGER': '/bin/cat', 17:41:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:41:34 INFO - 'PWD': '/builds/slave/test', 17:41:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:41:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:41:34 INFO - 'SHELL': '/bin/bash', 17:41:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PVfL1NTWpf/Listeners', 17:41:34 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 17:41:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:41:34 INFO - 'USER': 'cltbld', 17:41:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:41:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:41:34 INFO - 'XPC_FLAGS': '0x0', 17:41:34 INFO - 'XPC_SERVICE_NAME': '0', 17:41:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:41:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--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 17:41:34 INFO - mozversion application_buildid: 20160506162552 17:41:34 INFO - mozversion application_changeset: 025d85d8686491f7975b9d2e86b97180e3f9e0fa 17:41:34 INFO - mozversion application_display_name: Firefox 17:41:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:41:34 INFO - mozversion application_name: Firefox 17:41:34 INFO - mozversion application_remotingname: firefox 17:41:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:41:34 INFO - mozversion application_vendor: Mozilla 17:41:34 INFO - mozversion application_version: 47.0 17:41:34 INFO - mozversion platform_buildid: 20160506162552 17:41:34 INFO - mozversion platform_changeset: 025d85d8686491f7975b9d2e86b97180e3f9e0fa 17:41:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:41:34 INFO - mozversion platform_version: 47.0 17:41:34 INFO - using testdate: 1462581694 17:41:34 INFO - actual date: 1462581694 17:41:34 INFO - starting webserver on 'localhost:52490' 17:41:34 INFO - SUITE-START | Running 2 tests 17:41:34 INFO - TEST-START | dromaeo_css 17:41:34 INFO - operating with platform_type : mac_ 17:41:34 INFO - Initialising browser for dromaeo_css test... 17:41:34 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmph3NClF/profile http://localhost:52490/getInfo.html) 17:41:36 INFO - PROCESS | 1945 | __metrics Screen width/height:1600/1200 17:41:36 INFO - PROCESS | 1945 | colorDepth:24 17:41:36 INFO - PROCESS | 1945 | Browser inner width/height: 1024/689 17:41:36 INFO - PROCESS | 1945 | __metrics 17:41:36 INFO - PROCESS | 1945 | 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" 17:41:36 INFO - PROCESS | 1945 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:41:37 INFO - TEST-INFO | 1945: exit 0 17:41:37 INFO - Browser initialized. 17:41:37 INFO - Running cycle 1/1 for dromaeo_css test... 17:41:37 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmph3NClF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:41:49 INFO - PROCESS | 1948 | RSS: Main: 156966912 17:41:49 INFO - PROCESS | 1948 | 17:42:39 INFO - PROCESS | 1948 | 1462581759738 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] 17:44:49 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52490/tests/dromaeo/cssquery-ext.html) 17:44:50 INFO - PROCESS | 1948 | RSS: Main: 274280448 17:44:50 INFO - PROCESS | 1948 | 17:47:53 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-ext.html (next: http://localhost:52490/tests/dromaeo/cssquery-jquery.html) 17:47:53 INFO - PROCESS | 1948 | RSS: Main: 292204544 17:47:53 INFO - PROCESS | 1948 | 17:50:55 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52490/tests/dromaeo/cssquery-mootools.html) 17:50:56 INFO - PROCESS | 1948 | RSS: Main: 295124992 17:50:56 INFO - PROCESS | 1948 | 17:53:58 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52490/tests/dromaeo/cssquery-prototype.html) 17:53:58 INFO - PROCESS | 1948 | RSS: Main: 252514304 17:53:58 INFO - PROCESS | 1948 | 17:57:01 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52490/tests/dromaeo/cssquery-yui.html) 17:57:02 INFO - PROCESS | 1948 | RSS: Main: 252817408 17:57:02 INFO - PROCESS | 1948 | 18:00:04 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:52490/tests/dromaeo/cssquery-yui.html (next: http://localhost:52490/tests/dromaeo/cssquery-dojo.html) 18:00:05 INFO - PROCESS | 1948 | RSS: Main: 300699648 18:00:05 INFO - PROCESS | 1948 | 18:00:05 INFO - PROCESS | 1948 | __start_tp_report 18:00:05 INFO - PROCESS | 1948 | _x_x_mozilla_page_load 18:00:05 INFO - PROCESS | 1948 | _x_x_mozilla_page_load_details 18:00:05 INFO - PROCESS | 1948 | |i|pagename|runs| 18:00:05 INFO - PROCESS | 1948 | |0;dojo.html;3188.697073777991;3583.5878873929496;3590.1024743814046;3599.6220396858344;3676.6874815640685;5901.734421951017;5902.173695682603;5902.380250073745;5922.496587790032;5963.880722385555;5390.7870729085935;5419.83740487786;5874.236349274599;5894.764209431628;5912.408759124085;4498.302763071729;5083.110455670243;5274.393444753845;5286.10136276832;5295.629305948585;5336.706481143536;5353.464653534635;5355;5361.651492652965;5365.490278423544;5286.629935904488;5344.4922732340365;5356.464353564651;5360.678359298435;5372.91940620891;5345.465453454663;5390.757415916254;5399.298091248152;5405.648632838853;5421.051316019721;5141.76862041209;5294.338207724035;5320.813771518017;5328.360596728378;5333.653312534711;5713.74288157034;5716.114002329646;5766.192733017381;5766.942330576682;5781.421857814227;6225.564210505267;6328.145700330442;6329.556931014831;6336.96831515844;6350.237971443456;5020.347354843883;6261.937380626181;6305.527085468608;6324.525660575429;6336.3663633636725;5987.221661184061;6003.609765365237;6018.488428483628;6019.187409699722;6030.7889223876955;4694.36626055485;6263.937360626335;6329.493640508748;6335.746570137143;6341.524385671001;6259.6870156492905;6294.968525157345;6307.96846015767;6315.084312774622;6321.336259692758;5263.605229607795;6277.623342599459;6306.432421082125;6308.936910630927;6309.558330916791;6132.049587189092;6208;6300.747970081237;6322.241331040304;6326.46225070865;6278.968605157036;6312.810615681537;6339.11252424661;6354;6358.713857876407;4673.626109911199;4674.065186962621;4674.929876051862;4768.666193366431;5030.574481031846;4441.977790111029;4454.888627784331;4515.887102822456;4540.682152249311;4557.270836666117;4694.389729335165;5904.0553511437965;5947;5987.850303742354;6003.699815009232;6268.561200715997;6279.340669229665;6280.434760871543;6286.025666021774;6305.495560355161;5826.184334193216;5929.762809487658;5935.703214839241;5957.821265362046;6024.969875150684;5509.834704958857;5513.61404701675;5531.419200983839;5535.750891209906;5544.750486228129;6427.196600424947;6486.578372405778;6490.156279683611;6493.545451818327;6537.70580323877;6167.907481387693;6209.875802483834;6244.968775156095;6257.217847769029;6294.5593808434805;6446.806595802133;6472.579282346633;6482.384173503699;6486.254080780771;6489.707963141575;6269.968650156902;6270.937290627217;6291.779787707408;6292.307846137013;6304.275008374096;6173.660448675366;6214.285357183981;6258.624482531062;6258.968705156445;6259.780907668392;6228.34602366745;6232.875342493034;6241.6879156041105;6244.968775156095;6274.058891166362;6109.799438327977;6188.164597779242;6195.783147589993;6229.906551401823;6273.9372606273355;6072.605280656743;6137.101203255209;6165.383461653799;6167.198264225622;6178.721957511832;6220.782272620437;6264.342244064308;6274.121622972879;6275.6862156892885;6277;6052.515798736179;6122.855605810045;6170.6914654268;6182.505399568114;6190.133381326708 18:00:05 INFO - PROCESS | 1948 | |1;ext.html;513.9566679640327;554.7420449490986;562.1960596347221;637.9075034120053;649.5940037476577;32942.68229270829;42752;42857.57142428575;42931.78534107333;42951.99999999996;45906.770466147624;46534;46551;46574;46651;9783.95108024459;10057.396556206651;10059.849102263472;10070.798584028315;10072.446015469146;8780.69267575635;9890.950545247264;10021.649242276522;10050.095491405773;10065.14446272064;10126.139278161365;10210.591576336938;10228.795424091513;10256.538455769509;10276.332038417517;10107.747306317364;10170.389776613427;10171.949140254252;10176.491175441271;10176.542055607515;9902.752431189207;10055.547500362429;10070.597176112946;10078.79842403155;10079.236133676577;44565.77717111426;44782.880427989236;45271.54728452739;45342;45558.54441455576;2896.2614533294086;3442.9483557746653;3575.713942884563;3592.3533763922483;3620.583632882226;28103.29741756452;29164.708352916412;29246.561301580492;29307.8276868925;29322.266943326373;2824.3221626809623;2867.0252114281034;2868.784841136923;2895.6959519250386;2898.7970842041063;557399;560721;561275.1936240334;561390;564988;79611;79856.20143798487;79895.20104798878;79969;80430;79384.80922786162;79530;79637.20362796298;79669.60165199253;79811.20188798154;18800.905995469937;18884.81115188858;19072.90463547701;19090.04549772506;19122.904385477985;14454;14555.636109097357;14585.197814120325;14589.05171163871;14651.413943442356;23400.64899026516;23991.52016959651;24022.67875266842;24040.83632473086;24510.509789804106;9508.714738557715;9675.743192605545;9702.951485242529;9751.829780426395;9759.65841195569;950.4059962523423;986.6990567876867;1015.8831734350497;1109.6670998700358;1130.949107290174;29912.55131173034;30132;30233.848830755705;30512;31443.213919651735;1058.4760543530847;1087.4780105549223;1114.164376717462;1137.6871360375796;1249.5626530714178;15110.471133510275;15377.615559610875;15397.07617542951;15595.986260893005;15764;307565;307812;312305;312464;314102;69395;69459;69601.30398695948;69791.30208697848;69805.65097174582;1492.1047371577183;1534.5319677498471;1548.442560678155;1586.064222108938;1587.8094628644637;31215.37569248557;32277.677223227467;32814.83592582022;33198.83400583078;33249.92413576892;55596.16605750998;55823.883522330136;56319.71840140773;56401;56567;425466;431453;431672;432372;433662;30821.767129314576;35795.284094317445;36369.090772730895;36779.63220367869;36792.63207367999;44963.775181123885;45096.323555147355;45505.77247113743;46211;46504.069918602116;34357.656423435445;35405.11487212737;36683.44974825326;37937.4309385365;38559.6144038556;36960.44559331666;37091;37311.626883730816;38127.80936095302;39517.40723889087;38643;39106.6089339103;39552.40671389874;39834.2033159341;40027.999300017735;28326.716732832407;33165.668343316254;34500.65499345075;34566.481502777984;34873.82563087168;25201.87399062993;25602.487950241262;25649.845756941355;25874.74125258723;26050.478990419706 18:00:05 INFO - PROCESS | 1948 | |2;jquery.html;379.25097931585117;391.6807801641662;392.7231301932138;396.14432825097794;400.7154920006795;17318.04750738712;20282.69575956362;20474.283400081;20949.266775662854;20966.427517936158;12158.027357811376;12582.874171258285;21071.157153713833;21971.450713732163;21987.670184947237;5392.272043274166;5531.446855314477;5596.720163991805;5596.832095037135;5609.439056094378;4186.413902053715;4289.571042895716;4377.080813029268;6111;6160.353162917874;6105.389461053904;6136.202293701813;6145.969270153665;6153.815385538419;6161.3838616138255;5860.677662728548;6121.602095863777;6132.540059495555;6141.263048434194;6148.538859585549;4087.97956010221;5758.683272420015;5887.882242355172;5976.5218782497295;5992.310884248323;22243.666345004836;24268.878655606608;25191.24426267215;25863.482730345288;25921.092761753247;2338.1699496678602;2340.403197184724;2651;2854.3863069439963;2868.885244590214;10303.587856485732;10667.626633067883;12068.818967715491;12323.75352492945;15776.132312722795;1827.625336805965;1844.317602487075;1916.6837471817134;1935.0711658404045;1955.4036019014195;112140;113768.4311578445;116658.41670791677;116976;118636.22045669236;11484.253523520945;11595.478203480863;11671;11677.240979336317;11784.58753943608;14605.415783368546;14761.040532365361;14767.409303627735;14769.92615036918;14807.70384592302;8956.68651597191;8978.685745998859;9030.45817250967;9060.275177985877;9183.127602877716;9020.458772473541;9032.63869445215;9052.185303322733;9118.36171468004;9173.724788256362;4524.457065152203;4618.65360097994;4632.25883858581;4647.6281897448125;4675.392199014174;1778.060621839086;1812.4834422189613;1907.1513176636263;1956.4228552577024;1963.0773536438137;883.779055236191;949.8432758594893;1052.1793001458875;1074.822654262038;1075.198976762317;16778.496645100666;20987.160513579285;21053.684194736787;21182.78817211839;21465;1018.1142406106586;1038.3199004651965;1076.6500887211687;1090.2422816142705;1171.847659804237;10856.348619082723;11305.773884522263;11321.830172547418;11328.433578321052;11330.790495853229;719174;722789.3860530768;739815;744633;749724;124155;124378;128506.28962262293;131251;135246;940.3652534539159;999.8500224966314;1015.9789411641253;1052.079430498314;1059.7456610413444;18458.077096145407;18701.812981870553;18778.464907237347;18802;19004.809951900304;53413.73293133509;55194;55270.72364638151;55794.72102639461;57001.429985699615;80405;80628;81860;82589;84257.57871210604;8073.596320184085;8155.9184408157535;8342.79143021405;8721.563921803758;8961.23829474502;12856.135612071366;13850.16898986064;13957.651058723613;14023.649408764863;14371.281435928371;9740.805183896424;11608.767824643292;12140.696482588006;12149;12167.513299467922;9853.310268281151;11570.479328430072;11812;11990.820137698116;12094.548654161557;11915.344656043653;11980.400979950793;12052.939735301268;12244.632661020183;12396.81404778911;8451.577421129043;8522.232999030117;8678.652853885775;8721.956390218009;8947.328950328621;11679.182457227913;11847.170698051053;11903;12114.273143611412;12180.025597951966 18:00:05 INFO - PROCESS | 1948 | |3;mootools.html;1509.335892207428;1648.7691723158757;1678.4628918746002;2020.888851113189;2105.4946812764933;8430.83138337233;9103.681371152012;9353.15821576058;9360.81278374432;9366.20387267083;8944.195022447979;9232.122948319899;9246.768830779234;9247.21398681113;9247.982721900586;2559.5264875997955;2589.5338839008973;2596.31197732601;2596.44176502052;2600.6489123968213;2518.106072344319;2612.098825905069;2615.6730408698913;2624.7375262473793;2631.394779200785;2591.727868573801;2605.400757825701;2625.6061590761346;2628.9737102629015;2630.289821748127;2575.497777933304;2594.948101037978;2617.9083732069284;2627.395698989224;2631.236941287034;2569.4732579821093;2599.506093842164;2609.178108895711;2610.7258737832635;2617.8429294242405;9262.444253344753;9311.022342653992;9342.205912497446;9347.906520934772;9355.251579873593;5726.856828579319;5881.882362352729;5900.940990590082;5918.171455996164;5922.289325280994;3719.107414220595;3787.20468701573;3828;3853.556840963269;3861.9806900965314;1074.5379486820664;1080.0873262093519;1087.1140020882956;1128.0637071230858;1129.5594718059963;12456.12807103494;12542.81185782214;12568.622941311774;12572.559960401433;12643.051771117203;5022.89954200914;5027.4721154279005;5036.79852805891;5081.2378143278065;5099.64302498829;10678.145748340114;10693.984071513194;10697.41164235959;10711.732206694895;10766.461676916122;5819.243498345188;5819.941800582024;5838.065909454467;5848.795292164754;5854.502367298827;4648.697834640738;4808.158572249842;4988.6757360771435;5144.254083157921;5197.896042079213;4112.6150835421295;4139.379093135994;4187;4209.621134097946;4237.724547904377;1123.5168877382803;1362.8296462942133;1373.5329987803996;1383.7716776731927;1385.0789225165151;1426.2013272567187;1444.4077928049448;1456.0826679192041;1462.9487967921073;1497.0119720984317;8618.396712230084;8618.612162452704;8659.696910608225;8671.132886711279;8671.826563468696;532.0688794609433;537.4893850841715;545.4354742841141;545.939946605873;546.6364867363159;6411.070394792729;6479.092926990225;6482.416582507597;6490;6515.609063456208;11028.724281893017;11401.144914131473;11572.6528204154;11587.942060289814;11623.709407264885;11116.166287528467;11189;11240.662780116609;11309.98210161073;11362.886371136348;8922.86615700765;8943.23982461498;8962.955185224033;8995.775105622542;9024.774380640536;8328.916710832815;8329.167083291622;8403.915960840313;8418.831623367621;8424.368172386972;10096.293259471766;10121.443320617142;10225;10374.792504150026;10401.375917444977;10285.794284114425;10359.63741269052;10625.309354892217;10831.783364332827;10863.619773307897;7110.964445177741;7355.521891077063;7630.618469076413;7896.407769417431;7993.320567751573;8627.43921645091;8697.826043479221;8712.477251364939;8720.956395217983;8749.387542871938;7641.7707468776025;7708.845823083395;7759.30166285037;7826.491278066907;7869.252421019994;6520.967395162994;7427.888581671171;7477.775666730006;7492.887606686013;7614.923850761643;8006.159353267823;8177.345812335118;8201.384896132933;8218.753437396888;8229.506229626242;7365.705371785283;7394.704211831467;7412.592307422928;7429.108506979197;7434.182239953492;7494.662740176596;7517.436192285491;7561.054868141482;7561.9243807563425;7568.243175682608 18:00:05 INFO - PROCESS | 1948 | |4;prototype.html;339.95920489541254;350.4288010542816;352.2637687233682;357.46380429355963;361.9800910949897;6675.132232809734;6684.197896252451;6693.129893113888;6729.528932974694;6739.123913891193;6655.600663960166;6656.13470248867;6662.634159997201;6664.300248473914;6667.499937504695;4724.574788269055;4790.041991601676;4817.614590832725;4937.753112344386;5002.62480313977;4974.8258810941625;4979.004199160165;4981.277714731362;4993.400791904958;5012.27322038306;4448.4884238312525;4603;4923.433805112404;4925.605951523888;4931.901361972758;4725.692829966041;4778.450478195017;4900.828471003498;4902.240152776325;4926.408830940292;3744.2511497700298;3811.9237615247816;3947.388154836005;4002.239574480868;4225.176090662322;6361.9045714314325;6370.948793449075;6382.234131904155;6383.872322553523;6417.486601071903;5792.855178620526;5794.246747922785;5815.563832712563;5826.533877289807;5829.300483941912;7053.0830991970715;7055.329743674394;7065.364117229474;7070.36366726997;7073;4450.198964186445;4622.699524530928;4639.4896561378555;4643.535646435364;4644.976775116136;7321.963390183068;7489.10130784304;7494.625268736596;7499.100107987022;7504.474686771928;4706.482286948433;4707.95292047082;4714.669973101919;4718.127146477913;4725.503822098699;4770.737609431445;4773.451053128865;4783.736894470838;4790.616750659939;4800.759962001886;5645.4072322405555;5645.689487078168;5656.4060773619;5659.528522584075;5667.744951477193;6849.965750171317;7091.751788687371;7102.786916392516;7124.287571242938;7124.750633727898;1633.3548248442037;1644.4491095483147;1661.4101993792272;1666.6749983753293;1682.2345832646117;1000.4397537379091;1046.6859942017363;1051.9158467322595;1066.3228849680354;1080.8811030786608;6511.967440162864;6599.439047681001;6756.324367563205;6782.491313151435;6789.083473730983;7364.77905662831;7386.224446433154;7398.482106252511;7410.147832999274;7419.517731347445;6745.561538499982;6753.797386078426;6761.222459417131;6770;6784.7964561063245;3172.9682703173135;3872.3997780344093;3877.933568268749;3925.705572082048;3928.0769019280447;6291.937080629135;7437.367823734935;7740.380769538423;7769.922300777033;7842.52944823301;6308.716107775161;7628.46600737943;7690.461667683208;7695.538267703956;7702.653380597886;6382.904256436157;6675.933240667531;7772.533647981139;7791.766247012599;7820.882686759589;7098.893516597152;7426.851462970819;7431.702731890664;7435.70257189728;7443.069616297963;7223.927760722326;7267.7819665410125;7285.198628151005;7285.453590980592;7289.708411663474;7741.651625676745;7744.92255077442;7750;7756.495827771177;7774.2614451627005;4694.7887345068875;4743.976280118577;4745.9050818984115;4752.857414277577;4753.809847606057;5817.5345972321265;7042.084529011196;7250.347468727839;7379.3358597726465;7408.555486670817;7092.822679433056;7092.929070709227;7094;7096.042034325506;7124.928750712426;7037.542559733601;7078.256783037707;7091.11361079865;7109.395701365442;7117.074780278531;6959.860802784017;6961.338672826073;6966.79099627012;6974.232834388315;6974.721011159497;6832.795016149524;7077.292270772881;7107.395871350993;7116.715331386687;7132.750353737594;6735.191776986793;6755.121834161528;6897.930820722595;6918.93081069203;6961.547499412522 18:00:05 INFO - PROCESS | 1948 | |5;yui.html;669.7321071571371;740.9110906691197;752.4281546025021;776.8135647444615;825.1542169276489;15753.921230393846;15985.280662370193;15998.920005399972;16013;16018.19909004548;14706.926465367658;14712.632184195401;14737;15493.84506154938;16944.661106777858;1012.2711647613706;1024.160188645311;1036.0209601926183;1036.9889357876086;1038.7091614347994;1031.54611970733;1042.681981995491;1043.7755882485264;1045.6706137566641;1057.122588251753;1091.9945400273027;1099.9770213701254;1100.1638754546523;1100.4662738571785;1101.752105776198;1082.1288862465713;1084.0947808579845;1085.2403317677579;1086.413336798132;1086.6522712731928;821.3962737388015;823.3824631526355;823.501781422237;823.9423240373176;826.499967519654;13052.477900883954;15443.305051272608;15619.375224990987;15690.274069852307;15720.056796592242;11128.833067503994;11890.346030968294;13459.86540134596;13486.258255795927;13637.181769093886;10397.116245119174;10433.061024507755;10444.529996150191;10789.136869050535;10963.56145754169;3627.9637203628154;3630.8729194478065;3669.522962014921;3766.886993390175;3908.5895980921878;67714.66142669304;84540;85481.14518854795;92881.99999999933;94460;10055.949720251352;10162.796744065077;10330.741731456623;10456.52945617449;10471.267011309135;23744.7625523746;23806.92868820907;23836.284911452683;23843;23852.04591816308;7969.641366138538;7974.840503189904;8015.118336982927;8111.026676798704;8216.507009579444;4244.554321796229;4321.351797230441;4385.188740083031;4560.247559152769;4641.535846415399;3705.184859330997;3745.6254374562873;3745.85016599333;3748.850045998184;3753.6434038766274;709.4147328454046;712.5297303779585;712.5404114346321;715.2132654080469;723.6273319240595;605.6487237402296;620.736187120472;624.1355722324522;624.6470744029609;633.9904901426482;7239.348458638748;8475.449095808754;8519.872201916853;8553.187447192508;8558.871616925751;494.05940594059405;598.0222336479886;615.5906322295713;622.3341025103097;624.7625902157242;3861.710371722132;3919.8824035278985;4024.4164596133396;4034.616711412411;4123.619346011216;99902;100178;101720;101803.49098254461;102363.4881825601;82977.17022829695;83210;83525.58237208775;83892.16107838966;84109;75938.86091708629;76573.23426765772;77249.22750772533;77702;77952.05119871935;7956.2441568050945;8072.313853322534;8089.797755056171;8193.385496087698;8365.121662225378;50233;51779.741101294254;52405.47594524158;52808;53343.199852001475;70373.29626703667;71737.28262717306;71969.28030719626;72208.27791722016;72642.63678681572;11512.59705910289;11636.06911447099;11665.766684666429;11801;11925.4037298133;12041.697336224603;12282.81575776382;12358.258504489759;12377.319247441117;12931.482740690642;11839.526418943145;11859.762804743685;11871.821922670993;11887.82168267494;11973.880261197277;11871.465784039567;12074.637760867188;12076.637700868989;12138.089643276613;12189.268643881396;11484.138689598147;11734.765304694029;11748;11917.16579839403;12121.81817272724;9916.256280779115;10115.039071288216;10220.744481388025;10262.743431413975;10368.896311037095;10025.649102281384;10230.181585473294;10235.590576377159;10240.078392944373;10286.639967601099 18:00:05 INFO - PROCESS | 1948 | __end_tp_report 18:00:05 INFO - PROCESS | 1948 | __start_cc_report 18:00:05 INFO - PROCESS | 1948 | _x_x_mozilla_cycle_collect,972 18:00:05 INFO - PROCESS | 1948 | __end_cc_report 18:00:05 INFO - PROCESS | 1948 | __startTimestamp1462582805057__endTimestamp 18:00:05 INFO - PROCESS | 1948 | 18:00:05 INFO - PROCESS | 1948 | ------- Summary: start ------- 18:00:05 INFO - PROCESS | 1948 | Number of tests: 6 18:00:05 INFO - PROCESS | 1948 | 18:00:05 INFO - PROCESS | 1948 | [#0] dojo.html Cycles:165 Average:5822.27 Median:6132.05 stddev:666.01 (10.9%) stddev-sans-first:635.20 18:00:05 INFO - PROCESS | 1948 | Values: 3188.7 3583.6 3590.1 3599.6 3676.7 5901.7 5902.2 5902.4 5922.5 5963.9 5390.8 5419.8 5874.2 5894.8 5912.4 4498.3 5083.1 5274.4 5286.1 5295.6 5336.7 5353.5 5355.0 5361.7 5365.5 5286.6 5344.5 5356.5 5360.7 5372.9 5345.5 5390.8 5399.3 5405.6 5421.1 5141.8 5294.3 5320.8 5328.4 5333.7 5713.7 5716.1 5766.2 5766.9 5781.4 6225.6 6328.1 6329.6 6337.0 6350.2 5020.3 6261.9 6305.5 6324.5 6336.4 5987.2 6003.6 6018.5 6019.2 6030.8 4694.4 6263.9 6329.5 6335.7 6341.5 6259.7 6295.0 6308.0 6315.1 6321.3 5263.6 6277.6 6306.4 6308.9 6309.6 6132.0 6208.0 6300.7 6322.2 6326.5 6279.0 6312.8 6339.1 6354.0 6358.7 4673.6 4674.1 4674.9 4768.7 5030.6 4442.0 4454.9 4515.9 4540.7 4557.3 4694.4 5904.1 5947.0 5987.9 6003.7 6268.6 6279.3 6280.4 6286.0 6305.5 5826.2 5929.8 5935.7 5957.8 6025.0 5509.8 5513.6 5531.4 5535.8 5544.8 6427.2 6486.6 6490.2 6493.5 6537.7 6167.9 6209.9 6245.0 6257.2 6294.6 6446.8 6472.6 6482.4 6486.3 6489.7 6270.0 6270.9 6291.8 6292.3 6304.3 6173.7 6214.3 6258.6 6259.0 6259.8 6228.3 6232.9 6241.7 6245.0 6274.1 6109.8 6188.2 6195.8 6229.9 6273.9 6072.6 6137.1 6165.4 6167.2 6178.7 6220.8 6264.3 6274.1 6275.7 6277.0 6052.5 6122.9 6170.7 6182.5 6190.1 18:00:05 INFO - PROCESS | 1948 | 18:00:05 INFO - PROCESS | 1948 | [#1] ext.html Cycles:180 Average:61381.26 Median:18971.80 stddev:118471.90 (624.5%) stddev-sans-first:118716.10 18:00:05 INFO - PROCESS | 1948 | Values: 514.0 554.7 562.2 637.9 649.6 32942.7 42752.0 42857.6 42931.8 42952.0 45906.8 46534.0 46551.0 46574.0 46651.0 9784.0 10057.4 10059.8 10070.8 10072.4 8780.7 9891.0 10021.6 10050.1 10065.1 10126.1 10210.6 10228.8 10256.5 10276.3 10107.7 10170.4 10171.9 10176.5 10176.5 9902.8 10055.5 10070.6 10078.8 10079.2 44565.8 44782.9 45271.5 45342.0 45558.5 2896.3 3442.9 3575.7 3592.4 3620.6 28103.3 29164.7 29246.6 29307.8 29322.3 2824.3 2867.0 2868.8 2895.7 2898.8 557399.0 560721.0 561275.2 561390.0 564988.0 79611.0 79856.2 79895.2 79969.0 80430.0 79384.8 79530.0 79637.2 79669.6 79811.2 18800.9 18884.8 19072.9 19090.0 19122.9 14454.0 14555.6 14585.2 14589.1 14651.4 23400.6 23991.5 24022.7 24040.8 24510.5 9508.7 9675.7 9703.0 9751.8 9759.7 950.4 986.7 1015.9 1109.7 1130.9 29912.6 30132.0 30233.8 30512.0 31443.2 1058.5 1087.5 1114.2 1137.7 1249.6 15110.5 15377.6 15397.1 15596.0 15764.0 307565.0 307812.0 312305.0 312464.0 314102.0 69395.0 69459.0 69601.3 69791.3 69805.7 1492.1 1534.5 1548.4 1586.1 1587.8 31215.4 32277.7 32814.8 33198.8 33249.9 55596.2 55823.9 56319.7 56401.0 56567.0 425466.0 431453.0 431672.0 432372.0 433662.0 30821.8 35795.3 36369.1 36779.6 36792.6 44963.8 45096.3 45505.8 46211.0 46504.1 34357.7 35405.1 36683.4 37937.4 38559.6 36960.4 37091.0 37311.6 38127.8 39517.4 38643.0 39106.6 39552.4 39834.2 40028.0 28326.7 33165.7 34500.7 34566.5 34873.8 25201.9 25602.5 25649.8 25874.7 26050.5 18:00:05 INFO - PROCESS | 1948 | 18:00:05 INFO - PROCESS | 1948 | [#2] jquery.html Cycles:180 Average:39093.26 Median:21323.89 stddev:121609.73 (570.3%) stddev-sans-first:121916.13 18:00:05 INFO - PROCESS | 1948 | Values: 379.3 391.7 392.7 396.1 400.7 17318.0 20282.7 20474.3 20949.3 20966.4 12158.0 12582.9 21071.2 21971.5 21987.7 5392.3 5531.4 5596.7 5596.8 5609.4 4186.4 4289.6 4377.1 6111.0 6160.4 6105.4 6136.2 6146.0 6153.8 6161.4 5860.7 6121.6 6132.5 6141.3 6148.5 4088.0 5758.7 5887.9 5976.5 5992.3 22243.7 24268.9 25191.2 25863.5 25921.1 2338.2 2340.4 2651.0 2854.4 2868.9 10303.6 10667.6 12068.8 12323.8 15776.1 1827.6 1844.3 1916.7 1935.1 1955.4 112140.0 113768.4 116658.4 116976.0 118636.2 11484.3 11595.5 11671.0 11677.2 11784.6 14605.4 14761.0 14767.4 14769.9 14807.7 8956.7 8978.7 9030.5 9060.3 9183.1 9020.5 9032.6 9052.2 9118.4 9173.7 4524.5 4618.7 4632.3 4647.6 4675.4 1778.1 1812.5 1907.2 1956.4 1963.1 883.8 949.8 1052.2 1074.8 1075.2 16778.5 20987.2 21053.7 21182.8 21465.0 1018.1 1038.3 1076.7 1090.2 1171.8 10856.3 11305.8 11321.8 11328.4 11330.8 719174.0 722789.4 739815.0 744633.0 749724.0 124155.0 124378.0 128506.3 131251.0 135246.0 940.4 999.9 1016.0 1052.1 1059.7 18458.1 18701.8 18778.5 18802.0 19004.8 53413.7 55194.0 55270.7 55794.7 57001.4 80405.0 80628.0 81860.0 82589.0 84257.6 8073.6 8155.9 8342.8 8721.6 8961.2 12856.1 13850.2 13957.7 14023.6 14371.3 9740.8 11608.8 12140.7 12149.0 12167.5 9853.3 11570.5 11812.0 11990.8 12094.5 11915.3 11980.4 12052.9 12244.6 12396.8 8451.6 8522.2 8678.7 8722.0 8947.3 11679.2 11847.2 11903.0 12114.3 12180.0 18:00:05 INFO - PROCESS | 1948 | 18:00:05 INFO - PROCESS | 1948 | [#3] mootools.html Cycles:180 Average:6397.61 Median:5059.02 stddev:3403.06 (67.3%) stddev-sans-first:3392.77 18:00:05 INFO - PROCESS | 1948 | Values: 1509.3 1648.8 1678.5 2020.9 2105.5 8430.8 9103.7 9353.2 9360.8 9366.2 8944.2 9232.1 9246.8 9247.2 9248.0 2559.5 2589.5 2596.3 2596.4 2600.6 2518.1 2612.1 2615.7 2624.7 2631.4 2591.7 2605.4 2625.6 2629.0 2630.3 2575.5 2594.9 2617.9 2627.4 2631.2 2569.5 2599.5 2609.2 2610.7 2617.8 9262.4 9311.0 9342.2 9347.9 9355.3 5726.9 5881.9 5900.9 5918.2 5922.3 3719.1 3787.2 3828.0 3853.6 3862.0 1074.5 1080.1 1087.1 1128.1 1129.6 12456.1 12542.8 12568.6 12572.6 12643.1 5022.9 5027.5 5036.8 5081.2 5099.6 10678.1 10694.0 10697.4 10711.7 10766.5 5819.2 5819.9 5838.1 5848.8 5854.5 4648.7 4808.2 4988.7 5144.3 5197.9 4112.6 4139.4 4187.0 4209.6 4237.7 1123.5 1362.8 1373.5 1383.8 1385.1 1426.2 1444.4 1456.1 1462.9 1497.0 8618.4 8618.6 8659.7 8671.1 8671.8 532.1 537.5 545.4 545.9 546.6 6411.1 6479.1 6482.4 6490.0 6515.6 11028.7 11401.1 11572.7 11587.9 11623.7 11116.2 11189.0 11240.7 11310.0 11362.9 8922.9 8943.2 8963.0 8995.8 9024.8 8328.9 8329.2 8403.9 8418.8 8424.4 10096.3 10121.4 10225.0 10374.8 10401.4 10285.8 10359.6 10625.3 10831.8 10863.6 7111.0 7355.5 7630.6 7896.4 7993.3 8627.4 8697.8 8712.5 8721.0 8749.4 7641.8 7708.8 7759.3 7826.5 7869.3 6521.0 7427.9 7477.8 7492.9 7614.9 8006.2 8177.3 8201.4 8218.8 8229.5 7365.7 7394.7 7412.6 7429.1 7434.2 7494.7 7517.4 7561.1 7561.9 7568.2 18:00:05 INFO - PROCESS | 1948 | 18:00:05 INFO - PROCESS | 1948 | [#4] prototype.html Cycles:180 Average:5812.28 Median:6671.32 stddev:1858.88 (27.9%) stddev-sans-first:1818.15 18:00:05 INFO - PROCESS | 1948 | Values: 340.0 350.4 352.3 357.5 362.0 6675.1 6684.2 6693.1 6729.5 6739.1 6655.6 6656.1 6662.6 6664.3 6667.5 4724.6 4790.0 4817.6 4937.8 5002.6 4974.8 4979.0 4981.3 4993.4 5012.3 4448.5 4603.0 4923.4 4925.6 4931.9 4725.7 4778.5 4900.8 4902.2 4926.4 3744.3 3811.9 3947.4 4002.2 4225.2 6361.9 6370.9 6382.2 6383.9 6417.5 5792.9 5794.2 5815.6 5826.5 5829.3 7053.1 7055.3 7065.4 7070.4 7073.0 4450.2 4622.7 4639.5 4643.5 4645.0 7322.0 7489.1 7494.6 7499.1 7504.5 4706.5 4708.0 4714.7 4718.1 4725.5 4770.7 4773.5 4783.7 4790.6 4800.8 5645.4 5645.7 5656.4 5659.5 5667.7 6850.0 7091.8 7102.8 7124.3 7124.8 1633.4 1644.4 1661.4 1666.7 1682.2 1000.4 1046.7 1051.9 1066.3 1080.9 6512.0 6599.4 6756.3 6782.5 6789.1 7364.8 7386.2 7398.5 7410.1 7419.5 6745.6 6753.8 6761.2 6770.0 6784.8 3173.0 3872.4 3877.9 3925.7 3928.1 6291.9 7437.4 7740.4 7769.9 7842.5 6308.7 7628.5 7690.5 7695.5 7702.7 6382.9 6675.9 7772.5 7791.8 7820.9 7098.9 7426.9 7431.7 7435.7 7443.1 7223.9 7267.8 7285.2 7285.5 7289.7 7741.7 7744.9 7750.0 7756.5 7774.3 4694.8 4744.0 4745.9 4752.9 4753.8 5817.5 7042.1 7250.3 7379.3 7408.6 7092.8 7092.9 7094.0 7096.0 7124.9 7037.5 7078.3 7091.1 7109.4 7117.1 6959.9 6961.3 6966.8 6974.2 6974.7 6832.8 7077.3 7107.4 7116.7 7132.8 6735.2 6755.1 6897.9 6918.9 6961.5 18:00:05 INFO - PROCESS | 1948 | 18:00:05 INFO - PROCESS | 1948 | [#5] yui.html Cycles:180 Average:19525.09 Median:23839.64 stddev:27710.78 (116.2%) stddev-sans-first:27752.35 18:00:05 INFO - PROCESS | 1948 | Values: 669.7 740.9 752.4 776.8 825.2 15753.9 15985.3 15998.9 16013.0 16018.2 14706.9 14712.6 14737.0 15493.8 16944.7 1012.3 1024.2 1036.0 1037.0 1038.7 1031.5 1042.7 1043.8 1045.7 1057.1 1092.0 1100.0 1100.2 1100.5 1101.8 1082.1 1084.1 1085.2 1086.4 1086.7 821.4 823.4 823.5 823.9 826.5 13052.5 15443.3 15619.4 15690.3 15720.1 11128.8 11890.3 13459.9 13486.3 13637.2 10397.1 10433.1 10444.5 10789.1 10963.6 3628.0 3630.9 3669.5 3766.9 3908.6 67714.7 84540.0 85481.1 92882.0 94460.0 10055.9 10162.8 10330.7 10456.5 10471.3 23744.8 23806.9 23836.3 23843.0 23852.0 7969.6 7974.8 8015.1 8111.0 8216.5 4244.6 4321.4 4385.2 4560.2 4641.5 3705.2 3745.6 3745.9 3748.9 3753.6 709.4 712.5 712.5 715.2 723.6 605.6 620.7 624.1 624.6 634.0 7239.3 8475.4 8519.9 8553.2 8558.9 494.1 598.0 615.6 622.3 624.8 3861.7 3919.9 4024.4 4034.6 4123.6 99902.0 100178.0 101720.0 101803.5 102363.5 82977.2 83210.0 83525.6 83892.2 84109.0 75938.9 76573.2 77249.2 77702.0 77952.1 7956.2 8072.3 8089.8 8193.4 8365.1 50233.0 51779.7 52405.5 52808.0 53343.2 70373.3 71737.3 71969.3 72208.3 72642.6 11512.6 11636.1 11665.8 11801.0 11925.4 12041.7 12282.8 12358.3 12377.3 12931.5 11839.5 11859.8 11871.8 11887.8 11973.9 11871.5 12074.6 12076.6 12138.1 12189.3 11484.1 11734.8 11748.0 11917.2 12121.8 9916.3 10115.0 10220.7 10262.7 10368.9 10025.6 10230.2 10235.6 10240.1 10286.6 18:00:05 INFO - PROCESS | 1948 | -------- Summary: end -------- 18:00:05 INFO - PROCESS | 1948 | 18:00:05 INFO - TEST-INFO | 1948: exit 0 18:00:05 INFO - TEST-OK | dromaeo_css | took 1111267ms 18:00:05 INFO - TEST-START | kraken 18:00:05 INFO - operating with platform_type : mac_ 18:00:05 INFO - Initialising browser for kraken test... 18:00:05 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpKPkfKS/profile http://localhost:52490/getInfo.html) 18:00:07 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 18:00:07 INFO - PROCESS | 1953 | colorDepth:24 18:00:07 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 18:00:07 INFO - PROCESS | 1953 | __metrics 18:00:07 INFO - PROCESS | 1953 | 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" 18:00:07 INFO - PROCESS | 1953 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:00:08 INFO - TEST-INFO | 1953: exit 0 18:00:08 INFO - Browser initialized. 18:00:08 INFO - Running cycle 1/1 for kraken test... 18:00:08 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpKPkfKS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:00:20 INFO - PROCESS | 1956 | RSS: Main: 156864512 18:00:20 INFO - PROCESS | 1956 | 18:00:22 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52490/tests/kraken/driver.html?testName=audio-beat-detection) 18:00:23 INFO - PROCESS | 1956 | RSS: Main: 212926464 18:00:23 INFO - PROCESS | 1956 | 18:00:25 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52490/tests/kraken/driver.html?testName=audio-dft) 18:00:26 INFO - PROCESS | 1956 | RSS: Main: 223375360 18:00:26 INFO - PROCESS | 1956 | 18:00:29 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52490/tests/kraken/driver.html?testName=audio-fft) 18:00:30 INFO - PROCESS | 1956 | RSS: Main: 213127168 18:00:30 INFO - PROCESS | 1956 | 18:00:32 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52490/tests/kraken/driver.html?testName=audio-oscillator) 18:00:32 INFO - PROCESS | 1956 | RSS: Main: 213790720 18:00:32 INFO - PROCESS | 1956 | 18:00:34 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52490/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:00:35 INFO - PROCESS | 1956 | RSS: Main: 214855680 18:00:35 INFO - PROCESS | 1956 | 18:00:38 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52490/tests/kraken/driver.html?testName=imaging-darkroom) 18:00:38 INFO - PROCESS | 1956 | RSS: Main: 215744512 18:00:38 INFO - PROCESS | 1956 | 18:00:41 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52490/tests/kraken/driver.html?testName=imaging-desaturate) 18:00:42 INFO - PROCESS | 1956 | RSS: Main: 215060480 18:00:42 INFO - PROCESS | 1956 | 18:00:44 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52490/tests/kraken/driver.html?testName=json-parse-financial) 18:00:45 INFO - PROCESS | 1956 | RSS: Main: 215318528 18:00:45 INFO - PROCESS | 1956 | 18:00:46 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52490/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:00:47 INFO - PROCESS | 1956 | RSS: Main: 249065472 18:00:47 INFO - PROCESS | 1956 | 18:00:49 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:00:49 INFO - PROCESS | 1956 | RSS: Main: 249896960 18:00:49 INFO - PROCESS | 1956 | 18:00:51 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:00:52 INFO - PROCESS | 1956 | RSS: Main: 255041536 18:00:52 INFO - PROCESS | 1956 | 18:00:54 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:00:55 INFO - PROCESS | 1956 | RSS: Main: 256405504 18:00:55 INFO - PROCESS | 1956 | 18:00:58 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:00:58 INFO - PROCESS | 1956 | RSS: Main: 256536576 18:00:58 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52490/tests/kraken/driver.html?testName=ai-astar) 18:01:00 INFO - PROCESS | 1956 | RSS: Main: 318742528 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | __start_tp_report 18:01:00 INFO - PROCESS | 1956 | _x_x_mozilla_page_load 18:01:00 INFO - PROCESS | 1956 | _x_x_mozilla_page_load_details 18:01:00 INFO - PROCESS | 1956 | |i|pagename|runs| 18:01:00 INFO - PROCESS | 1956 | |0;ai-astar;112;107;112;110;113;112;120;111;110;111 18:01:00 INFO - PROCESS | 1956 | |1;audio-beat-detection;156;118;156;148;117;142;118;150;149;119 18:01:00 INFO - PROCESS | 1956 | |2;audio-dft;184;186;185;186;187;187;183;186;183;184 18:01:00 INFO - PROCESS | 1956 | |3;audio-fft;74;75;75;114;55;57;60;56;118;54 18:01:00 INFO - PROCESS | 1956 | |4;audio-oscillator;92;94;128;80;77;84;115;77;80;117 18:01:00 INFO - PROCESS | 1956 | |5;imaging-gaussian-blur;86;129;87;83;86;82;86;89;87;84 18:01:00 INFO - PROCESS | 1956 | |6;imaging-darkroom;92;92;92;92;92;92;92;92;91;91 18:01:00 INFO - PROCESS | 1956 | |7;imaging-desaturate;83;82;84;84;87;85;83;85;86;89 18:01:00 INFO - PROCESS | 1956 | |8;json-parse-financial;54;57;57;56;58;57;57;65;51;48 18:01:00 INFO - PROCESS | 1956 | |9;json-stringify-tinderbox;63;62;69;66;74;71;66;65;66;66 18:01:00 INFO - PROCESS | 1956 | |10;stanford-crypto-aes;80;80;81;80;79;80;78;80;82;83 18:01:00 INFO - PROCESS | 1956 | |11;stanford-crypto-ccm;119;121;119;118;117;129;125;124;139;122 18:01:00 INFO - PROCESS | 1956 | |12;stanford-crypto-pbkdf2;157;135;135;133;134;124;135;129;132;131 18:01:00 INFO - PROCESS | 1956 | |13;stanford-crypto-sha256-iterative;65;66;79;67;65;66;66;65;66;65 18:01:00 INFO - PROCESS | 1956 | __end_tp_report 18:01:00 INFO - PROCESS | 1956 | __start_cc_report 18:01:00 INFO - PROCESS | 1956 | _x_x_mozilla_cycle_collect,1199 18:01:00 INFO - PROCESS | 1956 | __end_cc_report 18:01:00 INFO - PROCESS | 1956 | __startTimestamp1462582860603__endTimestamp 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | ------- Summary: start ------- 18:01:00 INFO - PROCESS | 1956 | Number of tests: 14 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:3.33 (3.0%) stddev-sans-first:3.53 18:01:00 INFO - PROCESS | 1956 | Values: 112.0 107.0 112.0 110.0 113.0 112.0 120.0 111.0 110.0 111.0 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | [#1] audio-beat-detection Cycles:10 Average:137.30 Median:148.50 stddev:17.08 (11.5%) stddev-sans-first:16.72 18:01:00 INFO - PROCESS | 1956 | Values: 156.0 118.0 156.0 148.0 117.0 142.0 118.0 150.0 149.0 119.0 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | [#2] audio-dft Cycles:10 Average:185.10 Median:186.00 stddev:1.52 (0.8%) stddev-sans-first:1.56 18:01:00 INFO - PROCESS | 1956 | Values: 184.0 186.0 185.0 186.0 187.0 187.0 183.0 186.0 183.0 184.0 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | [#3] audio-fft Cycles:10 Average:73.80 Median:58.50 stddev:23.82 (40.7%) stddev-sans-first:25.27 18:01:00 INFO - PROCESS | 1956 | Values: 74.0 75.0 75.0 114.0 55.0 57.0 60.0 56.0 118.0 54.0 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:80.00 stddev:18.85 (23.6%) stddev-sans-first:19.97 18:01:00 INFO - PROCESS | 1956 | Values: 92.0 94.0 128.0 80.0 77.0 84.0 115.0 77.0 80.0 117.0 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:86.00 stddev:13.89 (16.2%) stddev-sans-first:14.66 18:01:00 INFO - PROCESS | 1956 | Values: 86.0 129.0 87.0 83.0 86.0 82.0 86.0 89.0 87.0 84.0 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | [#6] imaging-darkroom Cycles:10 Average:91.80 Median:92.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 18:01:00 INFO - PROCESS | 1956 | Values: 92.0 92.0 92.0 92.0 92.0 92.0 92.0 92.0 91.0 91.0 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:2.10 (2.5%) stddev-sans-first:2.12 18:01:00 INFO - PROCESS | 1956 | Values: 83.0 82.0 84.0 84.0 87.0 85.0 83.0 85.0 86.0 89.0 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:57.00 stddev:4.50 (7.9%) stddev-sans-first:4.71 18:01:00 INFO - PROCESS | 1956 | Values: 54.0 57.0 57.0 56.0 58.0 57.0 57.0 65.0 51.0 48.0 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:66.00 stddev:3.61 (5.5%) stddev-sans-first:3.56 18:01:00 INFO - PROCESS | 1956 | Values: 63.0 62.0 69.0 66.0 74.0 71.0 66.0 65.0 66.0 66.0 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:80.00 stddev:1.42 (1.8%) stddev-sans-first:1.50 18:01:00 INFO - PROCESS | 1956 | Values: 80.0 80.0 81.0 80.0 79.0 80.0 78.0 80.0 82.0 83.0 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | [#11] stanford-crypto-ccm Cycles:10 Average:123.30 Median:123.00 stddev:6.62 (5.4%) stddev-sans-first:6.83 18:01:00 INFO - PROCESS | 1956 | Values: 119.0 121.0 119.0 118.0 117.0 129.0 125.0 124.0 139.0 122.0 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.50 Median:134.50 stddev:8.62 (6.4%) stddev-sans-first:3.64 18:01:00 INFO - PROCESS | 1956 | Values: 157.0 135.0 135.0 133.0 134.0 124.0 135.0 129.0 132.0 131.0 18:01:00 INFO - PROCESS | 1956 | 18:01:00 INFO - PROCESS | 1956 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:66.00 stddev:4.27 (6.5%) stddev-sans-first:4.47 18:01:00 INFO - PROCESS | 1956 | Values: 65.0 66.0 79.0 67.0 65.0 66.0 66.0 65.0 66.0 65.0 18:01:00 INFO - PROCESS | 1956 | -------- Summary: end -------- 18:01:00 INFO - PROCESS | 1956 | 18:01:01 INFO - TEST-INFO | 1956: exit 0 18:01:01 INFO - TEST-OK | kraken | took 55857ms 18:01:01 INFO - SUITE-END | took 1167s 18:01:01 INFO - Completed test suite (00:19:27) 18:01:01 INFO - javascript benchmark 18:01:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3188.697073777991, 3583.5878873929496, 3590.1024743814046, 3599.6220396858344, 3676.6874815640685, 5901.734421951017, 5902.173695682603, 5902.380250073745, 5922.496587790032, 5963.880722385555, 5390.7870729085935, 5419.83740487786, 5874.236349274599, 5894.764209431628, 5912.408759124085, 4498.302763071729, 5083.110455670243, 5274.393444753845, 5286.10136276832, 5295.629305948585, 5336.706481143536, 5353.464653534635, 5355.0, 5361.651492652965, 5365.490278423544, 5286.629935904488, 5344.4922732340365, 5356.464353564651, 5360.678359298435, 5372.91940620891, 5345.465453454663, 5390.757415916254, 5399.298091248152, 5405.648632838853, 5421.051316019721, 5141.76862041209, 5294.338207724035, 5320.813771518017, 5328.360596728378, 5333.653312534711, 5713.74288157034, 5716.114002329646, 5766.192733017381, 5766.942330576682, 5781.421857814227, 6225.564210505267, 6328.145700330442, 6329.556931014831, 6336.96831515844, 6350.237971443456, 5020.347354843883, 6261.937380626181, 6305.527085468608, 6324.525660575429, 6336.3663633636725, 5987.221661184061, 6003.609765365237, 6018.488428483628, 6019.187409699722, 6030.7889223876955, 4694.36626055485, 6263.937360626335, 6329.493640508748, 6335.746570137143, 6341.524385671001, 6259.6870156492905, 6294.968525157345, 6307.96846015767, 6315.084312774622, 6321.336259692758, 5263.605229607795, 6277.623342599459, 6306.432421082125, 6308.936910630927, 6309.558330916791, 6132.049587189092, 6208.0, 6300.747970081237, 6322.241331040304, 6326.46225070865, 6278.968605157036, 6312.810615681537, 6339.11252424661, 6354.0, 6358.713857876407, 4673.626109911199, 4674.065186962621, 4674.929876051862, 4768.666193366431, 5030.574481031846, 4441.977790111029, 4454.888627784331, 4515.887102822456, 4540.682152249311, 4557.270836666117, 4694.389729335165, 5904.0553511437965, 5947.0, 5987.850303742354, 6003.699815009232, 6268.561200715997, 6279.340669229665, 6280.434760871543, 6286.025666021774, 6305.495560355161, 5826.184334193216, 5929.762809487658, 5935.703214839241, 5957.821265362046, 6024.969875150684, 5509.834704958857, 5513.61404701675, 5531.419200983839, 5535.750891209906, 5544.750486228129, 6427.196600424947, 6486.578372405778, 6490.156279683611, 6493.545451818327, 6537.70580323877, 6167.907481387693, 6209.875802483834, 6244.968775156095, 6257.217847769029, 6294.5593808434805, 6446.806595802133, 6472.579282346633, 6482.384173503699, 6486.254080780771, 6489.707963141575, 6269.968650156902, 6270.937290627217, 6291.779787707408, 6292.307846137013, 6304.275008374096, 6173.660448675366, 6214.285357183981, 6258.624482531062, 6258.968705156445, 6259.780907668392, 6228.34602366745, 6232.875342493034, 6241.6879156041105, 6244.968775156095, 6274.058891166362, 6109.799438327977, 6188.164597779242, 6195.783147589993, 6229.906551401823, 6273.9372606273355, 6072.605280656743, 6137.101203255209, 6165.383461653799, 6167.198264225622, 6178.721957511832, 6220.782272620437, 6264.342244064308, 6274.121622972879, 6275.6862156892885, 6277.0, 6052.515798736179, 6122.855605810045, 6170.6914654268, 6182.505399568114, 6190.133381326708], "unit": "score", "name": "dojo.html", "value": 5781.862891298445}, {"lowerIsBetter": false, "replicates": [513.9566679640327, 554.7420449490986, 562.1960596347221, 637.9075034120053, 649.5940037476577, 32942.68229270829, 42752.0, 42857.57142428575, 42931.78534107333, 42951.99999999996, 45906.770466147624, 46534.0, 46551.0, 46574.0, 46651.0, 9783.95108024459, 10057.396556206651, 10059.849102263472, 10070.798584028315, 10072.446015469146, 8780.69267575635, 9890.950545247264, 10021.649242276522, 10050.095491405773, 10065.14446272064, 10126.139278161365, 10210.591576336938, 10228.795424091513, 10256.538455769509, 10276.332038417517, 10107.747306317364, 10170.389776613427, 10171.949140254252, 10176.491175441271, 10176.542055607515, 9902.752431189207, 10055.547500362429, 10070.597176112946, 10078.79842403155, 10079.236133676577, 44565.77717111426, 44782.880427989236, 45271.54728452739, 45342.0, 45558.54441455576, 2896.2614533294086, 3442.9483557746653, 3575.713942884563, 3592.3533763922483, 3620.583632882226, 28103.29741756452, 29164.708352916412, 29246.561301580492, 29307.8276868925, 29322.266943326373, 2824.3221626809623, 2867.0252114281034, 2868.784841136923, 2895.6959519250386, 2898.7970842041063, 557399.0, 560721.0, 561275.1936240334, 561390.0, 564988.0, 79611.0, 79856.20143798487, 79895.20104798878, 79969.0, 80430.0, 79384.80922786162, 79530.0, 79637.20362796298, 79669.60165199253, 79811.20188798154, 18800.905995469937, 18884.81115188858, 19072.90463547701, 19090.04549772506, 19122.904385477985, 14454.0, 14555.636109097357, 14585.197814120325, 14589.05171163871, 14651.413943442356, 23400.64899026516, 23991.52016959651, 24022.67875266842, 24040.83632473086, 24510.509789804106, 9508.714738557715, 9675.743192605545, 9702.951485242529, 9751.829780426395, 9759.65841195569, 950.4059962523423, 986.6990567876867, 1015.8831734350497, 1109.6670998700358, 1130.949107290174, 29912.55131173034, 30132.0, 30233.848830755705, 30512.0, 31443.213919651735, 1058.4760543530847, 1087.4780105549223, 1114.164376717462, 1137.6871360375796, 1249.5626530714178, 15110.471133510275, 15377.615559610875, 15397.07617542951, 15595.986260893005, 15764.0, 307565.0, 307812.0, 312305.0, 312464.0, 314102.0, 69395.0, 69459.0, 69601.30398695948, 69791.30208697848, 69805.65097174582, 1492.1047371577183, 1534.5319677498471, 1548.442560678155, 1586.064222108938, 1587.8094628644637, 31215.37569248557, 32277.677223227467, 32814.83592582022, 33198.83400583078, 33249.92413576892, 55596.16605750998, 55823.883522330136, 56319.71840140773, 56401.0, 56567.0, 425466.0, 431453.0, 431672.0, 432372.0, 433662.0, 30821.767129314576, 35795.284094317445, 36369.090772730895, 36779.63220367869, 36792.63207367999, 44963.775181123885, 45096.323555147355, 45505.77247113743, 46211.0, 46504.069918602116, 34357.656423435445, 35405.11487212737, 36683.44974825326, 37937.4309385365, 38559.6144038556, 36960.44559331666, 37091.0, 37311.626883730816, 38127.80936095302, 39517.40723889087, 38643.0, 39106.6089339103, 39552.40671389874, 39834.2033159341, 40027.999300017735, 28326.716732832407, 33165.668343316254, 34500.65499345075, 34566.481502777984, 34873.82563087168, 25201.87399062993, 25602.487950241262, 25649.845756941355, 25874.74125258723, 26050.478990419706], "unit": "score", "name": "ext.html", "value": 20949.12931889414}, {"lowerIsBetter": false, "replicates": [379.25097931585117, 391.6807801641662, 392.7231301932138, 396.14432825097794, 400.7154920006795, 17318.04750738712, 20282.69575956362, 20474.283400081, 20949.266775662854, 20966.427517936158, 12158.027357811376, 12582.874171258285, 21071.157153713833, 21971.450713732163, 21987.670184947237, 5392.272043274166, 5531.446855314477, 5596.720163991805, 5596.832095037135, 5609.439056094378, 4186.413902053715, 4289.571042895716, 4377.080813029268, 6111.0, 6160.353162917874, 6105.389461053904, 6136.202293701813, 6145.969270153665, 6153.815385538419, 6161.3838616138255, 5860.677662728548, 6121.602095863777, 6132.540059495555, 6141.263048434194, 6148.538859585549, 4087.97956010221, 5758.683272420015, 5887.882242355172, 5976.5218782497295, 5992.310884248323, 22243.666345004836, 24268.878655606608, 25191.24426267215, 25863.482730345288, 25921.092761753247, 2338.1699496678602, 2340.403197184724, 2651.0, 2854.3863069439963, 2868.885244590214, 10303.587856485732, 10667.626633067883, 12068.818967715491, 12323.75352492945, 15776.132312722795, 1827.625336805965, 1844.317602487075, 1916.6837471817134, 1935.0711658404045, 1955.4036019014195, 112140.0, 113768.4311578445, 116658.41670791677, 116976.0, 118636.22045669236, 11484.253523520945, 11595.478203480863, 11671.0, 11677.240979336317, 11784.58753943608, 14605.415783368546, 14761.040532365361, 14767.409303627735, 14769.92615036918, 14807.70384592302, 8956.68651597191, 8978.685745998859, 9030.45817250967, 9060.275177985877, 9183.127602877716, 9020.458772473541, 9032.63869445215, 9052.185303322733, 9118.36171468004, 9173.724788256362, 4524.457065152203, 4618.65360097994, 4632.25883858581, 4647.6281897448125, 4675.392199014174, 1778.060621839086, 1812.4834422189613, 1907.1513176636263, 1956.4228552577024, 1963.0773536438137, 883.779055236191, 949.8432758594893, 1052.1793001458875, 1074.822654262038, 1075.198976762317, 16778.496645100666, 20987.160513579285, 21053.684194736787, 21182.78817211839, 21465.0, 1018.1142406106586, 1038.3199004651965, 1076.6500887211687, 1090.2422816142705, 1171.847659804237, 10856.348619082723, 11305.773884522263, 11321.830172547418, 11328.433578321052, 11330.790495853229, 719174.0, 722789.3860530768, 739815.0, 744633.0, 749724.0, 124155.0, 124378.0, 128506.28962262293, 131251.0, 135246.0, 940.3652534539159, 999.8500224966314, 1015.9789411641253, 1052.079430498314, 1059.7456610413444, 18458.077096145407, 18701.812981870553, 18778.464907237347, 18802.0, 19004.809951900304, 53413.73293133509, 55194.0, 55270.72364638151, 55794.72102639461, 57001.429985699615, 80405.0, 80628.0, 81860.0, 82589.0, 84257.57871210604, 8073.596320184085, 8155.9184408157535, 8342.79143021405, 8721.563921803758, 8961.23829474502, 12856.135612071366, 13850.16898986064, 13957.651058723613, 14023.649408764863, 14371.281435928371, 9740.805183896424, 11608.767824643292, 12140.696482588006, 12149.0, 12167.513299467922, 9853.310268281151, 11570.479328430072, 11812.0, 11990.820137698116, 12094.548654161557, 11915.344656043653, 11980.400979950793, 12052.939735301268, 12244.632661020183, 12396.81404778911, 8451.577421129043, 8522.232999030117, 8678.652853885775, 8721.956390218009, 8947.328950328621, 11679.182457227913, 11847.170698051053, 11903.0, 12114.273143611412, 12180.025597951966], "unit": "score", "name": "jquery.html", "value": 9892.553166938349}, {"lowerIsBetter": false, "replicates": [1509.335892207428, 1648.7691723158757, 1678.4628918746002, 2020.888851113189, 2105.4946812764933, 8430.83138337233, 9103.681371152012, 9353.15821576058, 9360.81278374432, 9366.20387267083, 8944.195022447979, 9232.122948319899, 9246.768830779234, 9247.21398681113, 9247.982721900586, 2559.5264875997955, 2589.5338839008973, 2596.31197732601, 2596.44176502052, 2600.6489123968213, 2518.106072344319, 2612.098825905069, 2615.6730408698913, 2624.7375262473793, 2631.394779200785, 2591.727868573801, 2605.400757825701, 2625.6061590761346, 2628.9737102629015, 2630.289821748127, 2575.497777933304, 2594.948101037978, 2617.9083732069284, 2627.395698989224, 2631.236941287034, 2569.4732579821093, 2599.506093842164, 2609.178108895711, 2610.7258737832635, 2617.8429294242405, 9262.444253344753, 9311.022342653992, 9342.205912497446, 9347.906520934772, 9355.251579873593, 5726.856828579319, 5881.882362352729, 5900.940990590082, 5918.171455996164, 5922.289325280994, 3719.107414220595, 3787.20468701573, 3828.0, 3853.556840963269, 3861.9806900965314, 1074.5379486820664, 1080.0873262093519, 1087.1140020882956, 1128.0637071230858, 1129.5594718059963, 12456.12807103494, 12542.81185782214, 12568.622941311774, 12572.559960401433, 12643.051771117203, 5022.89954200914, 5027.4721154279005, 5036.79852805891, 5081.2378143278065, 5099.64302498829, 10678.145748340114, 10693.984071513194, 10697.41164235959, 10711.732206694895, 10766.461676916122, 5819.243498345188, 5819.941800582024, 5838.065909454467, 5848.795292164754, 5854.502367298827, 4648.697834640738, 4808.158572249842, 4988.6757360771435, 5144.254083157921, 5197.896042079213, 4112.6150835421295, 4139.379093135994, 4187.0, 4209.621134097946, 4237.724547904377, 1123.5168877382803, 1362.8296462942133, 1373.5329987803996, 1383.7716776731927, 1385.0789225165151, 1426.2013272567187, 1444.4077928049448, 1456.0826679192041, 1462.9487967921073, 1497.0119720984317, 8618.396712230084, 8618.612162452704, 8659.696910608225, 8671.132886711279, 8671.826563468696, 532.0688794609433, 537.4893850841715, 545.4354742841141, 545.939946605873, 546.6364867363159, 6411.070394792729, 6479.092926990225, 6482.416582507597, 6490.0, 6515.609063456208, 11028.724281893017, 11401.144914131473, 11572.6528204154, 11587.942060289814, 11623.709407264885, 11116.166287528467, 11189.0, 11240.662780116609, 11309.98210161073, 11362.886371136348, 8922.86615700765, 8943.23982461498, 8962.955185224033, 8995.775105622542, 9024.774380640536, 8328.916710832815, 8329.167083291622, 8403.915960840313, 8418.831623367621, 8424.368172386972, 10096.293259471766, 10121.443320617142, 10225.0, 10374.792504150026, 10401.375917444977, 10285.794284114425, 10359.63741269052, 10625.309354892217, 10831.783364332827, 10863.619773307897, 7110.964445177741, 7355.521891077063, 7630.618469076413, 7896.407769417431, 7993.320567751573, 8627.43921645091, 8697.826043479221, 8712.477251364939, 8720.956395217983, 8749.387542871938, 7641.7707468776025, 7708.845823083395, 7759.30166285037, 7826.491278066907, 7869.252421019994, 6520.967395162994, 7427.888581671171, 7477.775666730006, 7492.887606686013, 7614.923850761643, 8006.159353267823, 8177.345812335118, 8201.384896132933, 8218.753437396888, 8229.506229626242, 7365.705371785283, 7394.704211831467, 7412.592307422928, 7429.108506979197, 7434.182239953492, 7494.662740176596, 7517.436192285491, 7561.054868141482, 7561.9243807563425, 7568.243175682608], "unit": "score", "name": "mootools.html", "value": 5126.131645873175}, {"lowerIsBetter": false, "replicates": [339.95920489541254, 350.4288010542816, 352.2637687233682, 357.46380429355963, 361.9800910949897, 6675.132232809734, 6684.197896252451, 6693.129893113888, 6729.528932974694, 6739.123913891193, 6655.600663960166, 6656.13470248867, 6662.634159997201, 6664.300248473914, 6667.499937504695, 4724.574788269055, 4790.041991601676, 4817.614590832725, 4937.753112344386, 5002.62480313977, 4974.8258810941625, 4979.004199160165, 4981.277714731362, 4993.400791904958, 5012.27322038306, 4448.4884238312525, 4603.0, 4923.433805112404, 4925.605951523888, 4931.901361972758, 4725.692829966041, 4778.450478195017, 4900.828471003498, 4902.240152776325, 4926.408830940292, 3744.2511497700298, 3811.9237615247816, 3947.388154836005, 4002.239574480868, 4225.176090662322, 6361.9045714314325, 6370.948793449075, 6382.234131904155, 6383.872322553523, 6417.486601071903, 5792.855178620526, 5794.246747922785, 5815.563832712563, 5826.533877289807, 5829.300483941912, 7053.0830991970715, 7055.329743674394, 7065.364117229474, 7070.36366726997, 7073.0, 4450.198964186445, 4622.699524530928, 4639.4896561378555, 4643.535646435364, 4644.976775116136, 7321.963390183068, 7489.10130784304, 7494.625268736596, 7499.100107987022, 7504.474686771928, 4706.482286948433, 4707.95292047082, 4714.669973101919, 4718.127146477913, 4725.503822098699, 4770.737609431445, 4773.451053128865, 4783.736894470838, 4790.616750659939, 4800.759962001886, 5645.4072322405555, 5645.689487078168, 5656.4060773619, 5659.528522584075, 5667.744951477193, 6849.965750171317, 7091.751788687371, 7102.786916392516, 7124.287571242938, 7124.750633727898, 1633.3548248442037, 1644.4491095483147, 1661.4101993792272, 1666.6749983753293, 1682.2345832646117, 1000.4397537379091, 1046.6859942017363, 1051.9158467322595, 1066.3228849680354, 1080.8811030786608, 6511.967440162864, 6599.439047681001, 6756.324367563205, 6782.491313151435, 6789.083473730983, 7364.77905662831, 7386.224446433154, 7398.482106252511, 7410.147832999274, 7419.517731347445, 6745.561538499982, 6753.797386078426, 6761.222459417131, 6770.0, 6784.7964561063245, 3172.9682703173135, 3872.3997780344093, 3877.933568268749, 3925.705572082048, 3928.0769019280447, 6291.937080629135, 7437.367823734935, 7740.380769538423, 7769.922300777033, 7842.52944823301, 6308.716107775161, 7628.46600737943, 7690.461667683208, 7695.538267703956, 7702.653380597886, 6382.904256436157, 6675.933240667531, 7772.533647981139, 7791.766247012599, 7820.882686759589, 7098.893516597152, 7426.851462970819, 7431.702731890664, 7435.70257189728, 7443.069616297963, 7223.927760722326, 7267.7819665410125, 7285.198628151005, 7285.453590980592, 7289.708411663474, 7741.651625676745, 7744.92255077442, 7750.0, 7756.495827771177, 7774.2614451627005, 4694.7887345068875, 4743.976280118577, 4745.9050818984115, 4752.857414277577, 4753.809847606057, 5817.5345972321265, 7042.084529011196, 7250.347468727839, 7379.3358597726465, 7408.555486670817, 7092.822679433056, 7092.929070709227, 7094.0, 7096.042034325506, 7124.928750712426, 7037.542559733601, 7078.256783037707, 7091.11361079865, 7109.395701365442, 7117.074780278531, 6959.860802784017, 6961.338672826073, 6966.79099627012, 6974.232834388315, 6974.721011159497, 6832.795016149524, 7077.292270772881, 7107.395871350993, 7116.715331386687, 7132.750353737594, 6735.191776986793, 6755.121834161528, 6897.930820722595, 6918.93081069203, 6961.547499412522], "unit": "score", "name": "prototype.html", "value": 5194.39659367045}, {"lowerIsBetter": false, "replicates": [669.7321071571371, 740.9110906691197, 752.4281546025021, 776.8135647444615, 825.1542169276489, 15753.921230393846, 15985.280662370193, 15998.920005399972, 16013.0, 16018.19909004548, 14706.926465367658, 14712.632184195401, 14737.0, 15493.84506154938, 16944.661106777858, 1012.2711647613706, 1024.160188645311, 1036.0209601926183, 1036.9889357876086, 1038.7091614347994, 1031.54611970733, 1042.681981995491, 1043.7755882485264, 1045.6706137566641, 1057.122588251753, 1091.9945400273027, 1099.9770213701254, 1100.1638754546523, 1100.4662738571785, 1101.752105776198, 1082.1288862465713, 1084.0947808579845, 1085.2403317677579, 1086.413336798132, 1086.6522712731928, 821.3962737388015, 823.3824631526355, 823.501781422237, 823.9423240373176, 826.499967519654, 13052.477900883954, 15443.305051272608, 15619.375224990987, 15690.274069852307, 15720.056796592242, 11128.833067503994, 11890.346030968294, 13459.86540134596, 13486.258255795927, 13637.181769093886, 10397.116245119174, 10433.061024507755, 10444.529996150191, 10789.136869050535, 10963.56145754169, 3627.9637203628154, 3630.8729194478065, 3669.522962014921, 3766.886993390175, 3908.5895980921878, 67714.66142669304, 84540.0, 85481.14518854795, 92881.99999999933, 94460.0, 10055.949720251352, 10162.796744065077, 10330.741731456623, 10456.52945617449, 10471.267011309135, 23744.7625523746, 23806.92868820907, 23836.284911452683, 23843.0, 23852.04591816308, 7969.641366138538, 7974.840503189904, 8015.118336982927, 8111.026676798704, 8216.507009579444, 4244.554321796229, 4321.351797230441, 4385.188740083031, 4560.247559152769, 4641.535846415399, 3705.184859330997, 3745.6254374562873, 3745.85016599333, 3748.850045998184, 3753.6434038766274, 709.4147328454046, 712.5297303779585, 712.5404114346321, 715.2132654080469, 723.6273319240595, 605.6487237402296, 620.736187120472, 624.1355722324522, 624.6470744029609, 633.9904901426482, 7239.348458638748, 8475.449095808754, 8519.872201916853, 8553.187447192508, 8558.871616925751, 494.05940594059405, 598.0222336479886, 615.5906322295713, 622.3341025103097, 624.7625902157242, 3861.710371722132, 3919.8824035278985, 4024.4164596133396, 4034.616711412411, 4123.619346011216, 99902.0, 100178.0, 101720.0, 101803.49098254461, 102363.4881825601, 82977.17022829695, 83210.0, 83525.58237208775, 83892.16107838966, 84109.0, 75938.86091708629, 76573.23426765772, 77249.22750772533, 77702.0, 77952.05119871935, 7956.2441568050945, 8072.313853322534, 8089.797755056171, 8193.385496087698, 8365.121662225378, 50233.0, 51779.741101294254, 52405.47594524158, 52808.0, 53343.199852001475, 70373.29626703667, 71737.28262717306, 71969.28030719626, 72208.27791722016, 72642.63678681572, 11512.59705910289, 11636.06911447099, 11665.766684666429, 11801.0, 11925.4037298133, 12041.697336224603, 12282.81575776382, 12358.258504489759, 12377.319247441117, 12931.482740690642, 11839.526418943145, 11859.762804743685, 11871.821922670993, 11887.82168267494, 11973.880261197277, 11871.465784039567, 12074.637760867188, 12076.637700868989, 12138.089643276613, 12189.268643881396, 11484.138689598147, 11734.765304694029, 11748.0, 11917.16579839403, 12121.81817272724, 9916.256280779115, 10115.039071288216, 10220.744481388025, 10262.743431413975, 10368.896311037095, 10025.649102281384, 10230.181585473294, 10235.590576377159, 10240.078392944373, 10286.639967601099], "unit": "score", "name": "yui.html", "value": 7384.214876595433}], "name": "dromaeo_css", "value": 7859.008835431461}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 107.0, 112.0, 110.0, 113.0, 112.0, 120.0, 111.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.8}, {"lowerIsBetter": true, "replicates": [156.0, 118.0, 156.0, 148.0, 117.0, 142.0, 118.0, 150.0, 149.0, 119.0], "unit": "score", "name": "audio-beat-detection", "value": 137.3}, {"lowerIsBetter": true, "replicates": [184.0, 186.0, 185.0, 186.0, 187.0, 187.0, 183.0, 186.0, 183.0, 184.0], "unit": "score", "name": "audio-dft", "value": 185.1}, {"lowerIsBetter": true, "replicates": [74.0, 75.0, 75.0, 114.0, 55.0, 57.0, 60.0, 56.0, 118.0, 54.0], "unit": "score", "name": "audio-fft", "value": 73.8}, {"lowerIsBetter": true, "replicates": [92.0, 94.0, 128.0, 80.0, 77.0, 84.0, 115.0, 77.0, 80.0, 117.0], "unit": "score", "name": "audio-oscillator", "value": 94.4}, {"lowerIsBetter": true, "replicates": [86.0, 129.0, 87.0, 83.0, 86.0, 82.0, 86.0, 89.0, 87.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 89.9}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 91.0, 91.0], "unit": "score", "name": "imaging-darkroom", "value": 91.8}, {"lowerIsBetter": true, "replicates": [83.0, 82.0, 84.0, 84.0, 87.0, 85.0, 83.0, 85.0, 86.0, 89.0], "unit": "score", "name": "imaging-desaturate", "value": 84.8}, {"lowerIsBetter": true, "replicates": [54.0, 57.0, 57.0, 56.0, 58.0, 57.0, 57.0, 65.0, 51.0, 48.0], "unit": "score", "name": "json-parse-financial", "value": 56.0}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 69.0, 66.0, 74.0, 71.0, 66.0, 65.0, 66.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.8}, {"lowerIsBetter": true, "replicates": [80.0, 80.0, 81.0, 80.0, 79.0, 80.0, 78.0, 80.0, 82.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.3}, {"lowerIsBetter": true, "replicates": [119.0, 121.0, 119.0, 118.0, 117.0, 129.0, 125.0, 124.0, 139.0, 122.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.3}, {"lowerIsBetter": true, "replicates": [157.0, 135.0, 135.0, 133.0, 134.0, 124.0, 135.0, 129.0, 132.0, 131.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 134.5}, {"lowerIsBetter": true, "replicates": [65.0, 66.0, 79.0, 67.0, 65.0, 66.0, 66.0, 65.0, 66.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.0}], "name": "kraken", "value": 1396.8}]} 18:01:01 INFO - Return code: 0 18:01:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:01:01 INFO - # TBPL SUCCESS # 18:01:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:01:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:01:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:01:01 INFO - 2016-05-06 18:01:01.926 screenresolution[1960:27126] starting screenresolution argv=screenresolution get 18:01:01 INFO - 2016-05-06 18:01:01.928 screenresolution[1960:27126] Display 0: 1600x1200x32@60 18:01:01 INFO - 2016-05-06 18:01:01.934 screenresolution[1961:27129] starting screenresolution argv=screenresolution list 18:01:01 INFO - Available Modes on Display 0 18:01:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:01:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:01:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:01:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:01:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:01:02 INFO - Intel Iris: 18:01:02 INFO - Chipset Model: Intel Iris 18:01:02 INFO - Type: GPU 18:01:02 INFO - Bus: Built-In 18:01:02 INFO - VRAM (Dynamic, Max): 1536 MB 18:01:02 INFO - Vendor: Intel (0x8086) 18:01:02 INFO - Device ID: 0x0a2e 18:01:02 INFO - Revision ID: 0x0009 18:01:02 INFO - Displays: 18:01:02 INFO - Display: 18:01:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:01:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:01:02 INFO - Display Serial Number: 1600x1200 60 18:01:02 INFO - Main Display: Yes 18:01:02 INFO - Mirror: Off 18:01:02 INFO - Online: Yes 18:01:02 INFO - Rotation: Supported 18:01:02 INFO - Return code: 0 18:01:02 INFO - Running post-action listener: _resource_record_post_action 18:01:02 INFO - Running post-run listener: _resource_record_post_run 18:01:03 INFO - Total resource usage - Wall time: 1191s; CPU: 53.0%; Read bytes: 884736; Write bytes: 507395072; Read time: 11; Write time: 1848 18:01:03 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 200446464; Write bytes: 196128768; Read time: 17935; Write time: 363 18:01:03 INFO - run-tests - Wall time: 1170s; CPU: 53.0%; Read bytes: 884736; Write bytes: 300473344; Read time: 11; Write time: 1455 18:01:03 INFO - Running post-run listener: _upload_blobber_files 18:01:03 INFO - Blob upload gear active. 18:01:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:01:03 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 18:01:03 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:01:03 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:01:03 INFO - (blobuploader) - INFO - Open directory for files ... 18:01:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:01:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:01:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:01:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:01:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:01:04 INFO - (blobuploader) - INFO - Done attempting. 18:01:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:01:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:01:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:01:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:01:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:01:05 INFO - (blobuploader) - INFO - Done attempting. 18:01:05 INFO - (blobuploader) - INFO - Iteration through files over. 18:01:05 INFO - Return code: 0 18:01:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:01:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:01:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1365c7fa6e3bed925f9e487b267fa966f04d95f6f69125e7817fca980476c6c19fd2783a4e4a130d2ada27e34c3caf23cc9d9d20fd69fc030b315eac85df653a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/71f123bb34d20b7f9e88b994392fe65ee22be3c379c221fce2e0fbbb7ae7b0568906a6c942ebc980494fe6cb9b875b4d6a35e5d0f0b4f540573903bcd08f6cf6"} 18:01:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:01:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:01:05 INFO - Contents: 18:01:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1365c7fa6e3bed925f9e487b267fa966f04d95f6f69125e7817fca980476c6c19fd2783a4e4a130d2ada27e34c3caf23cc9d9d20fd69fc030b315eac85df653a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/71f123bb34d20b7f9e88b994392fe65ee22be3c379c221fce2e0fbbb7ae7b0568906a6c942ebc980494fe6cb9b875b4d6a35e5d0f0b4f540573903bcd08f6cf6"} 18:01:05 INFO - Running post-run listener: copy_logs_to_upload_dir 18:01:05 INFO - Copying logs to upload dir... 18:01:05 INFO - mkdir: /builds/slave/test/build/upload/logs 18:01:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.080288 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-05-06 18:01:05.933236) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:05.936964) ========= 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.wKm9xxMYqV/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.PVfL1NTWpf/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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-Beta/sha512/1365c7fa6e3bed925f9e487b267fa966f04d95f6f69125e7817fca980476c6c19fd2783a4e4a130d2ada27e34c3caf23cc9d9d20fd69fc030b315eac85df653a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/71f123bb34d20b7f9e88b994392fe65ee22be3c379c221fce2e0fbbb7ae7b0568906a6c942ebc980494fe6cb9b875b4d6a35e5d0f0b4f540573903bcd08f6cf6"} build_url:https://queue.taskcluster.net/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011175 build_url: 'https://queue.taskcluster.net/v1/task/RVoShV2JRpm3KjNlXSrcrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1365c7fa6e3bed925f9e487b267fa966f04d95f6f69125e7817fca980476c6c19fd2783a4e4a130d2ada27e34c3caf23cc9d9d20fd69fc030b315eac85df653a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/71f123bb34d20b7f9e88b994392fe65ee22be3c379c221fce2e0fbbb7ae7b0568906a6c942ebc980494fe6cb9b875b4d6a35e5d0f0b4f540573903bcd08f6cf6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:05.965055) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:05.965389) ========= 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.wKm9xxMYqV/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.PVfL1NTWpf/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.005238 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:01:06.014430) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 18:01:06.014765) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 18:01:06.015072) ========= ========= Total master_lag: 0.17 =========