builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0299 starttime: 1482748856.21 results: success (0) buildid: 20161226004003 builduid: 56189c185af14b6fa3346d6a7cffe87e revision: 39582d06756a9ff47993d4097b4fb25f041be406 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 02:40:56.207998) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 02:40:56.208437) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 02:40:56.208767) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 02:40:56.243207) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 02:40:56.243610) ========= 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.hMQSqHhNsI/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.HSew9nYMME/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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-12-26 02:40:56-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-12-26 02:40:56 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.227043 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 02:40:56.494012) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 02:40:56.494299) ========= 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.hMQSqHhNsI/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.HSew9nYMME/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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.025088 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 02:40:56.583838) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 02:40:56.584143) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 39582d06756a9ff47993d4097b4fb25f041be406 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 39582d06756a9ff47993d4097b4fb25f041be406 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hMQSqHhNsI/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.HSew9nYMME/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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-12-26 02:40:56,683 truncating revision to first 12 chars 2016-12-26 02:40:56,683 Setting DEBUG logging. 2016-12-26 02:40:56,683 attempt 1/10 2016-12-26 02:40:56,683 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/39582d06756a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-26 02:40:57,974 unpacking tar archive at: mozilla-aurora-39582d06756a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.571129 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 02:40:58.184995) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 02:40:58.185302) ========= script_repo_revision: 39582d06756a9ff47993d4097b4fb25f041be406 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 02:40:58.185727) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 02:40:58.185991) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 02:40:58.205698) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-26 02:40:58.206043) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hMQSqHhNsI/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.HSew9nYMME/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:40:58 INFO - MultiFileLogger online at 20161226 02:40:58 in /builds/slave/test 02:40:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:40:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:40:58 INFO - {'append_to_log': False, 02:40:58 INFO - 'base_work_dir': '/builds/slave/test', 02:40:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:40:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:40:58 INFO - 'branch': 'Mozilla-Aurora', 02:40:58 INFO - 'buildbot_json_path': 'buildprops.json', 02:40:58 INFO - 'config_files': ('talos/mac_config.py',), 02:40:58 INFO - 'default_actions': ('clobber', 02:40:58 INFO - 'read-buildbot-config', 02:40:58 INFO - 'download-and-extract', 02:40:58 INFO - 'populate-webroot', 02:40:58 INFO - 'create-virtualenv', 02:40:58 INFO - 'install', 02:40:58 INFO - 'run-tests'), 02:40:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:40:58 INFO - 'download_minidump_stackwalk': True, 02:40:58 INFO - 'download_symbols': 'ondemand', 02:40:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:40:58 INFO - 'tooltool.py': '/tools/tooltool.py', 02:40:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:40:58 INFO - '/tools/misc-python/virtualenv.py')}, 02:40:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:40:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:40:58 INFO - 'installer_path': 'installer.exe', 02:40:58 INFO - 'log_level': 'info', 02:40:58 INFO - 'log_name': 'talos', 02:40:58 INFO - 'log_to_console': True, 02:40:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:40:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:40:58 INFO - 'opt_config_files': (), 02:40:58 INFO - 'pip_index': False, 02:40:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:40:58 INFO - 'cmd': ('bash', 02:40:58 INFO - '-c', 02:40:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:40:58 INFO - 'enabled': True, 02:40:58 INFO - 'halt_on_failure': False, 02:40:58 INFO - 'name': 'check_screen_resolution'},), 02:40:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:40:58 INFO - 'cmd': ('bash', 02:40:58 INFO - '-c', 02:40:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:40:58 INFO - 'enabled': True, 02:40:58 INFO - 'halt_on_failure': False, 02:40:58 INFO - 'name': 'check_screen_resolution'},), 02:40:58 INFO - 'run_cmd_checks_enabled': True, 02:40:58 INFO - 'sps_profile': False, 02:40:58 INFO - 'sps_profile_interval': 0, 02:40:58 INFO - 'suite': 'dromaeojs', 02:40:58 INFO - 'system_bits': '32', 02:40:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:40:58 INFO - 'title': 't-yosemite-r7-0299', 02:40:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:40:58 INFO - 'use_talos_json': True, 02:40:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:40:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:40:58 INFO - 'work_dir': 'build'} 02:40:58 INFO - [mozharness: 2016-12-26 10:40:58.406678Z] Running clobber step. 02:40:58 INFO - Running pre-action listener: _resource_record_pre_action 02:40:58 INFO - Running main action method: clobber 02:40:58 INFO - rmtree: /builds/slave/test/build 02:40:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:41:00 INFO - Running post-action listener: _resource_record_post_action 02:41:00 INFO - [mozharness: 2016-12-26 10:41:00.919090Z] Finished clobber step (success) 02:41:00 INFO - [mozharness: 2016-12-26 10:41:00.919282Z] Running read-buildbot-config step. 02:41:00 INFO - Running pre-action listener: _resource_record_pre_action 02:41:00 INFO - Running main action method: read_buildbot_config 02:41:00 INFO - Using buildbot properties: 02:41:00 INFO - { 02:41:00 INFO - "project": "", 02:41:00 INFO - "product": "firefox", 02:41:00 INFO - "script_repo_revision": "production", 02:41:00 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:41:00 INFO - "repository": "", 02:41:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:41:00 INFO - "buildid": "20161226004003", 02:41:00 INFO - "stage_platform": "macosx64", 02:41:00 INFO - "basedir": "/builds/slave/test", 02:41:00 INFO - "nightly_build": "True", 02:41:00 INFO - "buildnumber": 9, 02:41:00 INFO - "slavename": "t-yosemite-r7-0299", 02:41:00 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:41:00 INFO - "platform": "yosemite_r7", 02:41:00 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:41:00 INFO - "branch": "mozilla-aurora", 02:41:00 INFO - "repo_path": "releases/mozilla-aurora", 02:41:00 INFO - "revision": "39582d06756a9ff47993d4097b4fb25f041be406", 02:41:00 INFO - "pgo_build": "False", 02:41:00 INFO - "builduid": "56189c185af14b6fa3346d6a7cffe87e", 02:41:00 INFO - "slavebuilddir": "test" 02:41:00 INFO - } 02:41:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:41:00 INFO - Running post-action listener: _resource_record_post_action 02:41:00 INFO - [mozharness: 2016-12-26 10:41:00.922696Z] Finished read-buildbot-config step (success) 02:41:00 INFO - [mozharness: 2016-12-26 10:41:00.922836Z] Running download-and-extract step. 02:41:00 INFO - Running pre-action listener: _resource_record_pre_action 02:41:00 INFO - Running main action method: download_and_extract 02:41:00 INFO - mkdir: /builds/slave/test/build/tests 02:41:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:00 INFO - trying https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:41:00 INFO - Downloading https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:41:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:41:02 INFO - Downloaded 1199 bytes. 02:41:02 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:41:02 INFO - Using the following test package requirements: 02:41:02 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:41:02 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:02 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:41:02 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:02 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:41:02 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:02 INFO - u'jsshell-mac.zip'], 02:41:02 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:02 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:41:02 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:41:02 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:02 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:41:02 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:02 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:41:02 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:02 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:41:02 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:41:02 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:41:02 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:41:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:41:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:41:02 INFO - Fetch https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:41:02 INFO - Http code: 200 02:41:02 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 02:41:02 INFO - x-amz-cf-id: HAXqPhaYIPlfSf0l5b5uR3NONnOLO7gpIWbEfQ3FKFE0AWFc3ISFXw== 02:41:02 INFO - x-amz-version-id: _KS8K2XOf0IitkZfKztahfrQ.fXILtCF 02:41:03 INFO - Expected file size: 16271136 02:41:03 INFO - Obtained file size: 16271136 02:41:10 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:41:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:41:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:41:10 INFO - Fetch https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:41:10 INFO - Http code: 200 02:41:10 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 02:41:10 INFO - x-amz-cf-id: mvGjRXqMDMC4bKXP4gr0WuCysjDmyZE3U_vK-dc8V4hCG_E3mnQeRw== 02:41:10 INFO - x-amz-version-id: _KS8K2XOf0IitkZfKztahfrQ.fXILtCF 02:41:10 INFO - Expected file size: 16271136 02:41:10 INFO - Obtained file size: 16271136 02:41:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:41:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:41:17 INFO - Fetch https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:41:18 INFO - Http code: 200 02:41:18 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 02:41:18 INFO - x-amz-cf-id: 6VkLb-AZLWEzWyTXA0WQmXUgMflAuICSwzviVOe6Vx57lmVT_FDx2Q== 02:41:18 INFO - x-amz-version-id: _Clb5qwBO4Jp862p2OojlKCoHmNsSVdi 02:41:18 INFO - Expected file size: 14409038 02:41:18 INFO - Obtained file size: 14409038 02:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:19 INFO - trying https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:41:19 INFO - Downloading https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:41:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:41:21 INFO - Downloaded 109940568 bytes. 02:41:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:41:21 INFO - mkdir: /builds/slave/test/properties 02:41:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:41:21 INFO - Writing to file /builds/slave/test/properties/build_url 02:41:21 INFO - Contents: 02:41:21 INFO - build_url:https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:41:22 INFO - Running post-action listener: _resource_record_post_action 02:41:22 INFO - Running post-action listener: set_extra_try_arguments 02:41:22 INFO - [mozharness: 2016-12-26 10:41:22.514436Z] Finished download-and-extract step (success) 02:41:22 INFO - [mozharness: 2016-12-26 10:41:22.514583Z] Running populate-webroot step. 02:41:22 INFO - Running pre-action listener: _resource_record_pre_action 02:41:22 INFO - Running main action method: populate_webroot 02:41:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:41:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:41:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:41:22 INFO - '--tppagecycles', 02:41:22 INFO - '7'], 02:41:22 INFO - 'tests': ['tsvgm']}}, 02:41:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:41:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:41:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:41:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:41:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:41:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:41:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:22 INFO - 'talos_options': ['--disable-e10s'], 02:41:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:41:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:41:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:22 INFO - 'talos_options': ['--disable-e10s'], 02:41:22 INFO - 'tests': ['damp', 'tps']}, 02:41:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:22 INFO - 'tests': ['damp', 'tps']}, 02:41:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:41:22 INFO - 'tests': ['dromaeo_dom']}, 02:41:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:41:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:41:22 INFO - 'tests': ['basic_compositor_video']}, 02:41:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:41:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:41:22 INFO - 'tests': ['a11yr', 02:41:22 INFO - 'ts_paint', 02:41:22 INFO - 'tpaint', 02:41:22 INFO - 'sessionrestore', 02:41:22 INFO - 'sessionrestore_no_auto_restore', 02:41:22 INFO - 'tabpaint']}, 02:41:22 INFO - 'other-e10s': {'tests': ['a11yr', 02:41:22 INFO - 'ts_paint', 02:41:22 INFO - 'tpaint', 02:41:22 INFO - 'sessionrestore', 02:41:22 INFO - 'sessionrestore_no_auto_restore', 02:41:22 INFO - 'tabpaint']}, 02:41:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:41:22 INFO - 'ts_paint', 02:41:22 INFO - 'tpaint', 02:41:22 INFO - 'sessionrestore', 02:41:22 INFO - 'sessionrestore_no_auto_restore', 02:41:22 INFO - 'tabpaint']}, 02:41:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:41:22 INFO - 'ts_paint', 02:41:22 INFO - 'tpaint', 02:41:22 INFO - 'sessionrestore', 02:41:22 INFO - 'sessionrestore_no_auto_restore', 02:41:22 INFO - 'tabpaint']}, 02:41:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:41:22 INFO - 'tests': ['a11yr', 02:41:22 INFO - 'ts_paint', 02:41:22 INFO - 'tpaint', 02:41:22 INFO - 'sessionrestore', 02:41:22 INFO - 'sessionrestore_no_auto_restore', 02:41:22 INFO - 'tabpaint']}, 02:41:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:41:22 INFO - 'tests': ['a11yr', 02:41:22 INFO - 'ts_paint', 02:41:22 INFO - 'tpaint', 02:41:22 INFO - 'sessionrestore', 02:41:22 INFO - 'sessionrestore_no_auto_restore', 02:41:22 INFO - 'tabpaint']}, 02:41:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:41:22 INFO - 'tests': ['tsvgx', 02:41:22 INFO - 'tsvgr_opacity', 02:41:22 INFO - 'tart', 02:41:22 INFO - 'tscrollx', 02:41:22 INFO - 'cart']}, 02:41:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:41:22 INFO - 'tsvgr_opacity', 02:41:22 INFO - 'tart', 02:41:22 INFO - 'tscrollx', 02:41:22 INFO - 'cart']}, 02:41:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:22 INFO - 'talos_options': ['--disable-e10s'], 02:41:22 INFO - 'tests': ['tp5o']}, 02:41:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:22 INFO - 'tests': ['tp5o']}, 02:41:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:22 INFO - 'talos_options': ['--disable-e10s', 02:41:22 INFO - '--xperf_path', 02:41:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:41:22 INFO - 'tests': ['tp5n']}, 02:41:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:22 INFO - 'talos_options': ['--xperf_path', 02:41:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:41:22 INFO - 'tests': ['tp5n']}}, 02:41:22 INFO - 'talos.zip': {'path': '', 02:41:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:41:22 INFO - Running post-action listener: _resource_record_post_action 02:41:22 INFO - [mozharness: 2016-12-26 10:41:22.533712Z] Finished populate-webroot step (success) 02:41:22 INFO - [mozharness: 2016-12-26 10:41:22.533843Z] Running create-virtualenv step. 02:41:22 INFO - Running pre-action listener: _resource_record_pre_action 02:41:22 INFO - Running main action method: create_virtualenv 02:41:22 INFO - Creating virtualenv /builds/slave/test/build/venv 02:41:22 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:41:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:41:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:41:22 INFO - Using real prefix '/tools/python27' 02:41:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:41:23 INFO - Installing distribute.............................................................................................................................................................................................done. 02:41:26 INFO - Installing pip.................done. 02:41:26 INFO - Return code: 0 02:41:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:41:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:41:26 INFO - Reading from file tmpfile_stdout 02:41:26 INFO - Output received: 02:41:26 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:41:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:41:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1051c2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105138570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf79d87a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd6d30>, '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 0x104e3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fceb20>, '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.hMQSqHhNsI/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.HSew9nYMME/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:41:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:41:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hMQSqHhNsI/Render', 02:41:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:26 INFO - 'HOME': '/Users/cltbld', 02:41:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:26 INFO - 'LOGNAME': 'cltbld', 02:41:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:26 INFO - 'MOZ_NO_REMOTE': '1', 02:41:26 INFO - 'NO_EM_RESTART': '1', 02:41:26 INFO - 'PAGER': '/bin/cat', 02:41:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:26 INFO - 'PWD': '/builds/slave/test', 02:41:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:26 INFO - 'SHELL': '/bin/bash', 02:41:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HSew9nYMME/Listeners', 02:41:26 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 02:41:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:26 INFO - 'USER': 'cltbld', 02:41:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:26 INFO - 'XPC_FLAGS': '0x0', 02:41:26 INFO - 'XPC_SERVICE_NAME': '0', 02:41:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:26 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:41:26 INFO - Cleaning up... 02:41:26 INFO - Return code: 0 02:41:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:41:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1051c2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105138570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf79d87a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd6d30>, '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 0x104e3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fceb20>, '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.hMQSqHhNsI/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.HSew9nYMME/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:41:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:41:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hMQSqHhNsI/Render', 02:41:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:26 INFO - 'HOME': '/Users/cltbld', 02:41:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:26 INFO - 'LOGNAME': 'cltbld', 02:41:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:26 INFO - 'MOZ_NO_REMOTE': '1', 02:41:26 INFO - 'NO_EM_RESTART': '1', 02:41:26 INFO - 'PAGER': '/bin/cat', 02:41:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:26 INFO - 'PWD': '/builds/slave/test', 02:41:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:26 INFO - 'SHELL': '/bin/bash', 02:41:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HSew9nYMME/Listeners', 02:41:26 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 02:41:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:26 INFO - 'USER': 'cltbld', 02:41:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:26 INFO - 'XPC_FLAGS': '0x0', 02:41:26 INFO - 'XPC_SERVICE_NAME': '0', 02:41:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:27 INFO - Downloading/unpacking psutil>=3.1.1 02:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:41:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:41:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:41:30 INFO - Installing collected packages: psutil 02:41:30 INFO - Running setup.py install for psutil 02:41:31 INFO - building 'psutil._psutil_osx' extension 02:41:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:41:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:41:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:41:31 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:41:31 INFO - building 'psutil._psutil_posix' extension 02:41:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:41:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:41:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:41:31 INFO - ^ 02:41:31 INFO - 1 warning generated. 02:41:31 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:41:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:41:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:41:32 INFO - Successfully installed psutil 02:41:32 INFO - Cleaning up... 02:41:32 INFO - Return code: 0 02:41:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:32 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1051c2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105138570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf79d87a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd6d30>, '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 0x104e3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fceb20>, '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.hMQSqHhNsI/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.HSew9nYMME/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:32 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.3'] in /builds/slave/test/build 02:41:32 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.3 02:41:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hMQSqHhNsI/Render', 02:41:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:32 INFO - 'HOME': '/Users/cltbld', 02:41:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:32 INFO - 'LOGNAME': 'cltbld', 02:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:32 INFO - 'MOZ_NO_REMOTE': '1', 02:41:32 INFO - 'NO_EM_RESTART': '1', 02:41:32 INFO - 'PAGER': '/bin/cat', 02:41:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:32 INFO - 'PWD': '/builds/slave/test', 02:41:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:32 INFO - 'SHELL': '/bin/bash', 02:41:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HSew9nYMME/Listeners', 02:41:32 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 02:41:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:32 INFO - 'USER': 'cltbld', 02:41:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:32 INFO - 'XPC_FLAGS': '0x0', 02:41:32 INFO - 'XPC_SERVICE_NAME': '0', 02:41:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:41:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:41:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:41:35 INFO - Installing collected packages: mozsystemmonitor 02:41:35 INFO - Running setup.py install for mozsystemmonitor 02:41:35 INFO - Successfully installed mozsystemmonitor 02:41:35 INFO - Cleaning up... 02:41:35 INFO - Return code: 0 02:41:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:41:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:35 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1051c2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105138570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf79d87a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd6d30>, '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 0x104e3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fceb20>, '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.hMQSqHhNsI/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.HSew9nYMME/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:35 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==2.5.1'] in /builds/slave/test/build 02:41:35 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==2.5.1 02:41:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hMQSqHhNsI/Render', 02:41:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:35 INFO - 'HOME': '/Users/cltbld', 02:41:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:35 INFO - 'LOGNAME': 'cltbld', 02:41:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:35 INFO - 'MOZ_NO_REMOTE': '1', 02:41:35 INFO - 'NO_EM_RESTART': '1', 02:41:35 INFO - 'PAGER': '/bin/cat', 02:41:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:35 INFO - 'PWD': '/builds/slave/test', 02:41:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:35 INFO - 'SHELL': '/bin/bash', 02:41:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HSew9nYMME/Listeners', 02:41:35 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 02:41:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:35 INFO - 'USER': 'cltbld', 02:41:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:35 INFO - 'XPC_FLAGS': '0x0', 02:41:35 INFO - 'XPC_SERVICE_NAME': '0', 02:41:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:35 INFO - Downloading/unpacking jsonschema==2.5.1 02:41:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:41:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:38 INFO - Downloading functools32-3.2.3-2.tar.gz 02:41:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:41:38 INFO - warning: no files found matching '*.txt' 02:41:38 INFO - no previously-included directories found matching 'build' 02:41:38 INFO - no previously-included directories found matching 'dist' 02:41:38 INFO - no previously-included directories found matching '.git*' 02:41:38 INFO - Installing collected packages: jsonschema, functools32 02:41:38 INFO - Running setup.py install for functools32 02:41:38 INFO - warning: no files found matching '*.txt' 02:41:38 INFO - no previously-included directories found matching 'build' 02:41:38 INFO - no previously-included directories found matching 'dist' 02:41:38 INFO - no previously-included directories found matching '.git*' 02:41:38 INFO - Successfully installed jsonschema functools32 02:41:38 INFO - Cleaning up... 02:41:39 INFO - Return code: 0 02:41:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:41:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:39 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1051c2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105138570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf79d87a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd6d30>, '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 0x104e3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fceb20>, '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.hMQSqHhNsI/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.HSew9nYMME/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:39 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:41:39 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 functools32==3.2.3-2 02:41:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hMQSqHhNsI/Render', 02:41:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:39 INFO - 'HOME': '/Users/cltbld', 02:41:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:39 INFO - 'LOGNAME': 'cltbld', 02:41:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:39 INFO - 'MOZ_NO_REMOTE': '1', 02:41:39 INFO - 'NO_EM_RESTART': '1', 02:41:39 INFO - 'PAGER': '/bin/cat', 02:41:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:39 INFO - 'PWD': '/builds/slave/test', 02:41:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:39 INFO - 'SHELL': '/bin/bash', 02:41:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HSew9nYMME/Listeners', 02:41:39 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 02:41:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:39 INFO - 'USER': 'cltbld', 02:41:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:39 INFO - 'XPC_FLAGS': '0x0', 02:41:39 INFO - 'XPC_SERVICE_NAME': '0', 02:41:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:41:39 INFO - Cleaning up... 02:41:39 INFO - Return code: 0 02:41:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:41:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:39 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:39 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 0x1051c2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105138570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf79d87a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd6d30>, '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 0x104e3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fceb20>, '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.hMQSqHhNsI/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.HSew9nYMME/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:41:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:41:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hMQSqHhNsI/Render', 02:41:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:39 INFO - 'HOME': '/Users/cltbld', 02:41:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:39 INFO - 'LOGNAME': 'cltbld', 02:41:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:39 INFO - 'MOZ_NO_REMOTE': '1', 02:41:39 INFO - 'NO_EM_RESTART': '1', 02:41:39 INFO - 'PAGER': '/bin/cat', 02:41:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:39 INFO - 'PWD': '/builds/slave/test', 02:41:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:39 INFO - 'SHELL': '/bin/bash', 02:41:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HSew9nYMME/Listeners', 02:41:39 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 02:41:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:39 INFO - 'USER': 'cltbld', 02:41:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:39 INFO - 'XPC_FLAGS': '0x0', 02:41:39 INFO - 'XPC_SERVICE_NAME': '0', 02:41:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:39 INFO - Downloading/unpacking blobuploader==1.2.4 02:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:42 INFO - Downloading blobuploader-1.2.4.tar.gz 02:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:41:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:41:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:43 INFO - Downloading docopt-0.6.1.tar.gz 02:41:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:41:43 INFO - Installing collected packages: blobuploader, requests, docopt 02:41:43 INFO - Running setup.py install for blobuploader 02:41:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:41:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:41:43 INFO - Running setup.py install for requests 02:41:44 INFO - Running setup.py install for docopt 02:41:44 INFO - Successfully installed blobuploader requests docopt 02:41:44 INFO - Cleaning up... 02:41:44 INFO - Return code: 0 02:41:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:41: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:44 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 0x1051c2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105138570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf79d87a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd6d30>, '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 0x104e3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fceb20>, '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.hMQSqHhNsI/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.HSew9nYMME/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:41:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:41:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hMQSqHhNsI/Render', 02:41:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:44 INFO - 'HOME': '/Users/cltbld', 02:41:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:44 INFO - 'LOGNAME': 'cltbld', 02:41:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:44 INFO - 'MOZ_NO_REMOTE': '1', 02:41:44 INFO - 'NO_EM_RESTART': '1', 02:41:44 INFO - 'PAGER': '/bin/cat', 02:41:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:44 INFO - 'PWD': '/builds/slave/test', 02:41:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:44 INFO - 'SHELL': '/bin/bash', 02:41:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HSew9nYMME/Listeners', 02:41:44 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 02:41:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:44 INFO - 'USER': 'cltbld', 02:41:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:44 INFO - 'XPC_FLAGS': '0x0', 02:41:44 INFO - 'XPC_SERVICE_NAME': '0', 02:41:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:41:44 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-vn8Q0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:41:44 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-lNLCg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:41:44 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-ubK7LF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:41:44 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-HKvHnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:41:45 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-CfmhCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:41:45 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-L0N2sL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:41:45 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-C7dfHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:41:45 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-KyzbEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:41:45 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-Jb0gEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:41:45 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-zGyP9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:41:45 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-OM6q9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:41:45 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-dZFR5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:41:45 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-Ppz0sK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:41:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:41:45 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-azhokX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:46 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-Cjix5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:41:46 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-sQ1MB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:41:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:41:46 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-VuDKyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:41:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:41:46 INFO - Running setup.py install for manifestparser 02:41:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:41:46 INFO - Running setup.py install for mozcrash 02:41:46 INFO - Running setup.py install for mozdebug 02:41:46 INFO - Running setup.py install for mozdevice 02:41:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:41:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:41:47 INFO - Running setup.py install for mozfile 02:41:47 INFO - Running setup.py install for mozhttpd 02:41:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:41:47 INFO - Running setup.py install for mozinfo 02:41:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:41:47 INFO - Running setup.py install for mozInstall 02:41:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:41:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:41:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:41:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:41:47 INFO - Running setup.py install for mozleak 02:41:47 INFO - Running setup.py install for mozlog 02:41:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:41:47 INFO - Running setup.py install for moznetwork 02:41:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:41:48 INFO - Running setup.py install for mozprocess 02:41:48 INFO - Running setup.py install for mozprofile 02:41:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:41:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:41:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:41:48 INFO - Running setup.py install for mozrunner 02:41:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:41:48 INFO - Running setup.py install for mozscreenshot 02:41:48 INFO - Running setup.py install for moztest 02:41:48 INFO - Running setup.py install for mozversion 02:41:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:41:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:41:48 INFO - Cleaning up... 02:41:48 INFO - Return code: 0 02:41:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:48 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 0x1051c2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105138570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf79d87a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd6d30>, '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 0x104e3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fceb20>, '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.hMQSqHhNsI/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.HSew9nYMME/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:41:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hMQSqHhNsI/Render', 02:41:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:48 INFO - 'HOME': '/Users/cltbld', 02:41:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:48 INFO - 'LOGNAME': 'cltbld', 02:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:48 INFO - 'MOZ_NO_REMOTE': '1', 02:41:48 INFO - 'NO_EM_RESTART': '1', 02:41:48 INFO - 'PAGER': '/bin/cat', 02:41:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:48 INFO - 'PWD': '/builds/slave/test', 02:41:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:48 INFO - 'SHELL': '/bin/bash', 02:41:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HSew9nYMME/Listeners', 02:41:48 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 02:41:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:48 INFO - 'USER': 'cltbld', 02:41:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:48 INFO - 'XPC_FLAGS': '0x0', 02:41:48 INFO - 'XPC_SERVICE_NAME': '0', 02:41:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:41:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-ue3yU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:41:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-mTglRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:41:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-l3e6QI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:41:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-vCbsoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:41:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-aMZ7fH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:41:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-LHQSIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:41:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:41:49 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-HMcKC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:41:50 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-2iv8IO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:41:50 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-_SWLJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:41:50 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-D0Nf4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:41:50 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-GcCJBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:41:50 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-lh_QWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:41:50 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-tsZDeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:41:50 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-EfNCde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:50 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-baV65_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:41:50 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-ccNGQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:41:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:41:50 INFO - Running setup.py (path:/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/pip-YAukcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:41:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:41:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:41:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:41:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:41:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:41:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:41:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:41:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:41:53 INFO - Downloading blessings-1.6.tar.gz 02:41:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:41:53 INFO - Installing collected packages: blessings 02:41:53 INFO - Running setup.py install for blessings 02:41:53 INFO - Successfully installed blessings 02:41:53 INFO - Cleaning up... 02:41:54 INFO - Return code: 0 02:41:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:41:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:41:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:41:54 INFO - Reading from file tmpfile_stdout 02:41:54 INFO - Current package versions: 02:41:54 INFO - blessings == 1.6 02:41:54 INFO - blobuploader == 1.2.4 02:41:54 INFO - docopt == 0.6.1 02:41:54 INFO - functools32 == 3.2.3-2 02:41:54 INFO - jsonschema == 2.5.1 02:41:54 INFO - manifestparser == 1.1 02:41:54 INFO - mozInstall == 1.12 02:41:54 INFO - mozcrash == 1.0 02:41:54 INFO - mozdebug == 0.1 02:41:54 INFO - mozdevice == 0.48 02:41:54 INFO - mozfile == 1.2 02:41:54 INFO - mozhttpd == 0.7 02:41:54 INFO - mozinfo == 0.9 02:41:54 INFO - mozleak == 0.1 02:41:54 INFO - mozlog == 3.4 02:41:54 INFO - moznetwork == 0.27 02:41:54 INFO - mozprocess == 0.23 02:41:54 INFO - mozprofile == 0.28 02:41:54 INFO - mozrunner == 6.13 02:41:54 INFO - mozscreenshot == 0.1 02:41:54 INFO - mozsystemmonitor == 0.3 02:41:54 INFO - moztest == 0.7 02:41:54 INFO - mozversion == 1.4 02:41:54 INFO - psutil == 3.1.1 02:41:54 INFO - requests == 1.2.3 02:41:54 INFO - wsgiref == 0.1.2 02:41:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:41:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:54 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 0x1051c2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105138570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf79d87a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd6d30>, '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 0x104e3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fceb20>, '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.hMQSqHhNsI/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.HSew9nYMME/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:41:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:41:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hMQSqHhNsI/Render', 02:41:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:54 INFO - 'HOME': '/Users/cltbld', 02:41:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:54 INFO - 'LOGNAME': 'cltbld', 02:41:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:54 INFO - 'MOZ_NO_REMOTE': '1', 02:41:54 INFO - 'NO_EM_RESTART': '1', 02:41:54 INFO - 'PAGER': '/bin/cat', 02:41:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:54 INFO - 'PWD': '/builds/slave/test', 02:41:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:54 INFO - 'SHELL': '/bin/bash', 02:41:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HSew9nYMME/Listeners', 02:41:54 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 02:41:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:54 INFO - 'USER': 'cltbld', 02:41:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:54 INFO - 'XPC_FLAGS': '0x0', 02:41:54 INFO - 'XPC_SERVICE_NAME': '0', 02:41:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:41:54 INFO - Cleaning up... 02:41:54 INFO - Return code: 0 02:41:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:41:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:41:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:54 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 0x1051c2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105138570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf79d87a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd6d30>, '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 0x104e3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fceb20>, '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.hMQSqHhNsI/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.HSew9nYMME/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:41:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:41:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:41:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hMQSqHhNsI/Render', 02:41:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:54 INFO - 'HOME': '/Users/cltbld', 02:41:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:54 INFO - 'LOGNAME': 'cltbld', 02:41:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:54 INFO - 'MOZ_NO_REMOTE': '1', 02:41:54 INFO - 'NO_EM_RESTART': '1', 02:41:54 INFO - 'PAGER': '/bin/cat', 02:41:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:54 INFO - 'PWD': '/builds/slave/test', 02:41:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:54 INFO - 'SHELL': '/bin/bash', 02:41:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HSew9nYMME/Listeners', 02:41:54 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 02:41:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:54 INFO - 'USER': 'cltbld', 02:41:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:54 INFO - 'XPC_FLAGS': '0x0', 02:41:54 INFO - 'XPC_SERVICE_NAME': '0', 02:41:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:41:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:41:54 INFO - Cleaning up... 02:41:54 INFO - Return code: 0 02:41:54 INFO - Running post-action listener: _resource_record_post_action 02:41:54 INFO - Running post-action listener: _start_resource_monitoring 02:41:54 INFO - Starting resource monitoring. 02:41:54 INFO - [mozharness: 2016-12-26 10:41:54.938400Z] Finished create-virtualenv step (success) 02:41:54 INFO - [mozharness: 2016-12-26 10:41:54.939379Z] Running install step. 02:41:54 INFO - Running pre-action listener: _resource_record_pre_action 02:41:54 INFO - Running main action method: install 02:41:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:41:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:41:55 INFO - Reading from file tmpfile_stdout 02:41:55 INFO - Detecting whether we're running mozinstall >=1.0... 02:41:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:41:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:41:55 INFO - Reading from file tmpfile_stdout 02:41:55 INFO - Output received: 02:41:55 INFO - Usage: mozinstall [options] installer 02:41:55 INFO - Options: 02:41:55 INFO - -h, --help show this help message and exit 02:41:55 INFO - -d DEST, --destination=DEST 02:41:55 INFO - Directory to install application into. [default: 02:41:55 INFO - "/builds/slave/test"] 02:41:55 INFO - --app=APP Application being installed. [default: firefox] 02:41:55 INFO - mkdir: /builds/slave/test/build/application 02:41:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:41:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:42:26 INFO - Reading from file tmpfile_stdout 02:42:26 INFO - Output received: 02:42:26 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:42:26 INFO - Running post-action listener: _resource_record_post_action 02:42:26 INFO - [mozharness: 2016-12-26 10:42:26.352368Z] Finished install step (success) 02:42:26 INFO - [mozharness: 2016-12-26 10:42:26.352542Z] Running run-tests step. 02:42:26 INFO - Running pre-action listener: _resource_record_pre_action 02:42:26 INFO - Running main action method: run_tests 02:42:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:42:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:42:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:42:26 INFO - 2016-12-26 02:42:26.367 screenresolution[1639:9204] starting screenresolution argv=screenresolution get 02:42:26 INFO - 2016-12-26 02:42:26.369 screenresolution[1639:9204] Display 0: 1600x1200x32@60 02:42:26 INFO - 2016-12-26 02:42:26.375 screenresolution[1640:9206] starting screenresolution argv=screenresolution list 02:42:26 INFO - Available Modes on Display 0 02:42:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:42:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:42:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:42:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:42:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:42:26 INFO - Intel Iris: 02:42:26 INFO - Chipset Model: Intel Iris 02:42:26 INFO - Type: GPU 02:42:26 INFO - Bus: Built-In 02:42:26 INFO - VRAM (Dynamic, Max): 1536 MB 02:42:26 INFO - Vendor: Intel (0x8086) 02:42:26 INFO - Device ID: 0x0a2e 02:42:26 INFO - Revision ID: 0x0009 02:42:26 INFO - Displays: 02:42:26 INFO - Display: 02:42:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:42:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:42:26 INFO - Display Serial Number: 1600x1200 60 02:42:26 INFO - Main Display: Yes 02:42:26 INFO - Mirror: Off 02:42:26 INFO - Online: Yes 02:42:26 INFO - Rotation: Supported 02:42:26 INFO - Return code: 0 02:42:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:42:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:42:26 INFO - Python 2.7.3 02:42:26 INFO - Return code: 0 02:42:26 INFO - grabbing minidump binary from tooltool 02:42:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:42:26 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104fd6d30>, '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 0x104e3cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104fceb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:42:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:42:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:42:26 INFO - Calling ['/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'] with output_timeout 600 02:42:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:42:26 INFO - Return code: 0 02:42:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:42:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:42:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:42:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:42:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:42:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0299', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:42:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0299 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:42:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hMQSqHhNsI/Render', 02:42:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:26 INFO - 'HOME': '/Users/cltbld', 02:42:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:26 INFO - 'LOGNAME': 'cltbld', 02:42:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:42:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:42:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:26 INFO - 'MOZ_NO_REMOTE': '1', 02:42:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:42:26 INFO - 'NO_EM_RESTART': '1', 02:42:26 INFO - 'PAGER': '/bin/cat', 02:42:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:26 INFO - 'PWD': '/builds/slave/test', 02:42:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:42:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:26 INFO - 'SHELL': '/bin/bash', 02:42:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HSew9nYMME/Listeners', 02:42:26 INFO - 'TMPDIR': '/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/', 02:42:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:26 INFO - 'USER': 'cltbld', 02:42:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:26 INFO - 'XPC_FLAGS': '0x0', 02:42:26 INFO - 'XPC_SERVICE_NAME': '0', 02:42:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0299', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:42:26 INFO - mozversion application_buildid: 20161226004003 02:42:26 INFO - mozversion application_changeset: 39582d06756a9ff47993d4097b4fb25f041be406 02:42:26 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:42:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:42:26 INFO - mozversion application_name: Firefox 02:42:26 INFO - mozversion application_remotingname: firefox-dev 02:42:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:42:26 INFO - mozversion application_vendor: Mozilla 02:42:26 INFO - mozversion application_version: 52.0a2 02:42:26 INFO - mozversion platform_buildid: 20161226004003 02:42:26 INFO - mozversion platform_changeset: 39582d06756a9ff47993d4097b4fb25f041be406 02:42:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:42:26 INFO - mozversion platform_version: 52.0a2 02:42:26 INFO - using testdate: 1482748946 02:42:26 INFO - actual date: 1482748946 02:42:26 INFO - starting webserver on 'localhost:49250' 02:42:26 INFO - SUITE-START | Running 2 tests 02:42:26 INFO - TEST-START | dromaeo_css 02:42:26 INFO - operating with platform_type : mac_ 02:42:26 INFO - Initialising browser for dromaeo_css test... 02:42:27 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpSbseZm/profile http://localhost:49250/getInfo.html) 02:42:29 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 02:42:29 INFO - PROCESS | 1650 | colorDepth:24 02:42:29 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 02:42:29 INFO - PROCESS | 1650 | __metrics 02:42:29 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:42:29 INFO - PROCESS | 1650 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:42:30 INFO - TEST-INFO | 1650: exit 0 02:42:30 INFO - Browser initialized. 02:42:30 INFO - Running cycle 1/1 for dromaeo_css test... 02:42:30 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpSbseZm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:42:43 INFO - PROCESS | 1654 | RSS: Main: 222539776 02:42:43 INFO - PROCESS | 1654 | 02:43:34 INFO - PROCESS | 1654 | 1482749014274 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:45:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49250/tests/dromaeo/cssquery-ext.html) 02:45:50 INFO - PROCESS | 1654 | RSS: Main: 423596032 02:45:50 INFO - PROCESS | 1654 | 02:48:53 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-ext.html (next: http://localhost:49250/tests/dromaeo/cssquery-jquery.html) 02:48:53 INFO - PROCESS | 1654 | RSS: Main: 348704768 02:48:53 INFO - PROCESS | 1654 | 02:51:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49250/tests/dromaeo/cssquery-mootools.html) 02:51:56 INFO - PROCESS | 1654 | RSS: Main: 317861888 02:51:56 INFO - PROCESS | 1654 | 02:54:59 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49250/tests/dromaeo/cssquery-prototype.html) 02:54:59 INFO - PROCESS | 1654 | RSS: Main: 316690432 02:54:59 INFO - PROCESS | 1654 | 02:58:03 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49250/tests/dromaeo/cssquery-yui.html) 02:58:03 INFO - PROCESS | 1654 | RSS: Main: 307339264 02:58:03 INFO - PROCESS | 1654 | 03:01:06 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-yui.html (next: http://localhost:49250/tests/dromaeo/cssquery-dojo.html) 03:01:06 INFO - PROCESS | 1654 | RSS: Main: 391577600 03:01:06 INFO - PROCESS | 1654 | 03:01:06 INFO - PROCESS | 1654 | __start_tp_report 03:01:06 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 03:01:06 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 03:01:06 INFO - PROCESS | 1654 | |i|pagename|runs| 03:01:06 INFO - PROCESS | 1654 | |0;dojo.html;3340.014695664779;3358.4290670585983;3366.208940898889;3375.324935012995;3420.777649452785;5608.859778505529;5642.746076426561;5751.4536119068625;5758.395368486312;5764.423557644233;5625.634333768302;5704.828855134353;5716.742746576404;5719.799807006755;5720.399358067405;5192.40287366952;5193.246979188034;5201.453847346031;5213.504717051874;5257.395399529046;4640.2807564827335;5144.4598317176715;5156.097682905476;5165.638405311611;5269.736513174345;4315.395844581761;5262.236975638531;5278.392984806739;5280.102382594949;5282.23407605897;5250.894982100355;5259.342582177228;5261.105612045962;5264.15773476242;5275.076861549213;5100.234964755279;5204.141316682781;5221.582273418117;5225.190095535198;5252.842414727565;5730.799422020251;5732.828015159552;5733.770649174028;5745.6265342753;5749.1663708762;6116.640127388518;6121.727351053096;6122.540809439265;6125.398724082952;6125.662799505643;6213.068039794022;6217.751289948396;6246.593971391845;6270.404311590391;6281.403266689657;5739.713014349308;5741.8564535886935;5750.884982300372;5775.220345253421;5790.449907258803;5991.921454138297;6152.230971128609;6213.65824879636;6319.462845658079;6332.176817013758;5899.734511946973;6035.788747393908;6143.788236234952;6144.2319710036245;6151.477124444383;6166.260048794084;6255.468285195719;6260.998240281533;6262.3111457739615;6269.059641053787;5992.8801423972145;6045.637261764308;6182.412670796267;6190.071489276645;6202.596831205957;6103.206583144163;6130.979101202072;6188.597741146811;6201.069839524108;6239.157713708682;4904.313396124546;4905.558499735041;4911.386076740408;4916.287138364917;4942.258661200849;4329.09089091288;4435.334699794992;4435.445569303837;4450.020995381075;4458.08609235106;5596.132599447093;5651.660900345993;5655.604107712421;5666;5717.113847353667;6245.375462453719;6264.749410023639;6270.811875643647;6292.370762923671;6292.779752708633;5681.971590142023;5747.453991870766;5756.222909907193;5763.049096899049;5802.187693722798;5249.50129737682;5259.184826351921;5297.54970827484;5354.571634269249;5360.946390536123;5239.423663396947;6234.9688251558455;6281.71732272058;6287.905681414874;6344.936550634435;6212.409821066992;6245.5628106032145;6256.249250090018;6264.968675156595;6274.498040156868;6254.999200127958;6310.968445157745;6320.873582528416;6326.4622507087415;6357.14178585885;5921.2006379138265;6001.3698561652145;6010.248718910137;6041.78853740117;6118.20463339763;6158.04550294705;6162.661053642093;6185.103160041679;6204.906926396198;6216.875662486816;5131.461196574306;5991.700414979321;6064.757409703562;6097.176881120992;6126.326104128631;5844.503217226414;6038.547108966933;6048.788292409745;6050.848728781815;6060.393960603904;5715.742791574306;5770.480656740913;5772.5381969441505;5907.409259074058;5913.58604897653;5949.940500594938;5956.970215148896;6006.189164462741;6033.276006879202;6039.033754599243;5837.445442682938;5872.236609240771;5900;5914.4381283777975;5939.970300148471 03:01:06 INFO - PROCESS | 1654 | |1;ext.html;506.74662668665667;519.5947161214253;526.8540923491405;534.9679019258847;539.9460053994601;29078.273043173933;30658.693413065892;31664;31918;32959.67040329596;39996.80001599995;40208.39687404684;40315.19369612606;40340.79829600848;40404.79797601015;7094.21963584008;9184.540772961342;9335.64633128196;9337.906620933773;9347.392419492711;7886.250806173433;10999.835002474929;11249.662510124668;11266.155038372113;11368.31790092593;11097.3896435696;11137.832932506019;11183;11197.944010279938;11236.269642473213;11177.273477223995;11236.438178091063;11253.324800511995;11255.60605378816;11258;11084.002439780377;11176;11180.832287515694;11184.832227516594;11194.832077518844;37728.62271377306;38010.42984355237;38069.04827379338;38073.61926380728;38133.0466738331;3256.788308759923;3318.8340582970995;3656.177360093984;3711.406175011985;3882.068303607114;26622.86688566564;27568.586471202947;27576.724232757617;27594.172174834588;28000.739966701345;3127.2807254331406;3164;3183.936321273585;3276.8689252429876;3285.4086264472385;374730.12634936924;451795;452505;455567;455705.72147139383;53505.7324713374;53875;53992.190117148275;54079.45920540823;62714.372856271766;50359.24461133086;64575.03137452942;67200;67279.32720672828;67399.66300168567;15686.980346277684;15878.52364429069;15949.760753588705;15951.202439878192;15973.440929567409;9203;11123.777524449628;11136.220464567565;12051.879481205076;12266.81599776004;16470.588235294214;19400.805991940182;19622.215111395384;19694.803051969582;20166.798332016784;6746.4265537429865;7919.564423956623;8123.512589244664;8123.79690507742;8153.779257193375;842.6460886427715;878.7759121423996;930.9813803723888;1020.5305559442739;1043.4991204222015;28143.859280703466;28529.857350713115;28745.137645870658;29382.265443364086;29383.971560995262;1023.9283250172416;1033.2508931025097;1041.9944753313089;1119.2445099557765;1143.188336281233;14611.853881461262;15045.473408430871;15131.319090640947;15292.235388230543;15335.233238338262;677584;679677;680884;681437;683468;53996.73001634967;54524;54527;54576.63558410992;54683.17217794716;1227.7974134267745;1232.1805999010737;1265.2661456355293;1324.635725175546;1362.768329384007;28731;28833.70248338788;29293.26766830846;29299.7070029297;29998.55002175013;41687.957801055214;41879;42001;42059.579404205564;42070.78964605158;558047;558217;559299;559916;562993;25596;26225.868870655526;26349.473010540067;26442.86778566095;26645.866770666024;34260.65739342674;43132.137357253305;43318.917027073316;43765.56234437615;44072.779636101615;35847.46228806622;36165.63834361623;38771.030724230994;39442.21115577615;40139.799301004474;35899.64100358963;36526.817365913;36590.26819463649;36861;36862;34591.827040864635;35850.820745896104;36458.8177059113;36569.26861462809;36810.447843281836;31517.842410787798;32547.674523255413;33161.005169844946;33303.33393332168;33342.33315333631;24333.878330608233;24627.507449851262;24957.625635615845;24992.559080897616;25009 03:01:06 INFO - PROCESS | 1654 | |2;jquery.html;354.0811593913793;367.5460805904707;369.6285233340491;381.29841092390006;398.7288643722268;14296.571102866917;14988.5503434897;19516.41450756476;20067.89626570538;20528.692069618966;11526.654200373981;11785.941070294637;15498.690026199498;19847.30534431295;20053.29813456529;4302.741835489881;4414.22751018572;5252.6323157379;5376.569874410057;5443.972780136094;5306.310179676637;5402.108652072423;5411.594130440233;5413.512783849453;5422.05114105032;5135.512126347991;5339.919901201485;5387.649802762828;5412.9458705413035;5425.077736784737;5367.812126575571;5377.865553361177;5394.838154855361;5396.163594642837;5410.08028635133;5008.24876268559;5171.741412929376;5234.424213336568;5234.581233501311;5248.947510524884;13076.476940922352;13181.275029873354;13202.471901123943;15434.691306173814;25121.11591630635;2125.521757604542;2212.9114835406563;2236.888155592214;2472.5178590174924;2746.917592472229;10023.14803241718;10248.590056397736;10425.270231083827;10467.267291289612;12377.443015064344;1824.4617837737899;1824.5894673698444;1862.366795289608;1864.3008871673123;1879.6710575649206;113618.43190784076;115182;117315;118419;120391.39804300922;11464.885351146382;11545.134114941413;11689.824652630217;11722.238054526599;11879.584214552622;12787.552435664707;13470;13530.120542164728;13538.25539595312;14006.71986560263;6728.629925354055;8232.259096681328;8466.576671166416;8518.957405212934;8564.914350856536;8106.229908158715;8379.639259501411;8414.990201175775;8557.743267701979;8569.228769410784;4497.820087196541;4538.069695712371;4538.818447262139;4548.317752337176;4559.430071241095;1600.415848215393;1842.5209445543987;1891.6216756648726;1897.9940631465345;1898.402003368962;871.4945331707594;879.3273146043282;926.5645146781002;938.1931538876561;944.7449188719007;20398.592028159354;20907.37277881666;20976.160953561688;21009.78990210109;21076.367708968755;904.1410659873015;941.091846368258;949.6343907595625;966.5940305071887;970.5001924009141;8248.628811703487;9075.636974521078;11209.887901120885;11293.548258069584;11309.208355415041;605961.9701901462;614546;619018;620466;623091;106655.4667226659;124313.75686243203;127569;127757;132526.33736831447;935.2471260635118;935.2611436964858;968.138356862393;969.9127078562963;970.7427531704104;18338.63322733511;18484.075796210403;18492.815071849647;18728.906355468134;18914.905425472785;44452.55547444613;51337.45987620377;52010;54873.725631371584;55934.44065559292;74118;76202;79754.20245797468;80888.59555702184;81184.78222826544;6720;7633.656485458278;7915;7954.443188976716;8386.622601982803;6790.388865002174;9081.182693557612;9587.952060239653;10862.945685271523;12236.44935977901;10915.494709402776;10986.945065274622;11099.945505176995;11144.108471322437;11173.385463799568;9963.352382095141;10874.619388321371;10979.560817567528;11103.278286911325;11135.220534562503;7940.444168908121;10860.945695271474;10888.183386245904;11252.212345135762;11815.940920295687;6943.270956549692;7312.890306645511;7405.5926924019695;7946.642401091849;8132.878006829784;9851.802963940538;10358.533865975898;10742.8388574173;10892.564297428014;10932.125429965425 03:01:06 INFO - PROCESS | 1654 | |3;mootools.html;1294.5274974634262;1437.9065360751551;1482.3403585404492;1612.7016501947141;1818.3453956575631;7434.405247580195;7758.534487930728;7878.369730421567;8220.136885627013;8222.794430139249;8099.635516401761;8108.878366824488;8186.181381861826;8187.017557893047;8201.425900186974;2218.955620887585;2256.7968882800546;2263.411513006622;2268.4442311633675;2269.580127676377;2232.9888350558226;2269.00163927872;2289.553537060274;2293.3693234360603;2293.8279629027807;2286.542691461706;2293.6444851048113;2321.442853715105;2324.790768830805;2326.581215381231;2265.161890100657;2276.590213761522;2304.5045315257134;2308.203669733939;2308.953820923572;2265.2524666859895;2335.2760644200184;2335.8131349492;2337.625979843234;2341.555104530134;8104.311133553655;8174.713885013996;8244.216799404046;8249.958750206211;8261.421700480969;4460.8884777880485;5021.271915572258;5196.92204616931;5204.661696989683;5235.162374020177;3307.3219989902254;3451.361498122855;3530.1880567469625;3546.4680297955256;3550.289942011608;1067.1729409707461;1083.6586475260267;1683.5538582275706;1709.0173150438422;1795.416489640872;10775.784484310348;10867.29362591437;10877.347359158475;10891.128709703205;10905.618303359424;4287.656987440997;4471.642268618502;4504.2793153095345;4513.368128462018;4514.277715565494;8606.139386061344;8635;8650.567471626393;8652.610632521551;8660.696875609323;5065.645404821627;5073.543381095719;5081.720505372166;5082.466341034212;5085.186370180753;4031.254217969685;4220.155968806251;4409.250427427345;4684.929726054112;4762.357081793983;3649.671529562352;3652.196516766307;3737.0657335666083;3748.962510374916;3773.339665558516;1117.7261570915177;1126.5775334249656;1136.363636363639;1153.1837356164076;1153.5789436855487;778.5640041576735;859.909709480508;897.5146133149517;906.1799071839995;924.8242833861544;5567.805126820542;5574.804881829116;5735.340435849847;5851.38560451155;5851.736671849777;917.3899356927702;917.7934964632905;919.1957037592107;926.4996901673021;954.341504361988;3719.9442008369897;3768.5666148393284;3779.508663873734;3808.6953043756434;3891.3384724596885;9717.319787614799;9904.900950990397;10024;10081.243906707026;10084.596616135272;10025.899741002497;10031.297809153435;10038.799224015478;10048.196144308438;10107.595696172219;7410.555366677909;7569.545827250383;7699.153093159748;7706.422018348534;7743.070831500256;5505.862353441196;5599.580031497735;5823.039198532195;5853.619514731528;5878;9052.864207037032;9162;9371.718848434557;9378.249740020918;9492.667756628485;7441.218672039358;8513.276371508491;8874.378793484653;9529;9649.986751391003;6957.512974091765;6982.1272340957385;7053.717851285891;7191.712331506681;7209.423246140192;7727.49771264866;7782.610869456391;7789.922100779147;7801.2198780121535;7848.960755196187;6918.688659010458;7043.577385356895;7074.71701131949;7113.928860711327;7184.066071410683;6906.516543841883;6966.686499107451;6968.895466568107;7001.789946301619;7025.297470252934;6515.609063456208;6559.180102487189;6644.568103073478;6653.900191497035;6669.799906002828;4823.614110871052;4853.466118726867;4854.417469903634;4900.509949005071;4907.705537667752;4108.404281379243;4761.452432970253;4829.2997515359375;4993.8751531210555;5097.311862898461 03:01:06 INFO - PROCESS | 1654 | |4;prototype.html;298.95665128556357;299.1176030709408;307.85992373470066;312.51716098627617;313.7568384502012;4884.926726099107;5259.395169555502;5322.973385133069;5337.5196232339085;5377.166539186423;4393.8022788974495;4525.660575456837;4793.849476125731;5110.386753589574;5119.974400127994;3215.8713651453913;3773.471743943707;4029.798510074492;4065.5934406559254;4066.373450619746;4039.4950631171105;4039.919201615966;4051.7771522566245;4078.877633670995;4096.672266218696;3583.713302935772;3750.5311836020496;3902.2780785554623;4021.5120405450025;4058.15354013923;4023.6982226333143;4025.0076212274816;4028.879133625996;4048.793407592869;4052.716309858311;3507.368673638744;3523.8547472071677;3545.6808887200277;3545.8758943436856;3547.609762926076;3954.5056867891512;4218.578142185787;4250.001249706316;4734.928976065327;5094.93006468642;3447.9311413461655;4073.3889916512762;4597.632189424838;4604.769761511911;4614.653900957408;4175.081482073939;4594.495999680183;5042.327566389819;5227.032998895216;5349;3496.930061398758;3704.0924973381693;3886.164474637938;3887.5334959804723;3914.0214946263436;5385.06137790399;5475.342958844884;5735.483806457397;5749.511291540182;5849;3350.715189208896;3435.4331535296396;3473.235888104613;3797.1266608680157;3813.523309586302;3429.34842379952;3813.427985802152;3902.6097390261316;3912.315344814646;3927.6072392760493;4010.959890401117;4233.428487154257;4409.559044095565;4498.572635599613;4586.922073312769;4915.262710593368;5137.458762371273;5461.231750092424;5553.805616803474;5601.663900166003;980.5293459139652;1156.9645167574972;1251.2054845173197;1260.3131293445094;1263.2483672215017;727.5016613619582;759.6391713935947;761.9771406857803;818.0306336990685;860.492309537376;4040.7575545467366;4877.707337559757;4880.829170978998;4937.703737775745;5010.519792702636;4709.646776491777;4724.6692731508465;5410.97294513525;5463.69949652765;5464.18037294409;4177.660510114346;4631.980964187874;4717.655468191549;4880.292357608198;4945.134601444733;2411.63825426184;2425.1754403502896;2696.7572918437436;2771.7155171122286;2847.928801779931;4820.325154478376;5228.980348831941;5280.947190528045;5591.41290164535;5608.663480191201;5007.749612519359;5323.3514471807675;5502.862428439241;5509.917351239735;5515.7242137892945;5390;5509.917351239735;5528.419515950767;5533.446655334515;5583.050881350099;4209.684273679425;4417.867463976086;4440.511543730252;5124.718140502159;5381.3542374913695;4121.793910304533;4304.548022457722;4744.8375148088935;5005.824796132118;5258.789648414021;4906.663468841222;5397.703126327932;5523.585731070106;5559.861003474945;5562.471565201299;3349.4640857463664;3588.5155504006625;3606.549181352331;3609.141292234449;3622.4023036198732;5112.514311140436;5296.258523806747;5322.41453440129;5335.172358649922;5396.946030539644;4602.516735742699;4634.86095417138;5143.665661731976;5218.608604354613;5220.269162317202;4820.855374338776;4948.655452111782;5114.33513643224;5186.533212010786;5197.038547868657;4036.7486079315504;4119.402686610485;4796.712197268175;5054.444011158696;5094.617903657167;4409.007973206131;5218.921716174336;5377.731113444234;5547.5007249347755;5552.805651802168;4512.593866552026;5318.946810531846;5390.19147127934;5436.836894893159;5441.401445841034 03:01:06 INFO - PROCESS | 1654 | |5;yui.html;689.9689513971871;691.8062942376133;717.5766297884247;740.7148247924547;762.5196126440342;13799.379027943742;14335.354909029093;14347.497837575687;14360.928195359022;14365.928170359146;12833.743325133515;12881.613551593467;12896.548620798274;12953.28756918369;12973.286469244187;646.2309851276977;669.3172963577149;675.1695414639971;675.9155105611799;679.5990365684245;674.6795272245693;799.6801279488193;833.5082301442147;837.3217693668117;840.5839109640737;727.5634619228473;884.0584777212281;884.1247165306364;884.933629977751;885.9069797671249;875.5053394831899;877.0965905117771;879.5734068976541;879.736079176251;880.053942012336;512.3416409913281;535.0609680011594;626.1515646299245;657.1293036726356;658.9044588534636;13431;13635.795463068062;13940.721185576233;13958.302084895715;13982.230977296245;12159.452824622913;12342.382880856012;12447.253164810141;12519.56181533642;12637.49450021998;11946.940265298705;12059.33673647949;12241.755164896653;12243.265404075784;12251.081168912278;4246.256905041636;4255.319148936186;4302.311630139163;4472.776361181928;4484.035932274544;87960;90482;92927;94095;96434.55348169783;10380.32527885685;10488.056074953138;10676.679699609023;10683.57265709363;10811.18916081297;20487.8975605121;20499.11495599693;20580.27969021077;21014.78985210159;21034.684479732816;7495;7571.432142589328;7596.696132154762;7631.885521717068;7661.578613176329;4151.750894946313;4236.724612900151;4274.252005898955;4385.188740083095;4415.933760993587;3262.102921696505;3376.696097351201;3381.8816341427932;3385.6783605557434;3394.032700680294;648.7210499485267;682.7269092363094;686.5949090036902;691.550492180077;692.7219280427603;546.8769526856426;552.4061633743742;658.8968439436499;661.7286912365906;668.729164688302;8678.956605216934;8959.5968181432;8994.595243214071;9108.772280692903;9260.027697091844;633.1515768869644;635.5932203389834;645.554567348528;668.6222284409293;670.6244503078292;3423;4268.359746038119;4364.8472303469225;4407.845725399659;4482.641388688897;102030;105001;113927;118095;118691;95557;98139.50930245446;98306.50846745864;99128.50435747775;99435.50282248687;88051.55974220217;91264;91514.08485915055;92087.53956230175;93557;8458.154184581492;8605.053444121268;8610.483370997761;8652.264557512683;8804.47173169612;49535.00929981452;51357.48642513676;51797.223041653655;54977.72511137419;55545.72227138838;54533;71191;71842.64078679783;76456.61771691106;76489.61755191411;12025.281725622524;12119.001823495828;12324.691882703004;12528.18566793192;12649.557265495663;13699.794503082661;13731.519396821062;13770.518031868836;14107.647308817363;14140.222287774272;12717.236965782082;12902.741945161231;12951.935240323737;12984.805227921399;13174.670633233862;12246.938765306117;12680.74638507243;12757.872421275668;13071.085024048225;13570.86429135696;12234.75530489403;12381.938090309492;12531.18547294423;12577.993760499321;13098;10731.89268107309;10801.837972430263;10926.890731092588;11049.060829829554;11232.55069797199;10909.563617455531;11161.553537858395;11248.10450261156;11310.208285419942;11383.886161138282 03:01:06 INFO - PROCESS | 1654 | __end_tp_report 03:01:06 INFO - PROCESS | 1654 | __start_cc_report 03:01:06 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,2538 03:01:06 INFO - PROCESS | 1654 | __end_cc_report 03:01:06 INFO - PROCESS | 1654 | __startTimestamp1482750066901__endTimestamp 03:01:06 INFO - PROCESS | 1654 | 03:01:06 INFO - PROCESS | 1654 | ------- Summary: start ------- 03:01:06 INFO - PROCESS | 1654 | Number of tests: 6 03:01:06 INFO - PROCESS | 1654 | 03:01:06 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5698.66 Median:5872.24 stddev:634.37 (10.8%) stddev-sans-first:608.73 03:01:06 INFO - PROCESS | 1654 | Values: 3340.0 3358.4 3366.2 3375.3 3420.8 5608.9 5642.7 5751.5 5758.4 5764.4 5625.6 5704.8 5716.7 5719.8 5720.4 5192.4 5193.2 5201.5 5213.5 5257.4 4640.3 5144.5 5156.1 5165.6 5269.7 4315.4 5262.2 5278.4 5280.1 5282.2 5250.9 5259.3 5261.1 5264.2 5275.1 5100.2 5204.1 5221.6 5225.2 5252.8 5730.8 5732.8 5733.8 5745.6 5749.2 6116.6 6121.7 6122.5 6125.4 6125.7 6213.1 6217.8 6246.6 6270.4 6281.4 5739.7 5741.9 5750.9 5775.2 5790.4 5991.9 6152.2 6213.7 6319.5 6332.2 5899.7 6035.8 6143.8 6144.2 6151.5 6166.3 6255.5 6261.0 6262.3 6269.1 5992.9 6045.6 6182.4 6190.1 6202.6 6103.2 6131.0 6188.6 6201.1 6239.2 4904.3 4905.6 4911.4 4916.3 4942.3 4329.1 4435.3 4435.4 4450.0 4458.1 5596.1 5651.7 5655.6 5666.0 5717.1 6245.4 6264.7 6270.8 6292.4 6292.8 5682.0 5747.5 5756.2 5763.0 5802.2 5249.5 5259.2 5297.5 5354.6 5360.9 5239.4 6235.0 6281.7 6287.9 6344.9 6212.4 6245.6 6256.2 6265.0 6274.5 6255.0 6311.0 6320.9 6326.5 6357.1 5921.2 6001.4 6010.2 6041.8 6118.2 6158.0 6162.7 6185.1 6204.9 6216.9 5131.5 5991.7 6064.8 6097.2 6126.3 5844.5 6038.5 6048.8 6050.8 6060.4 5715.7 5770.5 5772.5 5907.4 5913.6 5949.9 5957.0 6006.2 6033.3 6039.0 5837.4 5872.2 5900.0 5914.4 5940.0 03:01:06 INFO - PROCESS | 1654 | 03:01:06 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:68266.83 Median:33322.83 stddev:152250.20 (456.9%) stddev-sans-first:152592.30 03:01:06 INFO - PROCESS | 1654 | Values: 506.7 519.6 526.9 535.0 539.9 29078.3 30658.7 31664.0 31918.0 32959.7 39996.8 40208.4 40315.2 40340.8 40404.8 7094.2 9184.5 9335.6 9337.9 9347.4 7886.3 10999.8 11249.7 11266.2 11368.3 11097.4 11137.8 11183.0 11197.9 11236.3 11177.3 11236.4 11253.3 11255.6 11258.0 11084.0 11176.0 11180.8 11184.8 11194.8 37728.6 38010.4 38069.0 38073.6 38133.0 3256.8 3318.8 3656.2 3711.4 3882.1 26622.9 27568.6 27576.7 27594.2 28000.7 3127.3 3164.0 3183.9 3276.9 3285.4 374730.1 451795.0 452505.0 455567.0 455705.7 53505.7 53875.0 53992.2 54079.5 62714.4 50359.2 64575.0 67200.0 67279.3 67399.7 15687.0 15878.5 15949.8 15951.2 15973.4 9203.0 11123.8 11136.2 12051.9 12266.8 16470.6 19400.8 19622.2 19694.8 20166.8 6746.4 7919.6 8123.5 8123.8 8153.8 842.6 878.8 931.0 1020.5 1043.5 28143.9 28529.9 28745.1 29382.3 29384.0 1023.9 1033.3 1042.0 1119.2 1143.2 14611.9 15045.5 15131.3 15292.2 15335.2 677584.0 679677.0 680884.0 681437.0 683468.0 53996.7 54524.0 54527.0 54576.6 54683.2 1227.8 1232.2 1265.3 1324.6 1362.8 28731.0 28833.7 29293.3 29299.7 29998.6 41688.0 41879.0 42001.0 42059.6 42070.8 558047.0 558217.0 559299.0 559916.0 562993.0 25596.0 26225.9 26349.5 26442.9 26645.9 34260.7 43132.1 43318.9 43765.6 44072.8 35847.5 36165.6 38771.0 39442.2 40139.8 35899.6 36526.8 36590.3 36861.0 36862.0 34591.8 35850.8 36458.8 36569.3 36810.4 31517.8 32547.7 33161.0 33303.3 33342.3 24333.9 24627.5 24957.6 24992.6 25009.0 03:01:06 INFO - PROCESS | 1654 | 03:01:06 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:34643.40 Median:390.01 stddev:102777.33 (26352.2%) stddev-sans-first:103033.40 03:01:06 INFO - PROCESS | 1654 | Values: 354.1 367.5 369.6 381.3 398.7 14296.6 14988.6 19516.4 20067.9 20528.7 11526.7 11785.9 15498.7 19847.3 20053.3 4302.7 4414.2 5252.6 5376.6 5444.0 5306.3 5402.1 5411.6 5413.5 5422.1 5135.5 5339.9 5387.6 5412.9 5425.1 5367.8 5377.9 5394.8 5396.2 5410.1 5008.2 5171.7 5234.4 5234.6 5248.9 13076.5 13181.3 13202.5 15434.7 25121.1 2125.5 2212.9 2236.9 2472.5 2746.9 10023.1 10248.6 10425.3 10467.3 12377.4 1824.5 1824.6 1862.4 1864.3 1879.7 113618.4 115182.0 117315.0 118419.0 120391.4 11464.9 11545.1 11689.8 11722.2 11879.6 12787.6 13470.0 13530.1 13538.3 14006.7 6728.6 8232.3 8466.6 8519.0 8564.9 8106.2 8379.6 8415.0 8557.7 8569.2 4497.8 4538.1 4538.8 4548.3 4559.4 1600.4 1842.5 1891.6 1898.0 1898.4 871.5 879.3 926.6 938.2 944.7 20398.6 20907.4 20976.2 21009.8 21076.4 904.1 941.1 949.6 966.6 970.5 8248.6 9075.6 11209.9 11293.5 11309.2 605962.0 614546.0 619018.0 620466.0 623091.0 106655.5 124313.8 127569.0 127757.0 132526.3 935.2 935.3 968.1 969.9 970.7 18338.6 18484.1 18492.8 18728.9 18914.9 44452.6 51337.5 52010.0 54873.7 55934.4 74118.0 76202.0 79754.2 80888.6 81184.8 6720.0 7633.7 7915.0 7954.4 8386.6 6790.4 9081.2 9588.0 10862.9 12236.4 10915.5 10986.9 11099.9 11144.1 11173.4 9963.4 10874.6 10979.6 11103.3 11135.2 7940.4 10860.9 10888.2 11252.2 11815.9 6943.3 7312.9 7405.6 7946.6 8132.9 9851.8 10358.5 10742.8 10892.6 10932.1 03:01:06 INFO - PROCESS | 1654 | 03:01:06 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5373.66 Median:5077.63 stddev:2905.78 (57.2%) stddev-sans-first:2897.76 03:01:06 INFO - PROCESS | 1654 | Values: 1294.5 1437.9 1482.3 1612.7 1818.3 7434.4 7758.5 7878.4 8220.1 8222.8 8099.6 8108.9 8186.2 8187.0 8201.4 2219.0 2256.8 2263.4 2268.4 2269.6 2233.0 2269.0 2289.6 2293.4 2293.8 2286.5 2293.6 2321.4 2324.8 2326.6 2265.2 2276.6 2304.5 2308.2 2309.0 2265.3 2335.3 2335.8 2337.6 2341.6 8104.3 8174.7 8244.2 8250.0 8261.4 4460.9 5021.3 5196.9 5204.7 5235.2 3307.3 3451.4 3530.2 3546.5 3550.3 1067.2 1083.7 1683.6 1709.0 1795.4 10775.8 10867.3 10877.3 10891.1 10905.6 4287.7 4471.6 4504.3 4513.4 4514.3 8606.1 8635.0 8650.6 8652.6 8660.7 5065.6 5073.5 5081.7 5082.5 5085.2 4031.3 4220.2 4409.3 4684.9 4762.4 3649.7 3652.2 3737.1 3749.0 3773.3 1117.7 1126.6 1136.4 1153.2 1153.6 778.6 859.9 897.5 906.2 924.8 5567.8 5574.8 5735.3 5851.4 5851.7 917.4 917.8 919.2 926.5 954.3 3719.9 3768.6 3779.5 3808.7 3891.3 9717.3 9904.9 10024.0 10081.2 10084.6 10025.9 10031.3 10038.8 10048.2 10107.6 7410.6 7569.5 7699.2 7706.4 7743.1 5505.9 5599.6 5823.0 5853.6 5878.0 9052.9 9162.0 9371.7 9378.2 9492.7 7441.2 8513.3 8874.4 9529.0 9650.0 6957.5 6982.1 7053.7 7191.7 7209.4 7727.5 7782.6 7789.9 7801.2 7849.0 6918.7 7043.6 7074.7 7113.9 7184.1 6906.5 6966.7 6968.9 7001.8 7025.3 6515.6 6559.2 6644.6 6653.9 6669.8 4823.6 4853.5 4854.4 4900.5 4907.7 4108.4 4761.5 4829.3 4993.9 5097.3 03:01:06 INFO - PROCESS | 1654 | 03:01:06 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4272.24 Median:4713.65 stddev:1299.90 (27.6%) stddev-sans-first:1268.88 03:01:06 INFO - PROCESS | 1654 | Values: 299.0 299.1 307.9 312.5 313.8 4884.9 5259.4 5323.0 5337.5 5377.2 4393.8 4525.7 4793.8 5110.4 5120.0 3215.9 3773.5 4029.8 4065.6 4066.4 4039.5 4039.9 4051.8 4078.9 4096.7 3583.7 3750.5 3902.3 4021.5 4058.2 4023.7 4025.0 4028.9 4048.8 4052.7 3507.4 3523.9 3545.7 3545.9 3547.6 3954.5 4218.6 4250.0 4734.9 5094.9 3447.9 4073.4 4597.6 4604.8 4614.7 4175.1 4594.5 5042.3 5227.0 5349.0 3496.9 3704.1 3886.2 3887.5 3914.0 5385.1 5475.3 5735.5 5749.5 5849.0 3350.7 3435.4 3473.2 3797.1 3813.5 3429.3 3813.4 3902.6 3912.3 3927.6 4011.0 4233.4 4409.6 4498.6 4586.9 4915.3 5137.5 5461.2 5553.8 5601.7 980.5 1157.0 1251.2 1260.3 1263.2 727.5 759.6 762.0 818.0 860.5 4040.8 4877.7 4880.8 4937.7 5010.5 4709.6 4724.7 5411.0 5463.7 5464.2 4177.7 4632.0 4717.7 4880.3 4945.1 2411.6 2425.2 2696.8 2771.7 2847.9 4820.3 5229.0 5280.9 5591.4 5608.7 5007.7 5323.4 5502.9 5509.9 5515.7 5390.0 5509.9 5528.4 5533.4 5583.1 4209.7 4417.9 4440.5 5124.7 5381.4 4121.8 4304.5 4744.8 5005.8 5258.8 4906.7 5397.7 5523.6 5559.9 5562.5 3349.5 3588.5 3606.5 3609.1 3622.4 5112.5 5296.3 5322.4 5335.2 5396.9 4602.5 4634.9 5143.7 5218.6 5220.3 4820.9 4948.7 5114.3 5186.5 5197.0 4036.7 4119.4 4796.7 5054.4 5094.6 4409.0 5218.9 5377.7 5547.5 5552.8 4512.6 5318.9 5390.2 5436.8 5441.4 03:01:06 INFO - PROCESS | 1654 | 03:01:06 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:20765.18 Median:4411.89 stddev:30818.22 (698.5%) stddev-sans-first:30867.81 03:01:06 INFO - PROCESS | 1654 | Values: 690.0 691.8 717.6 740.7 762.5 13799.4 14335.4 14347.5 14360.9 14365.9 12833.7 12881.6 12896.5 12953.3 12973.3 646.2 669.3 675.2 675.9 679.6 674.7 799.7 833.5 837.3 840.6 727.6 884.1 884.1 884.9 885.9 875.5 877.1 879.6 879.7 880.1 512.3 535.1 626.2 657.1 658.9 13431.0 13635.8 13940.7 13958.3 13982.2 12159.5 12342.4 12447.3 12519.6 12637.5 11946.9 12059.3 12241.8 12243.3 12251.1 4246.3 4255.3 4302.3 4472.8 4484.0 87960.0 90482.0 92927.0 94095.0 96434.6 10380.3 10488.1 10676.7 10683.6 10811.2 20487.9 20499.1 20580.3 21014.8 21034.7 7495.0 7571.4 7596.7 7631.9 7661.6 4151.8 4236.7 4274.3 4385.2 4415.9 3262.1 3376.7 3381.9 3385.7 3394.0 648.7 682.7 686.6 691.6 692.7 546.9 552.4 658.9 661.7 668.7 8679.0 8959.6 8994.6 9108.8 9260.0 633.2 635.6 645.6 668.6 670.6 3423.0 4268.4 4364.8 4407.8 4482.6 102030.0 105001.0 113927.0 118095.0 118691.0 95557.0 98139.5 98306.5 99128.5 99435.5 88051.6 91264.0 91514.1 92087.5 93557.0 8458.2 8605.1 8610.5 8652.3 8804.5 49535.0 51357.5 51797.2 54977.7 55545.7 54533.0 71191.0 71842.6 76456.6 76489.6 12025.3 12119.0 12324.7 12528.2 12649.6 13699.8 13731.5 13770.5 14107.6 14140.2 12717.2 12902.7 12951.9 12984.8 13174.7 12246.9 12680.7 12757.9 13071.1 13570.9 12234.8 12381.9 12531.2 12578.0 13098.0 10731.9 10801.8 10926.9 11049.1 11232.6 10909.6 11161.6 11248.1 11310.2 11383.9 03:01:06 INFO - PROCESS | 1654 | -------- Summary: end -------- 03:01:06 INFO - PROCESS | 1654 | 03:01:07 INFO - TEST-INFO | 1654: exit 0 03:01:07 INFO - TEST-OK | dromaeo_css | took 1120568ms 03:01:07 INFO - TEST-START | kraken 03:01:07 INFO - operating with platform_type : mac_ 03:01:07 INFO - Initialising browser for kraken test... 03:01:07 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpBeniuP/profile http://localhost:49250/getInfo.html) 03:01:10 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 03:01:10 INFO - PROCESS | 1661 | colorDepth:24 03:01:10 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 03:01:10 INFO - PROCESS | 1661 | __metrics 03:01:10 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:01:10 INFO - PROCESS | 1661 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:01:11 INFO - TEST-INFO | 1661: exit 0 03:01:11 INFO - Browser initialized. 03:01:11 INFO - Running cycle 1/1 for kraken test... 03:01:11 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/tmpBeniuP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:01:23 INFO - PROCESS | 1663 | RSS: Main: 222638080 03:01:23 INFO - PROCESS | 1663 | 03:01:26 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection) 03:01:27 INFO - PROCESS | 1663 | RSS: Main: 272097280 03:01:27 INFO - PROCESS | 1663 | 03:01:29 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-dft) 03:01:30 INFO - PROCESS | 1663 | RSS: Main: 282931200 03:01:30 INFO - PROCESS | 1663 | 03:01:33 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-fft) 03:01:34 INFO - PROCESS | 1663 | RSS: Main: 382132224 03:01:34 INFO - PROCESS | 1663 | 03:01:36 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator) 03:01:36 INFO - PROCESS | 1663 | RSS: Main: 283230208 03:01:36 INFO - PROCESS | 1663 | 03:01:38 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:01:39 INFO - PROCESS | 1663 | RSS: Main: 285114368 03:01:39 INFO - PROCESS | 1663 | 03:01:42 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom) 03:01:42 INFO - PROCESS | 1663 | RSS: Main: 325750784 03:01:42 INFO - PROCESS | 1663 | 03:01:45 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate) 03:01:46 INFO - PROCESS | 1663 | RSS: Main: 328019968 03:01:46 INFO - PROCESS | 1663 | 03:01:49 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial) 03:01:49 INFO - PROCESS | 1663 | RSS: Main: 322822144 03:01:49 INFO - PROCESS | 1663 | 03:01:51 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:01:52 INFO - PROCESS | 1663 | RSS: Main: 299257856 03:01:52 INFO - PROCESS | 1663 | 03:01:54 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:01:54 INFO - PROCESS | 1663 | RSS: Main: 300982272 03:01:54 INFO - PROCESS | 1663 | 03:01:56 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:01:57 INFO - PROCESS | 1663 | RSS: Main: 303112192 03:01:57 INFO - PROCESS | 1663 | 03:02:00 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:02:01 INFO - PROCESS | 1663 | RSS: Main: 318951424 03:02:01 INFO - PROCESS | 1663 | 03:02:04 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:02:04 INFO - PROCESS | 1663 | RSS: Main: 312864768 03:02:04 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49250/tests/kraken/driver.html?testName=ai-astar) 03:02:06 INFO - PROCESS | 1663 | RSS: Main: 357318656 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | __start_tp_report 03:02:06 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 03:02:06 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 03:02:06 INFO - PROCESS | 1663 | |i|pagename|runs| 03:02:06 INFO - PROCESS | 1663 | |0;ai-astar;116;116;115;114;114;114;115;114;115;114 03:02:06 INFO - PROCESS | 1663 | |1;audio-beat-detection;144;116;135;178;113;110;109;110;139;109 03:02:06 INFO - PROCESS | 1663 | |2;audio-dft;182;185;184;184;183;181;182;183;185;184 03:02:06 INFO - PROCESS | 1663 | |3;audio-fft;81;60;58;89;63;64;60;58;82;58 03:02:06 INFO - PROCESS | 1663 | |4;audio-oscillator;84;71;94;70;69;69;89;67;69;91 03:02:06 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;88;83;86;84;86;83;87;84;86;83 03:02:06 INFO - PROCESS | 1663 | |6;imaging-darkroom;92;93;93;92;93;93;92;106;149;108 03:02:06 INFO - PROCESS | 1663 | |7;imaging-desaturate;83;81;81;94;124;122;83;83;81;81 03:02:06 INFO - PROCESS | 1663 | |8;json-parse-financial;57;56;57;56;56;55;57;79;90;89 03:02:06 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;65;72;74;72;74;70;70;71;69;70 03:02:06 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;82;82;87;82;83;81;80;82;84;84 03:02:06 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;142;157;153;153;146;161;153;144;191;140 03:02:06 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;168;167;165;166;165;166;165;164;236;213 03:02:06 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;69;67;67;68;68;67;67;67;66;66 03:02:06 INFO - PROCESS | 1663 | __end_tp_report 03:02:06 INFO - PROCESS | 1663 | __start_cc_report 03:02:06 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1982 03:02:06 INFO - PROCESS | 1663 | __end_cc_report 03:02:06 INFO - PROCESS | 1663 | __startTimestamp1482750126802__endTimestamp 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | ------- Summary: start ------- 03:02:06 INFO - PROCESS | 1663 | Number of tests: 14 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:114.70 Median:115.00 stddev:0.82 (0.7%) stddev-sans-first:0.73 03:02:06 INFO - PROCESS | 1663 | Values: 116.0 116.0 115.0 114.0 114.0 114.0 115.0 114.0 115.0 114.0 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:125.50 stddev:22.70 (18.1%) stddev-sans-first:23.15 03:02:06 INFO - PROCESS | 1663 | Values: 144.0 116.0 135.0 178.0 113.0 110.0 109.0 110.0 139.0 109.0 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:1.34 (0.7%) stddev-sans-first:1.33 03:02:06 INFO - PROCESS | 1663 | Values: 182.0 185.0 184.0 184.0 183.0 181.0 182.0 183.0 185.0 184.0 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:67.30 Median:63.50 stddev:11.88 (18.7%) stddev-sans-first:11.52 03:02:06 INFO - PROCESS | 1663 | Values: 81.0 60.0 58.0 89.0 63.0 64.0 60.0 58.0 82.0 58.0 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:77.50 stddev:10.82 (14.0%) stddev-sans-first:11.20 03:02:06 INFO - PROCESS | 1663 | Values: 84.0 71.0 94.0 70.0 69.0 69.0 89.0 67.0 69.0 91.0 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:85.00 Median:86.00 stddev:1.83 (2.1%) stddev-sans-first:1.58 03:02:06 INFO - PROCESS | 1663 | Values: 88.0 83.0 86.0 84.0 86.0 83.0 87.0 84.0 86.0 83.0 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:101.10 Median:92.50 stddev:17.88 (19.3%) stddev-sans-first:18.66 03:02:06 INFO - PROCESS | 1663 | Values: 92.0 93.0 93.0 92.0 93.0 93.0 92.0 106.0 149.0 108.0 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:82.00 stddev:17.16 (20.9%) stddev-sans-first:17.94 03:02:06 INFO - PROCESS | 1663 | Values: 83.0 81.0 81.0 94.0 124.0 122.0 83.0 83.0 81.0 81.0 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:65.20 Median:57.00 stddev:14.65 (25.7%) stddev-sans-first:15.24 03:02:06 INFO - PROCESS | 1663 | Values: 57.0 56.0 57.0 56.0 56.0 55.0 57.0 79.0 90.0 89.0 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.50 stddev:2.63 (3.7%) stddev-sans-first:1.80 03:02:06 INFO - PROCESS | 1663 | Values: 65.0 72.0 74.0 72.0 74.0 70.0 70.0 71.0 69.0 70.0 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:82.50 stddev:1.95 (2.4%) stddev-sans-first:2.05 03:02:06 INFO - PROCESS | 1663 | Values: 82.0 82.0 87.0 82.0 83.0 81.0 80.0 82.0 84.0 84.0 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:154.00 Median:153.00 stddev:14.66 (9.6%) stddev-sans-first:14.89 03:02:06 INFO - PROCESS | 1663 | Values: 142.0 157.0 153.0 153.0 146.0 161.0 153.0 144.0 191.0 140.0 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.50 Median:166.50 stddev:25.38 (15.2%) stddev-sans-first:26.69 03:02:06 INFO - PROCESS | 1663 | Values: 168.0 167.0 165.0 166.0 165.0 166.0 165.0 164.0 236.0 213.0 03:02:06 INFO - PROCESS | 1663 | 03:02:06 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:0.92 (1.4%) stddev-sans-first:0.71 03:02:06 INFO - PROCESS | 1663 | Values: 69.0 67.0 67.0 68.0 68.0 67.0 67.0 67.0 66.0 66.0 03:02:06 INFO - PROCESS | 1663 | -------- Summary: end -------- 03:02:06 INFO - PROCESS | 1663 | 03:02:07 INFO - TEST-INFO | 1663: exit 0 03:02:07 INFO - TEST-OK | kraken | took 60378ms 03:02:07 INFO - SUITE-END | took 1180s 03:02:07 INFO - Completed test suite (00:19:40) 03:02:07 INFO - javascript benchmark 03:02:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.014695664779, 3358.4290670585983, 3366.208940898889, 3375.324935012995, 3420.777649452785, 5608.859778505529, 5642.746076426561, 5751.4536119068625, 5758.395368486312, 5764.423557644233, 5625.634333768302, 5704.828855134353, 5716.742746576404, 5719.799807006755, 5720.399358067405, 5192.40287366952, 5193.246979188034, 5201.453847346031, 5213.504717051874, 5257.395399529046, 4640.2807564827335, 5144.4598317176715, 5156.097682905476, 5165.638405311611, 5269.736513174345, 4315.395844581761, 5262.236975638531, 5278.392984806739, 5280.102382594949, 5282.23407605897, 5250.894982100355, 5259.342582177228, 5261.105612045962, 5264.15773476242, 5275.076861549213, 5100.234964755279, 5204.141316682781, 5221.582273418117, 5225.190095535198, 5252.842414727565, 5730.799422020251, 5732.828015159552, 5733.770649174028, 5745.6265342753, 5749.1663708762, 6116.640127388518, 6121.727351053096, 6122.540809439265, 6125.398724082952, 6125.662799505643, 6213.068039794022, 6217.751289948396, 6246.593971391845, 6270.404311590391, 6281.403266689657, 5739.713014349308, 5741.8564535886935, 5750.884982300372, 5775.220345253421, 5790.449907258803, 5991.921454138297, 6152.230971128609, 6213.65824879636, 6319.462845658079, 6332.176817013758, 5899.734511946973, 6035.788747393908, 6143.788236234952, 6144.2319710036245, 6151.477124444383, 6166.260048794084, 6255.468285195719, 6260.998240281533, 6262.3111457739615, 6269.059641053787, 5992.8801423972145, 6045.637261764308, 6182.412670796267, 6190.071489276645, 6202.596831205957, 6103.206583144163, 6130.979101202072, 6188.597741146811, 6201.069839524108, 6239.157713708682, 4904.313396124546, 4905.558499735041, 4911.386076740408, 4916.287138364917, 4942.258661200849, 4329.09089091288, 4435.334699794992, 4435.445569303837, 4450.020995381075, 4458.08609235106, 5596.132599447093, 5651.660900345993, 5655.604107712421, 5666.0, 5717.113847353667, 6245.375462453719, 6264.749410023639, 6270.811875643647, 6292.370762923671, 6292.779752708633, 5681.971590142023, 5747.453991870766, 5756.222909907193, 5763.049096899049, 5802.187693722798, 5249.50129737682, 5259.184826351921, 5297.54970827484, 5354.571634269249, 5360.946390536123, 5239.423663396947, 6234.9688251558455, 6281.71732272058, 6287.905681414874, 6344.936550634435, 6212.409821066992, 6245.5628106032145, 6256.249250090018, 6264.968675156595, 6274.498040156868, 6254.999200127958, 6310.968445157745, 6320.873582528416, 6326.4622507087415, 6357.14178585885, 5921.2006379138265, 6001.3698561652145, 6010.248718910137, 6041.78853740117, 6118.20463339763, 6158.04550294705, 6162.661053642093, 6185.103160041679, 6204.906926396198, 6216.875662486816, 5131.461196574306, 5991.700414979321, 6064.757409703562, 6097.176881120992, 6126.326104128631, 5844.503217226414, 6038.547108966933, 6048.788292409745, 6050.848728781815, 6060.393960603904, 5715.742791574306, 5770.480656740913, 5772.5381969441505, 5907.409259074058, 5913.58604897653, 5949.940500594938, 5956.970215148896, 6006.189164462741, 6033.276006879202, 6039.033754599243, 5837.445442682938, 5872.236609240771, 5900.0, 5914.4381283777975, 5939.970300148471], "value": 5658.815422098435, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.74662668665667, 519.5947161214253, 526.8540923491405, 534.9679019258847, 539.9460053994601, 29078.273043173933, 30658.693413065892, 31664.0, 31918.0, 32959.67040329596, 39996.80001599995, 40208.39687404684, 40315.19369612606, 40340.79829600848, 40404.79797601015, 7094.21963584008, 9184.540772961342, 9335.64633128196, 9337.906620933773, 9347.392419492711, 7886.250806173433, 10999.835002474929, 11249.662510124668, 11266.155038372113, 11368.31790092593, 11097.3896435696, 11137.832932506019, 11183.0, 11197.944010279938, 11236.269642473213, 11177.273477223995, 11236.438178091063, 11253.324800511995, 11255.60605378816, 11258.0, 11084.002439780377, 11176.0, 11180.832287515694, 11184.832227516594, 11194.832077518844, 37728.62271377306, 38010.42984355237, 38069.04827379338, 38073.61926380728, 38133.0466738331, 3256.788308759923, 3318.8340582970995, 3656.177360093984, 3711.406175011985, 3882.068303607114, 26622.86688566564, 27568.586471202947, 27576.724232757617, 27594.172174834588, 28000.739966701345, 3127.2807254331406, 3164.0, 3183.936321273585, 3276.8689252429876, 3285.4086264472385, 374730.12634936924, 451795.0, 452505.0, 455567.0, 455705.72147139383, 53505.7324713374, 53875.0, 53992.190117148275, 54079.45920540823, 62714.372856271766, 50359.24461133086, 64575.03137452942, 67200.0, 67279.32720672828, 67399.66300168567, 15686.980346277684, 15878.52364429069, 15949.760753588705, 15951.202439878192, 15973.440929567409, 9203.0, 11123.777524449628, 11136.220464567565, 12051.879481205076, 12266.81599776004, 16470.588235294214, 19400.805991940182, 19622.215111395384, 19694.803051969582, 20166.798332016784, 6746.4265537429865, 7919.564423956623, 8123.512589244664, 8123.79690507742, 8153.779257193375, 842.6460886427715, 878.7759121423996, 930.9813803723888, 1020.5305559442739, 1043.4991204222015, 28143.859280703466, 28529.857350713115, 28745.137645870658, 29382.265443364086, 29383.971560995262, 1023.9283250172416, 1033.2508931025097, 1041.9944753313089, 1119.2445099557765, 1143.188336281233, 14611.853881461262, 15045.473408430871, 15131.319090640947, 15292.235388230543, 15335.233238338262, 677584.0, 679677.0, 680884.0, 681437.0, 683468.0, 53996.73001634967, 54524.0, 54527.0, 54576.63558410992, 54683.17217794716, 1227.7974134267745, 1232.1805999010737, 1265.2661456355293, 1324.635725175546, 1362.768329384007, 28731.0, 28833.70248338788, 29293.26766830846, 29299.7070029297, 29998.55002175013, 41687.957801055214, 41879.0, 42001.0, 42059.579404205564, 42070.78964605158, 558047.0, 558217.0, 559299.0, 559916.0, 562993.0, 25596.0, 26225.868870655526, 26349.473010540067, 26442.86778566095, 26645.866770666024, 34260.65739342674, 43132.137357253305, 43318.917027073316, 43765.56234437615, 44072.779636101615, 35847.46228806622, 36165.63834361623, 38771.030724230994, 39442.21115577615, 40139.799301004474, 35899.64100358963, 36526.817365913, 36590.26819463649, 36861.0, 36862.0, 34591.827040864635, 35850.820745896104, 36458.8177059113, 36569.26861462809, 36810.447843281836, 31517.842410787798, 32547.674523255413, 33161.005169844946, 33303.33393332168, 33342.33315333631, 24333.878330608233, 24627.507449851262, 24957.625635615845, 24992.559080897616, 25009.0], "value": 19490.671695590096, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.0811593913793, 367.5460805904707, 369.6285233340491, 381.29841092390006, 398.7288643722268, 14296.571102866917, 14988.5503434897, 19516.41450756476, 20067.89626570538, 20528.692069618966, 11526.654200373981, 11785.941070294637, 15498.690026199498, 19847.30534431295, 20053.29813456529, 4302.741835489881, 4414.22751018572, 5252.6323157379, 5376.569874410057, 5443.972780136094, 5306.310179676637, 5402.108652072423, 5411.594130440233, 5413.512783849453, 5422.05114105032, 5135.512126347991, 5339.919901201485, 5387.649802762828, 5412.9458705413035, 5425.077736784737, 5367.812126575571, 5377.865553361177, 5394.838154855361, 5396.163594642837, 5410.08028635133, 5008.24876268559, 5171.741412929376, 5234.424213336568, 5234.581233501311, 5248.947510524884, 13076.476940922352, 13181.275029873354, 13202.471901123943, 15434.691306173814, 25121.11591630635, 2125.521757604542, 2212.9114835406563, 2236.888155592214, 2472.5178590174924, 2746.917592472229, 10023.14803241718, 10248.590056397736, 10425.270231083827, 10467.267291289612, 12377.443015064344, 1824.4617837737899, 1824.5894673698444, 1862.366795289608, 1864.3008871673123, 1879.6710575649206, 113618.43190784076, 115182.0, 117315.0, 118419.0, 120391.39804300922, 11464.885351146382, 11545.134114941413, 11689.824652630217, 11722.238054526599, 11879.584214552622, 12787.552435664707, 13470.0, 13530.120542164728, 13538.25539595312, 14006.71986560263, 6728.629925354055, 8232.259096681328, 8466.576671166416, 8518.957405212934, 8564.914350856536, 8106.229908158715, 8379.639259501411, 8414.990201175775, 8557.743267701979, 8569.228769410784, 4497.820087196541, 4538.069695712371, 4538.818447262139, 4548.317752337176, 4559.430071241095, 1600.415848215393, 1842.5209445543987, 1891.6216756648726, 1897.9940631465345, 1898.402003368962, 871.4945331707594, 879.3273146043282, 926.5645146781002, 938.1931538876561, 944.7449188719007, 20398.592028159354, 20907.37277881666, 20976.160953561688, 21009.78990210109, 21076.367708968755, 904.1410659873015, 941.091846368258, 949.6343907595625, 966.5940305071887, 970.5001924009141, 8248.628811703487, 9075.636974521078, 11209.887901120885, 11293.548258069584, 11309.208355415041, 605961.9701901462, 614546.0, 619018.0, 620466.0, 623091.0, 106655.4667226659, 124313.75686243203, 127569.0, 127757.0, 132526.33736831447, 935.2471260635118, 935.2611436964858, 968.138356862393, 969.9127078562963, 970.7427531704104, 18338.63322733511, 18484.075796210403, 18492.815071849647, 18728.906355468134, 18914.905425472785, 44452.55547444613, 51337.45987620377, 52010.0, 54873.725631371584, 55934.44065559292, 74118.0, 76202.0, 79754.20245797468, 80888.59555702184, 81184.78222826544, 6720.0, 7633.656485458278, 7915.0, 7954.443188976716, 8386.622601982803, 6790.388865002174, 9081.182693557612, 9587.952060239653, 10862.945685271523, 12236.44935977901, 10915.494709402776, 10986.945065274622, 11099.945505176995, 11144.108471322437, 11173.385463799568, 9963.352382095141, 10874.619388321371, 10979.560817567528, 11103.278286911325, 11135.220534562503, 7940.444168908121, 10860.945695271474, 10888.183386245904, 11252.212345135762, 11815.940920295687, 6943.270956549692, 7312.890306645511, 7405.5926924019695, 7946.642401091849, 8132.878006829784, 9851.802963940538, 10358.533865975898, 10742.8388574173, 10892.564297428014, 10932.125429965425], "value": 9026.493754750529, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1294.5274974634262, 1437.9065360751551, 1482.3403585404492, 1612.7016501947141, 1818.3453956575631, 7434.405247580195, 7758.534487930728, 7878.369730421567, 8220.136885627013, 8222.794430139249, 8099.635516401761, 8108.878366824488, 8186.181381861826, 8187.017557893047, 8201.425900186974, 2218.955620887585, 2256.7968882800546, 2263.411513006622, 2268.4442311633675, 2269.580127676377, 2232.9888350558226, 2269.00163927872, 2289.553537060274, 2293.3693234360603, 2293.8279629027807, 2286.542691461706, 2293.6444851048113, 2321.442853715105, 2324.790768830805, 2326.581215381231, 2265.161890100657, 2276.590213761522, 2304.5045315257134, 2308.203669733939, 2308.953820923572, 2265.2524666859895, 2335.2760644200184, 2335.8131349492, 2337.625979843234, 2341.555104530134, 8104.311133553655, 8174.713885013996, 8244.216799404046, 8249.958750206211, 8261.421700480969, 4460.8884777880485, 5021.271915572258, 5196.92204616931, 5204.661696989683, 5235.162374020177, 3307.3219989902254, 3451.361498122855, 3530.1880567469625, 3546.4680297955256, 3550.289942011608, 1067.1729409707461, 1083.6586475260267, 1683.5538582275706, 1709.0173150438422, 1795.416489640872, 10775.784484310348, 10867.29362591437, 10877.347359158475, 10891.128709703205, 10905.618303359424, 4287.656987440997, 4471.642268618502, 4504.2793153095345, 4513.368128462018, 4514.277715565494, 8606.139386061344, 8635.0, 8650.567471626393, 8652.610632521551, 8660.696875609323, 5065.645404821627, 5073.543381095719, 5081.720505372166, 5082.466341034212, 5085.186370180753, 4031.254217969685, 4220.155968806251, 4409.250427427345, 4684.929726054112, 4762.357081793983, 3649.671529562352, 3652.196516766307, 3737.0657335666083, 3748.962510374916, 3773.339665558516, 1117.7261570915177, 1126.5775334249656, 1136.363636363639, 1153.1837356164076, 1153.5789436855487, 778.5640041576735, 859.909709480508, 897.5146133149517, 906.1799071839995, 924.8242833861544, 5567.805126820542, 5574.804881829116, 5735.340435849847, 5851.38560451155, 5851.736671849777, 917.3899356927702, 917.7934964632905, 919.1957037592107, 926.4996901673021, 954.341504361988, 3719.9442008369897, 3768.5666148393284, 3779.508663873734, 3808.6953043756434, 3891.3384724596885, 9717.319787614799, 9904.900950990397, 10024.0, 10081.243906707026, 10084.596616135272, 10025.899741002497, 10031.297809153435, 10038.799224015478, 10048.196144308438, 10107.595696172219, 7410.555366677909, 7569.545827250383, 7699.153093159748, 7706.422018348534, 7743.070831500256, 5505.862353441196, 5599.580031497735, 5823.039198532195, 5853.619514731528, 5878.0, 9052.864207037032, 9162.0, 9371.718848434557, 9378.249740020918, 9492.667756628485, 7441.218672039358, 8513.276371508491, 8874.378793484653, 9529.0, 9649.986751391003, 6957.512974091765, 6982.1272340957385, 7053.717851285891, 7191.712331506681, 7209.423246140192, 7727.49771264866, 7782.610869456391, 7789.922100779147, 7801.2198780121535, 7848.960755196187, 6918.688659010458, 7043.577385356895, 7074.71701131949, 7113.928860711327, 7184.066071410683, 6906.516543841883, 6966.686499107451, 6968.895466568107, 7001.789946301619, 7025.297470252934, 6515.609063456208, 6559.180102487189, 6644.568103073478, 6653.900191497035, 6669.799906002828, 4823.614110871052, 4853.466118726867, 4854.417469903634, 4900.509949005071, 4907.705537667752, 4108.404281379243, 4761.452432970253, 4829.2997515359375, 4993.8751531210555, 5097.311862898461], "value": 4391.587817796726, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.95665128556357, 299.1176030709408, 307.85992373470066, 312.51716098627617, 313.7568384502012, 4884.926726099107, 5259.395169555502, 5322.973385133069, 5337.5196232339085, 5377.166539186423, 4393.8022788974495, 4525.660575456837, 4793.849476125731, 5110.386753589574, 5119.974400127994, 3215.8713651453913, 3773.471743943707, 4029.798510074492, 4065.5934406559254, 4066.373450619746, 4039.4950631171105, 4039.919201615966, 4051.7771522566245, 4078.877633670995, 4096.672266218696, 3583.713302935772, 3750.5311836020496, 3902.2780785554623, 4021.5120405450025, 4058.15354013923, 4023.6982226333143, 4025.0076212274816, 4028.879133625996, 4048.793407592869, 4052.716309858311, 3507.368673638744, 3523.8547472071677, 3545.6808887200277, 3545.8758943436856, 3547.609762926076, 3954.5056867891512, 4218.578142185787, 4250.001249706316, 4734.928976065327, 5094.93006468642, 3447.9311413461655, 4073.3889916512762, 4597.632189424838, 4604.769761511911, 4614.653900957408, 4175.081482073939, 4594.495999680183, 5042.327566389819, 5227.032998895216, 5349.0, 3496.930061398758, 3704.0924973381693, 3886.164474637938, 3887.5334959804723, 3914.0214946263436, 5385.06137790399, 5475.342958844884, 5735.483806457397, 5749.511291540182, 5849.0, 3350.715189208896, 3435.4331535296396, 3473.235888104613, 3797.1266608680157, 3813.523309586302, 3429.34842379952, 3813.427985802152, 3902.6097390261316, 3912.315344814646, 3927.6072392760493, 4010.959890401117, 4233.428487154257, 4409.559044095565, 4498.572635599613, 4586.922073312769, 4915.262710593368, 5137.458762371273, 5461.231750092424, 5553.805616803474, 5601.663900166003, 980.5293459139652, 1156.9645167574972, 1251.2054845173197, 1260.3131293445094, 1263.2483672215017, 727.5016613619582, 759.6391713935947, 761.9771406857803, 818.0306336990685, 860.492309537376, 4040.7575545467366, 4877.707337559757, 4880.829170978998, 4937.703737775745, 5010.519792702636, 4709.646776491777, 4724.6692731508465, 5410.97294513525, 5463.69949652765, 5464.18037294409, 4177.660510114346, 4631.980964187874, 4717.655468191549, 4880.292357608198, 4945.134601444733, 2411.63825426184, 2425.1754403502896, 2696.7572918437436, 2771.7155171122286, 2847.928801779931, 4820.325154478376, 5228.980348831941, 5280.947190528045, 5591.41290164535, 5608.663480191201, 5007.749612519359, 5323.3514471807675, 5502.862428439241, 5509.917351239735, 5515.7242137892945, 5390.0, 5509.917351239735, 5528.419515950767, 5533.446655334515, 5583.050881350099, 4209.684273679425, 4417.867463976086, 4440.511543730252, 5124.718140502159, 5381.3542374913695, 4121.793910304533, 4304.548022457722, 4744.8375148088935, 5005.824796132118, 5258.789648414021, 4906.663468841222, 5397.703126327932, 5523.585731070106, 5559.861003474945, 5562.471565201299, 3349.4640857463664, 3588.5155504006625, 3606.549181352331, 3609.141292234449, 3622.4023036198732, 5112.514311140436, 5296.258523806747, 5322.41453440129, 5335.172358649922, 5396.946030539644, 4602.516735742699, 4634.86095417138, 5143.665661731976, 5218.608604354613, 5220.269162317202, 4820.855374338776, 4948.655452111782, 5114.33513643224, 5186.533212010786, 5197.038547868657, 4036.7486079315504, 4119.402686610485, 4796.712197268175, 5054.444011158696, 5094.617903657167, 4409.007973206131, 5218.921716174336, 5377.731113444234, 5547.5007249347755, 5552.805651802168, 4512.593866552026, 5318.946810531846, 5390.19147127934, 5436.836894893159, 5441.401445841034], "value": 3857.140074727933, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.9689513971871, 691.8062942376133, 717.5766297884247, 740.7148247924547, 762.5196126440342, 13799.379027943742, 14335.354909029093, 14347.497837575687, 14360.928195359022, 14365.928170359146, 12833.743325133515, 12881.613551593467, 12896.548620798274, 12953.28756918369, 12973.286469244187, 646.2309851276977, 669.3172963577149, 675.1695414639971, 675.9155105611799, 679.5990365684245, 674.6795272245693, 799.6801279488193, 833.5082301442147, 837.3217693668117, 840.5839109640737, 727.5634619228473, 884.0584777212281, 884.1247165306364, 884.933629977751, 885.9069797671249, 875.5053394831899, 877.0965905117771, 879.5734068976541, 879.736079176251, 880.053942012336, 512.3416409913281, 535.0609680011594, 626.1515646299245, 657.1293036726356, 658.9044588534636, 13431.0, 13635.795463068062, 13940.721185576233, 13958.302084895715, 13982.230977296245, 12159.452824622913, 12342.382880856012, 12447.253164810141, 12519.56181533642, 12637.49450021998, 11946.940265298705, 12059.33673647949, 12241.755164896653, 12243.265404075784, 12251.081168912278, 4246.256905041636, 4255.319148936186, 4302.311630139163, 4472.776361181928, 4484.035932274544, 87960.0, 90482.0, 92927.0, 94095.0, 96434.55348169783, 10380.32527885685, 10488.056074953138, 10676.679699609023, 10683.57265709363, 10811.18916081297, 20487.8975605121, 20499.11495599693, 20580.27969021077, 21014.78985210159, 21034.684479732816, 7495.0, 7571.432142589328, 7596.696132154762, 7631.885521717068, 7661.578613176329, 4151.750894946313, 4236.724612900151, 4274.252005898955, 4385.188740083095, 4415.933760993587, 3262.102921696505, 3376.696097351201, 3381.8816341427932, 3385.6783605557434, 3394.032700680294, 648.7210499485267, 682.7269092363094, 686.5949090036902, 691.550492180077, 692.7219280427603, 546.8769526856426, 552.4061633743742, 658.8968439436499, 661.7286912365906, 668.729164688302, 8678.956605216934, 8959.5968181432, 8994.595243214071, 9108.772280692903, 9260.027697091844, 633.1515768869644, 635.5932203389834, 645.554567348528, 668.6222284409293, 670.6244503078292, 3423.0, 4268.359746038119, 4364.8472303469225, 4407.845725399659, 4482.641388688897, 102030.0, 105001.0, 113927.0, 118095.0, 118691.0, 95557.0, 98139.50930245446, 98306.50846745864, 99128.50435747775, 99435.50282248687, 88051.55974220217, 91264.0, 91514.08485915055, 92087.53956230175, 93557.0, 8458.154184581492, 8605.053444121268, 8610.483370997761, 8652.264557512683, 8804.47173169612, 49535.00929981452, 51357.48642513676, 51797.223041653655, 54977.72511137419, 55545.72227138838, 54533.0, 71191.0, 71842.64078679783, 76456.61771691106, 76489.61755191411, 12025.281725622524, 12119.001823495828, 12324.691882703004, 12528.18566793192, 12649.557265495663, 13699.794503082661, 13731.519396821062, 13770.518031868836, 14107.647308817363, 14140.222287774272, 12717.236965782082, 12902.741945161231, 12951.935240323737, 12984.805227921399, 13174.670633233862, 12246.938765306117, 12680.74638507243, 12757.872421275668, 13071.085024048225, 13570.86429135696, 12234.75530489403, 12381.938090309492, 12531.18547294423, 12577.993760499321, 13098.0, 10731.89268107309, 10801.837972430263, 10926.890731092588, 11049.060829829554, 11232.55069797199, 10909.563617455531, 11161.553537858395, 11248.10450261156, 11310.208285419942, 11383.886161138282], "value": 7248.101530421276, "unit": "score"}], "name": "dromaeo_css", "value": 7044.852217251395, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 115.0, 114.0, 114.0, 114.0, 115.0, 114.0, 115.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 116.0, 135.0, 178.0, 113.0, 110.0, 109.0, 110.0, 139.0, 109.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 184.0, 184.0, 183.0, 181.0, 182.0, 183.0, 185.0, 184.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 58.0, 89.0, 63.0, 64.0, 60.0, 58.0, 82.0, 58.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 94.0, 70.0, 69.0, 69.0, 89.0, 67.0, 69.0, 91.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 86.0, 84.0, 86.0, 83.0, 87.0, 84.0, 86.0, 83.0], "value": 85.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 92.0, 93.0, 93.0, 92.0, 106.0, 149.0, 108.0], "value": 101.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 81.0, 94.0, 124.0, 122.0, 83.0, 83.0, 81.0, 81.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 57.0, 56.0, 56.0, 55.0, 57.0, 79.0, 90.0, 89.0], "value": 65.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 74.0, 72.0, 74.0, 70.0, 70.0, 71.0, 69.0, 70.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 87.0, 82.0, 83.0, 81.0, 80.0, 82.0, 84.0, 84.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 157.0, 153.0, 153.0, 146.0, 161.0, 153.0, 144.0, 191.0, 140.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 165.0, 166.0, 165.0, 166.0, 165.0, 164.0, 236.0, 213.0], "value": 177.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 68.0, 68.0, 67.0, 67.0, 67.0, 66.0, 66.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1463.6000000000001, "alertThreshold": 2.0}]} 03:02:08 INFO - Return code: 0 03:02:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:02:08 INFO - # TBPL SUCCESS # 03:02:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:02:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:02:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:02:08 INFO - 2016-12-26 03:02:08.116 screenresolution[1667:11700] starting screenresolution argv=screenresolution get 03:02:08 INFO - 2016-12-26 03:02:08.120 screenresolution[1667:11700] Display 0: 1600x1200x32@60 03:02:08 INFO - 2016-12-26 03:02:08.126 screenresolution[1668:11703] starting screenresolution argv=screenresolution list 03:02:08 INFO - Available Modes on Display 0 03:02:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:02:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:02:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:02:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:02:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:02:08 INFO - Intel Iris: 03:02:08 INFO - Chipset Model: Intel Iris 03:02:08 INFO - Type: GPU 03:02:08 INFO - Bus: Built-In 03:02:08 INFO - VRAM (Dynamic, Max): 1536 MB 03:02:08 INFO - Vendor: Intel (0x8086) 03:02:08 INFO - Device ID: 0x0a2e 03:02:08 INFO - Revision ID: 0x0009 03:02:08 INFO - Displays: 03:02:08 INFO - Display: 03:02:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:02:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:02:08 INFO - Display Serial Number: 1600x1200 60 03:02:08 INFO - Main Display: Yes 03:02:08 INFO - Mirror: Off 03:02:08 INFO - Online: Yes 03:02:08 INFO - Rotation: Supported 03:02:08 INFO - Return code: 0 03:02:08 INFO - Running post-action listener: _resource_record_post_action 03:02:08 INFO - [mozharness: 2016-12-26 11:02:08.377474Z] Finished run-tests step (success) 03:02:08 INFO - Running post-run listener: _resource_record_post_run 03:02:08 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 32041984; Write bytes: 656676352; Read time: 198; Write time: 1877 03:02:08 INFO - TinderboxPrint: CPU usage
53.5% 03:02:08 INFO - TinderboxPrint: I/O read bytes / time
32,041,984 / 198 03:02:08 INFO - TinderboxPrint: I/O write bytes / time
656,676,352 / 1,877 03:02:08 INFO - TinderboxPrint: CPU idle
2,254.5 (46.5%) 03:02:08 INFO - TinderboxPrint: CPU system
970.3 (20.0%) 03:02:08 INFO - TinderboxPrint: CPU user
1,624.8 (33.5%) 03:02:08 INFO - TinderboxPrint: Swap in / out
377,249,792 / 0 03:02:08 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 346194432; Write bytes: 346138112; Read time: 27119; Write time: 712 03:02:08 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 29993984; Write bytes: 303476736; Read time: 179; Write time: 1137 03:02:08 INFO - Running post-run listener: _upload_blobber_files 03:02:08 INFO - Blob upload gear active. 03:02:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:02:08 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 03:02:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:02:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:02:09 INFO - (blobuploader) - INFO - Open directory for files ... 03:02:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:02:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:02:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:10 INFO - (blobuploader) - INFO - Done attempting. 03:02:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:02:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:02:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:11 INFO - (blobuploader) - INFO - Done attempting. 03:02:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:02:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:02:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:13 INFO - (blobuploader) - INFO - Done attempting. 03:02:13 INFO - (blobuploader) - INFO - Iteration through files over. 03:02:13 INFO - Return code: 0 03:02:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:02:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:02:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4286f7f45069ea5f78a6ccd8bb83ba304884aaa5a383035c94ad8a2e0ca2974b1587c8b7ef30df4efe260a4f79d2b800e1eb74b9c4ddcf76ce966477a591cef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/003d54040fd8c2c0e85a79aa5d8fc0322d612e8a1cb582191b31eb4cf52525a58265f3787ce421b80976671073aa7363f6b9e37dd2a1d262e87dd14e20330f3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8a697b675f70077b4ab207817b40805857e06d8aec07b99b630a3c933e7f1a0efd6183e3dede1ba48d006bf7d5f003195b866889c0869f9051a4658611d8571"} 03:02:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:02:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:02:13 INFO - Contents: 03:02:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4286f7f45069ea5f78a6ccd8bb83ba304884aaa5a383035c94ad8a2e0ca2974b1587c8b7ef30df4efe260a4f79d2b800e1eb74b9c4ddcf76ce966477a591cef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/003d54040fd8c2c0e85a79aa5d8fc0322d612e8a1cb582191b31eb4cf52525a58265f3787ce421b80976671073aa7363f6b9e37dd2a1d262e87dd14e20330f3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8a697b675f70077b4ab207817b40805857e06d8aec07b99b630a3c933e7f1a0efd6183e3dede1ba48d006bf7d5f003195b866889c0869f9051a4658611d8571"} 03:02:13 INFO - Running post-run listener: copy_logs_to_upload_dir 03:02:13 INFO - Copying logs to upload dir... 03:02:13 INFO - mkdir: /builds/slave/test/build/upload/logs 03:02:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.359301 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-26 03:02:13.618339) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 03:02:13.626166) ========= 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.hMQSqHhNsI/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.HSew9nYMME/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4286f7f45069ea5f78a6ccd8bb83ba304884aaa5a383035c94ad8a2e0ca2974b1587c8b7ef30df4efe260a4f79d2b800e1eb74b9c4ddcf76ce966477a591cef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/003d54040fd8c2c0e85a79aa5d8fc0322d612e8a1cb582191b31eb4cf52525a58265f3787ce421b80976671073aa7363f6b9e37dd2a1d262e87dd14e20330f3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8a697b675f70077b4ab207817b40805857e06d8aec07b99b630a3c933e7f1a0efd6183e3dede1ba48d006bf7d5f003195b866889c0869f9051a4658611d8571"} build_url:https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010963 build_url: 'https://queue.taskcluster.net/v1/task/YoDa_ZUQScSWVO8idbUOuA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4286f7f45069ea5f78a6ccd8bb83ba304884aaa5a383035c94ad8a2e0ca2974b1587c8b7ef30df4efe260a4f79d2b800e1eb74b9c4ddcf76ce966477a591cef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/003d54040fd8c2c0e85a79aa5d8fc0322d612e8a1cb582191b31eb4cf52525a58265f3787ce421b80976671073aa7363f6b9e37dd2a1d262e87dd14e20330f3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8a697b675f70077b4ab207817b40805857e06d8aec07b99b630a3c933e7f1a0efd6183e3dede1ba48d006bf7d5f003195b866889c0869f9051a4658611d8571"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 03:02:13.664834) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 03:02:13.665197) ========= 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.hMQSqHhNsI/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.HSew9nYMME/Listeners TMPDIR=/var/folders/sv/hlxstmsx7bn09142831q1n6000000w/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.005131 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 03:02:13.713351) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 03:02:13.716697) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 03:02:13.717023) ========= ========= Total master_lag: 0.24 =========