builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0314 starttime: 1482257512.57 results: success (0) buildid: 20161220074553 builduid: ea4fb84392a04b0f91e5553a18d81794 revision: 18ca74ed9f75b19ff614801c27e2ab50b10bd704 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 10:11:52.570922) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 10:11:52.581599) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 10:11:52.581981) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 10:11:52.608839) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 10:11:52.609247) ========= 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.TZft7sE29M/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.pE25vmm5co/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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-20 10:11:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.6M=0.001s 2016-12-20 10:11:52 (20.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103904 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 10:11:52.754936) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 10:11:52.755569) ========= 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.TZft7sE29M/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.pE25vmm5co/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.025109 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 10:11:52.807317) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-20 10:11:52.807907) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 18ca74ed9f75b19ff614801c27e2ab50b10bd704 --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 18ca74ed9f75b19ff614801c27e2ab50b10bd704 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TZft7sE29M/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.pE25vmm5co/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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-20 10:11:52,923 truncating revision to first 12 chars 2016-12-20 10:11:52,923 Setting DEBUG logging. 2016-12-20 10:11:52,924 attempt 1/10 2016-12-20 10:11:52,924 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/18ca74ed9f75?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-20 10:11:53,922 unpacking tar archive at: mozilla-aurora-18ca74ed9f75/testing/mozharness/ program finished with exit code 0 elapsedTime=1.276736 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-20 10:11:54.128426) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 10:11:54.128795) ========= script_repo_revision: 18ca74ed9f75b19ff614801c27e2ab50b10bd704 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 10:11:54.129200) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 10:11:54.129475) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 10:11:54.149500) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-12-20 10:11:54.149916) ========= /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.TZft7sE29M/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.pE25vmm5co/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:11:54 INFO - MultiFileLogger online at 20161220 10:11:54 in /builds/slave/test 10:11:54 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 10:11:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:11:54 INFO - {'append_to_log': False, 10:11:54 INFO - 'base_work_dir': '/builds/slave/test', 10:11:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:11:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:11:54 INFO - 'branch': 'Mozilla-Aurora', 10:11:54 INFO - 'buildbot_json_path': 'buildprops.json', 10:11:54 INFO - 'config_files': ('talos/mac_config.py',), 10:11:54 INFO - 'default_actions': ('clobber', 10:11:54 INFO - 'read-buildbot-config', 10:11:54 INFO - 'download-and-extract', 10:11:54 INFO - 'populate-webroot', 10:11:54 INFO - 'create-virtualenv', 10:11:54 INFO - 'install', 10:11:54 INFO - 'run-tests'), 10:11:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:11:54 INFO - 'download_minidump_stackwalk': True, 10:11:54 INFO - 'download_symbols': 'ondemand', 10:11:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:11:54 INFO - 'tooltool.py': '/tools/tooltool.py', 10:11:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:11:54 INFO - '/tools/misc-python/virtualenv.py')}, 10:11:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:11:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:11:54 INFO - 'installer_path': 'installer.exe', 10:11:54 INFO - 'log_level': 'info', 10:11:54 INFO - 'log_name': 'talos', 10:11:54 INFO - 'log_to_console': True, 10:11:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:11:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:11:54 INFO - 'opt_config_files': (), 10:11:54 INFO - 'pip_index': False, 10:11:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:11:54 INFO - 'cmd': ('bash', 10:11:54 INFO - '-c', 10:11:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:11:54 INFO - 'enabled': True, 10:11:54 INFO - 'halt_on_failure': False, 10:11:54 INFO - 'name': 'check_screen_resolution'},), 10:11:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:11:54 INFO - 'cmd': ('bash', 10:11:54 INFO - '-c', 10:11:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:11:54 INFO - 'enabled': True, 10:11:54 INFO - 'halt_on_failure': False, 10:11:54 INFO - 'name': 'check_screen_resolution'},), 10:11:54 INFO - 'run_cmd_checks_enabled': True, 10:11:54 INFO - 'sps_profile': False, 10:11:54 INFO - 'sps_profile_interval': 0, 10:11:54 INFO - 'suite': 'dromaeojs', 10:11:54 INFO - 'system_bits': '32', 10:11:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:11:54 INFO - 'title': 't-yosemite-r7-0314', 10:11:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:11:54 INFO - 'use_talos_json': True, 10:11:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:11:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:11:54 INFO - 'work_dir': 'build'} 10:11:54 INFO - [mozharness: 2016-12-20 18:11:54.348152Z] Running clobber step. 10:11:54 INFO - Running pre-action listener: _resource_record_pre_action 10:11:54 INFO - Running main action method: clobber 10:11:54 INFO - rmtree: /builds/slave/test/build 10:11:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:11:56 INFO - Running post-action listener: _resource_record_post_action 10:11:56 INFO - [mozharness: 2016-12-20 18:11:56.915099Z] Finished clobber step (success) 10:11:56 INFO - [mozharness: 2016-12-20 18:11:56.915365Z] Running read-buildbot-config step. 10:11:56 INFO - Running pre-action listener: _resource_record_pre_action 10:11:56 INFO - Running main action method: read_buildbot_config 10:11:56 INFO - Using buildbot properties: 10:11:56 INFO - { 10:11:56 INFO - "project": "", 10:11:56 INFO - "product": "firefox", 10:11:56 INFO - "script_repo_revision": "production", 10:11:56 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:11:56 INFO - "repository": "", 10:11:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:11:56 INFO - "buildid": "20161220074553", 10:11:56 INFO - "pgo_build": "False", 10:11:56 INFO - "basedir": "/builds/slave/test", 10:11:56 INFO - "buildnumber": 45, 10:11:56 INFO - "slavename": "t-yosemite-r7-0314", 10:11:56 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:11:56 INFO - "platform": "yosemite_r7", 10:11:56 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:11:56 INFO - "branch": "mozilla-aurora", 10:11:56 INFO - "repo_path": "releases/mozilla-aurora", 10:11:56 INFO - "revision": "18ca74ed9f75b19ff614801c27e2ab50b10bd704", 10:11:56 INFO - "stage_platform": "macosx64", 10:11:56 INFO - "builduid": "ea4fb84392a04b0f91e5553a18d81794", 10:11:56 INFO - "slavebuilddir": "test" 10:11:56 INFO - } 10:11:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 10:11:56 INFO - Running post-action listener: _resource_record_post_action 10:11:56 INFO - [mozharness: 2016-12-20 18:11:56.920918Z] Finished read-buildbot-config step (success) 10:11:56 INFO - [mozharness: 2016-12-20 18:11:56.921149Z] Running download-and-extract step. 10:11:56 INFO - Running pre-action listener: _resource_record_pre_action 10:11:56 INFO - Running main action method: download_and_extract 10:11:56 INFO - mkdir: /builds/slave/test/build/tests 10:11:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:11:56 INFO - trying https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 10:11:56 INFO - Downloading https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/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 10:11:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/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 10:11:57 INFO - Downloaded 1199 bytes. 10:11:57 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:11:57 INFO - Using the following test package requirements: 10:11:57 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:11:57 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:11:57 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 10:11:57 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:11:57 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 10:11:57 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:11:57 INFO - u'jsshell-mac.zip'], 10:11:57 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:11:57 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 10:11:57 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:11:57 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:11:57 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 10:11:57 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:11:57 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 10:11:57 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:11:57 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 10:11:57 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:11:57 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 10:11:57 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:11:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:11:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:11:57 INFO - Fetch https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:11:58 INFO - Http code: 200 10:11:58 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 10:11:58 INFO - x-amz-cf-id: TWNREwYEpBSd_IixIr1tU-76eB1-tfRzkZl2U8gQlMnHsll7rG5fcA== 10:11:58 INFO - x-amz-version-id: RHfEdDd1HdbNaAw7v0tn1cYDDcpq_R7j 10:11:58 INFO - Expected file size: 16267640 10:11:58 INFO - Obtained file size: 16267640 10:12:04 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 10:12:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:12:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:12:04 INFO - Fetch https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:12:05 INFO - Http code: 200 10:12:05 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 10:12:05 INFO - x-amz-cf-id: xbQ01oU_IYeXkHkONb8qRTYqdtv8OdCsAlcF-nfyEm5eHpFPoZIBig== 10:12:05 INFO - x-amz-version-id: RHfEdDd1HdbNaAw7v0tn1cYDDcpq_R7j 10:12:06 INFO - Expected file size: 16267640 10:12:06 INFO - Obtained file size: 16267640 10:12:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 10:12:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:12:12 INFO - Fetch https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 10:12:13 INFO - Http code: 200 10:12:13 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 10:12:13 INFO - x-amz-cf-id: 1hfJBNNdNyvVOz9NXkgwFfMeVa9ZeXGveS17aTur5J5gzPwR1sVKJg== 10:12:13 INFO - x-amz-version-id: u0YcPNyVtPYegtU_GuAKvAna2KSOrjZB 10:12:13 INFO - Expected file size: 14409038 10:12:13 INFO - Obtained file size: 14409038 10:12:13 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')]} 10:12:13 INFO - trying https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:12:13 INFO - Downloading https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 10:12:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/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 10:12:16 INFO - Downloaded 109675368 bytes. 10:12:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:12:16 INFO - mkdir: /builds/slave/test/properties 10:12:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:12:16 INFO - Writing to file /builds/slave/test/properties/build_url 10:12:16 INFO - Contents: 10:12:16 INFO - build_url:https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:12:18 INFO - Running post-action listener: _resource_record_post_action 10:12:18 INFO - Running post-action listener: set_extra_try_arguments 10:12:18 INFO - [mozharness: 2016-12-20 18:12:18.100787Z] Finished download-and-extract step (success) 10:12:18 INFO - [mozharness: 2016-12-20 18:12:18.100912Z] Running populate-webroot step. 10:12:18 INFO - Running pre-action listener: _resource_record_pre_action 10:12:18 INFO - Running main action method: populate_webroot 10:12:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:12:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:12:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:12:18 INFO - '--tppagecycles', 10:12:18 INFO - '7'], 10:12:18 INFO - 'tests': ['tsvgm']}}, 10:12:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:12:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:12:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:12:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:12:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:12:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:12:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:12:18 INFO - 'talos_options': ['--disable-e10s'], 10:12:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:12:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:12:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:12:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:12:18 INFO - 'talos_options': ['--disable-e10s'], 10:12:18 INFO - 'tests': ['damp', 'tps']}, 10:12:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:12:18 INFO - 'tests': ['damp', 'tps']}, 10:12:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:12:18 INFO - 'tests': ['dromaeo_dom']}, 10:12:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:12:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:12:18 INFO - 'tests': ['basic_compositor_video']}, 10:12:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:12:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:12:18 INFO - 'tests': ['a11yr', 10:12:18 INFO - 'ts_paint', 10:12:18 INFO - 'tpaint', 10:12:18 INFO - 'sessionrestore', 10:12:18 INFO - 'sessionrestore_no_auto_restore', 10:12:18 INFO - 'tabpaint']}, 10:12:18 INFO - 'other-e10s': {'tests': ['a11yr', 10:12:18 INFO - 'ts_paint', 10:12:18 INFO - 'tpaint', 10:12:18 INFO - 'sessionrestore', 10:12:18 INFO - 'sessionrestore_no_auto_restore', 10:12:18 INFO - 'tabpaint']}, 10:12:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:12:18 INFO - 'ts_paint', 10:12:18 INFO - 'tpaint', 10:12:18 INFO - 'sessionrestore', 10:12:18 INFO - 'sessionrestore_no_auto_restore', 10:12:18 INFO - 'tabpaint']}, 10:12:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:12:18 INFO - 'ts_paint', 10:12:18 INFO - 'tpaint', 10:12:18 INFO - 'sessionrestore', 10:12:18 INFO - 'sessionrestore_no_auto_restore', 10:12:18 INFO - 'tabpaint']}, 10:12:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:12:18 INFO - 'tests': ['a11yr', 10:12:18 INFO - 'ts_paint', 10:12:18 INFO - 'tpaint', 10:12:18 INFO - 'sessionrestore', 10:12:18 INFO - 'sessionrestore_no_auto_restore', 10:12:18 INFO - 'tabpaint']}, 10:12:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:12:18 INFO - 'tests': ['a11yr', 10:12:18 INFO - 'ts_paint', 10:12:18 INFO - 'tpaint', 10:12:18 INFO - 'sessionrestore', 10:12:18 INFO - 'sessionrestore_no_auto_restore', 10:12:18 INFO - 'tabpaint']}, 10:12:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:12:18 INFO - 'tests': ['tsvgx', 10:12:18 INFO - 'tsvgr_opacity', 10:12:18 INFO - 'tart', 10:12:18 INFO - 'tscrollx', 10:12:18 INFO - 'cart']}, 10:12:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:12:18 INFO - 'tsvgr_opacity', 10:12:18 INFO - 'tart', 10:12:18 INFO - 'tscrollx', 10:12:18 INFO - 'cart']}, 10:12:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:12:18 INFO - 'talos_options': ['--disable-e10s'], 10:12:18 INFO - 'tests': ['tp5o']}, 10:12:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:12:18 INFO - 'tests': ['tp5o']}, 10:12:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:12:18 INFO - 'talos_options': ['--disable-e10s', 10:12:18 INFO - '--xperf_path', 10:12:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:12:18 INFO - 'tests': ['tp5n']}, 10:12:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:12:18 INFO - 'talos_options': ['--xperf_path', 10:12:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:12:18 INFO - 'tests': ['tp5n']}}, 10:12:18 INFO - 'talos.zip': {'path': '', 10:12:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:12:18 INFO - Running post-action listener: _resource_record_post_action 10:12:18 INFO - [mozharness: 2016-12-20 18:12:18.119238Z] Finished populate-webroot step (success) 10:12:18 INFO - [mozharness: 2016-12-20 18:12:18.119345Z] Running create-virtualenv step. 10:12:18 INFO - Running pre-action listener: _resource_record_pre_action 10:12:18 INFO - Running main action method: create_virtualenv 10:12:18 INFO - Creating virtualenv /builds/slave/test/build/venv 10:12:18 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 10:12:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:12:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:12:18 INFO - Using real prefix '/tools/python27' 10:12:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:12:18 INFO - Installing distribute.............................................................................................................................................................................................done. 10:12:21 INFO - Installing pip.................done. 10:12:21 INFO - Return code: 0 10:12:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:12:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:12:22 INFO - Reading from file tmpfile_stdout 10:12:22 INFO - Output received: 10:12:22 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) 10:12:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:22 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 0x101acd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97b0cb2ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e1d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018d9b20>, '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.TZft7sE29M/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.pE25vmm5co/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:12:22 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 10:12:22 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 10:12:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TZft7sE29M/Render', 10:12:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:22 INFO - 'HOME': '/Users/cltbld', 10:12:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:22 INFO - 'LOGNAME': 'cltbld', 10:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:22 INFO - 'MOZ_NO_REMOTE': '1', 10:12:22 INFO - 'NO_EM_RESTART': '1', 10:12:22 INFO - 'PAGER': '/bin/cat', 10:12:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:22 INFO - 'PWD': '/builds/slave/test', 10:12:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:22 INFO - 'SHELL': '/bin/bash', 10:12:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pE25vmm5co/Listeners', 10:12:22 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:12:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:22 INFO - 'USER': 'cltbld', 10:12:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:22 INFO - 'XPC_FLAGS': '0x0', 10:12:22 INFO - 'XPC_SERVICE_NAME': '0', 10:12:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:22 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 10:12:22 INFO - Cleaning up... 10:12:22 INFO - Return code: 0 10:12:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:22 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 0x101acd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97b0cb2ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e1d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018d9b20>, '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.TZft7sE29M/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.pE25vmm5co/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:12:22 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 10:12:22 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 10:12:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TZft7sE29M/Render', 10:12:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:22 INFO - 'HOME': '/Users/cltbld', 10:12:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:22 INFO - 'LOGNAME': 'cltbld', 10:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:22 INFO - 'MOZ_NO_REMOTE': '1', 10:12:22 INFO - 'NO_EM_RESTART': '1', 10:12:22 INFO - 'PAGER': '/bin/cat', 10:12:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:22 INFO - 'PWD': '/builds/slave/test', 10:12:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:22 INFO - 'SHELL': '/bin/bash', 10:12:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pE25vmm5co/Listeners', 10:12:22 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:12:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:22 INFO - 'USER': 'cltbld', 10:12:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:22 INFO - 'XPC_FLAGS': '0x0', 10:12:22 INFO - 'XPC_SERVICE_NAME': '0', 10:12:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:22 INFO - Downloading/unpacking psutil>=3.1.1 10:12:22 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 10:12:22 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 10:12:22 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 10:12:22 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 10:12:22 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 10:12:22 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 10:12:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:12:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:12:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:12:26 INFO - Installing collected packages: psutil 10:12:26 INFO - Running setup.py install for psutil 10:12:26 INFO - building 'psutil._psutil_osx' extension 10:12:26 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 10:12:26 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 10:12:26 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 10:12:26 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 10:12:26 INFO - building 'psutil._psutil_posix' extension 10:12:26 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 10:12:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:12:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:12:27 INFO - ^ 10:12:27 INFO - 1 warning generated. 10:12:27 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 10:12:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:12:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:12:27 INFO - Successfully installed psutil 10:12:27 INFO - Cleaning up... 10:12:27 INFO - Return code: 0 10:12:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:12:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:27 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 0x101acd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97b0cb2ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e1d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018d9b20>, '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.TZft7sE29M/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.pE25vmm5co/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:12:27 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 10:12:27 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 10:12:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TZft7sE29M/Render', 10:12:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:27 INFO - 'HOME': '/Users/cltbld', 10:12:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:27 INFO - 'LOGNAME': 'cltbld', 10:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:27 INFO - 'MOZ_NO_REMOTE': '1', 10:12:27 INFO - 'NO_EM_RESTART': '1', 10:12:27 INFO - 'PAGER': '/bin/cat', 10:12:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:27 INFO - 'PWD': '/builds/slave/test', 10:12:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:27 INFO - 'SHELL': '/bin/bash', 10:12:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pE25vmm5co/Listeners', 10:12:27 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:12:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:27 INFO - 'USER': 'cltbld', 10:12:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:27 INFO - 'XPC_FLAGS': '0x0', 10:12:27 INFO - 'XPC_SERVICE_NAME': '0', 10:12:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:12: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 10:12: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 10:12: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 10:12: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 10:12: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 10:12: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 10:12:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:12:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:12:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:12:30 INFO - Installing collected packages: mozsystemmonitor 10:12:30 INFO - Running setup.py install for mozsystemmonitor 10:12:30 INFO - Successfully installed mozsystemmonitor 10:12:30 INFO - Cleaning up... 10:12:30 INFO - Return code: 0 10:12:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:12:30 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')]} 10:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:30 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')]} 10:12:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:30 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 0x101acd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97b0cb2ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e1d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018d9b20>, '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.TZft7sE29M/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.pE25vmm5co/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:12:30 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 10:12:30 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 10:12:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TZft7sE29M/Render', 10:12:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:30 INFO - 'HOME': '/Users/cltbld', 10:12:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:30 INFO - 'LOGNAME': 'cltbld', 10:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:30 INFO - 'MOZ_NO_REMOTE': '1', 10:12:30 INFO - 'NO_EM_RESTART': '1', 10:12:30 INFO - 'PAGER': '/bin/cat', 10:12:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:30 INFO - 'PWD': '/builds/slave/test', 10:12:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:30 INFO - 'SHELL': '/bin/bash', 10:12:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pE25vmm5co/Listeners', 10:12:30 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:12:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:30 INFO - 'USER': 'cltbld', 10:12:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:30 INFO - 'XPC_FLAGS': '0x0', 10:12:30 INFO - 'XPC_SERVICE_NAME': '0', 10:12:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:30 INFO - Downloading/unpacking jsonschema==2.5.1 10:12:30 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 10:12:30 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 10:12:30 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 10:12:30 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 10:12:30 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 10:12:30 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 10:12:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:12:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:12:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:12:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:12:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:12:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:12:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:12:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:12:33 INFO - Downloading functools32-3.2.3-2.tar.gz 10:12:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:12:33 INFO - warning: no files found matching '*.txt' 10:12:33 INFO - no previously-included directories found matching 'build' 10:12:33 INFO - no previously-included directories found matching 'dist' 10:12:33 INFO - no previously-included directories found matching '.git*' 10:12:33 INFO - Installing collected packages: jsonschema, functools32 10:12:33 INFO - Running setup.py install for functools32 10:12:34 INFO - warning: no files found matching '*.txt' 10:12:34 INFO - no previously-included directories found matching 'build' 10:12:34 INFO - no previously-included directories found matching 'dist' 10:12:34 INFO - no previously-included directories found matching '.git*' 10:12:34 INFO - Successfully installed jsonschema functools32 10:12:34 INFO - Cleaning up... 10:12:34 INFO - Return code: 0 10:12:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101acd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97b0cb2ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e1d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018d9b20>, '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.TZft7sE29M/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.pE25vmm5co/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:12:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TZft7sE29M/Render', 10:12:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:34 INFO - 'HOME': '/Users/cltbld', 10:12:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:34 INFO - 'LOGNAME': 'cltbld', 10:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:34 INFO - 'MOZ_NO_REMOTE': '1', 10:12:34 INFO - 'NO_EM_RESTART': '1', 10:12:34 INFO - 'PAGER': '/bin/cat', 10:12:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:34 INFO - 'PWD': '/builds/slave/test', 10:12:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:34 INFO - 'SHELL': '/bin/bash', 10:12:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pE25vmm5co/Listeners', 10:12:34 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:12:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:34 INFO - 'USER': 'cltbld', 10:12:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:34 INFO - 'XPC_FLAGS': '0x0', 10:12:34 INFO - 'XPC_SERVICE_NAME': '0', 10:12:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:12:34 INFO - Cleaning up... 10:12:34 INFO - Return code: 0 10:12:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101acd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97b0cb2ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e1d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018d9b20>, '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.TZft7sE29M/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.pE25vmm5co/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:12:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:12:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TZft7sE29M/Render', 10:12:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:34 INFO - 'HOME': '/Users/cltbld', 10:12:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:34 INFO - 'LOGNAME': 'cltbld', 10:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:34 INFO - 'MOZ_NO_REMOTE': '1', 10:12:34 INFO - 'NO_EM_RESTART': '1', 10:12:34 INFO - 'PAGER': '/bin/cat', 10:12:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:34 INFO - 'PWD': '/builds/slave/test', 10:12:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:34 INFO - 'SHELL': '/bin/bash', 10:12:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pE25vmm5co/Listeners', 10:12:34 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:12:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:34 INFO - 'USER': 'cltbld', 10:12:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:34 INFO - 'XPC_FLAGS': '0x0', 10:12:34 INFO - 'XPC_SERVICE_NAME': '0', 10:12:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:34 INFO - Downloading/unpacking blobuploader==1.2.4 10:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:12:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:12:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:12:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:12:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:12:37 INFO - Downloading blobuploader-1.2.4.tar.gz 10:12:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:12:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:12:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:12:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:12:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:12:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:12:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:12:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:12: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 10:12: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 10:12: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 10:12: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 10:12: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 10:12: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 10:12:38 INFO - Downloading docopt-0.6.1.tar.gz 10:12:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:12:38 INFO - Installing collected packages: blobuploader, requests, docopt 10:12:38 INFO - Running setup.py install for blobuploader 10:12:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:12:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:12:38 INFO - Running setup.py install for requests 10:12:39 INFO - Running setup.py install for docopt 10:12:39 INFO - Successfully installed blobuploader requests docopt 10:12:39 INFO - Cleaning up... 10:12:39 INFO - Return code: 0 10:12:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:12: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')]} 10:12:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12: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')]} 10:12:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:39 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 0x101acd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97b0cb2ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e1d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018d9b20>, '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.TZft7sE29M/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.pE25vmm5co/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:12:39 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 10:12:39 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 10:12:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TZft7sE29M/Render', 10:12:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:39 INFO - 'HOME': '/Users/cltbld', 10:12:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:39 INFO - 'LOGNAME': 'cltbld', 10:12:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:39 INFO - 'MOZ_NO_REMOTE': '1', 10:12:39 INFO - 'NO_EM_RESTART': '1', 10:12:39 INFO - 'PAGER': '/bin/cat', 10:12:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:39 INFO - 'PWD': '/builds/slave/test', 10:12:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:39 INFO - 'SHELL': '/bin/bash', 10:12:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pE25vmm5co/Listeners', 10:12:39 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:12:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:39 INFO - 'USER': 'cltbld', 10:12:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:39 INFO - 'XPC_FLAGS': '0x0', 10:12:39 INFO - 'XPC_SERVICE_NAME': '0', 10:12:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:12:39 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-zpb5K6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:12:39 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-YuEMXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:12:40 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-zXEEHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:12:40 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-MBlnDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:12:40 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-KHnn8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:12:40 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Mg4oqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:12:40 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-G3V9XA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:12:40 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-SlpSyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:12:40 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-bihF9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:12:40 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Gta3lD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:12:40 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-2Vpm2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:12:41 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-HnPMWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:12:41 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Mkp6kA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:12:41 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-aQ9rXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:12:41 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-a_7tcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:12:41 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-QXN8JL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:12:41 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-T7qz7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:12:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:12:41 INFO - Running setup.py install for manifestparser 10:12:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:12:41 INFO - Running setup.py install for mozcrash 10:12:41 INFO - Running setup.py install for mozdebug 10:12:42 INFO - Running setup.py install for mozdevice 10:12:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:12:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:12:42 INFO - Running setup.py install for mozfile 10:12:42 INFO - Running setup.py install for mozhttpd 10:12:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:12:42 INFO - Running setup.py install for mozinfo 10:12:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:12:42 INFO - Running setup.py install for mozInstall 10:12:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:12:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:12:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:12:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:12:42 INFO - Running setup.py install for mozleak 10:12:42 INFO - Running setup.py install for mozlog 10:12:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:12:43 INFO - Running setup.py install for moznetwork 10:12:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:12:43 INFO - Running setup.py install for mozprocess 10:12:43 INFO - Running setup.py install for mozprofile 10:12:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:12:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:12:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:12:43 INFO - Running setup.py install for mozrunner 10:12:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:12:43 INFO - Running setup.py install for mozscreenshot 10:12:43 INFO - Running setup.py install for moztest 10:12:44 INFO - Running setup.py install for mozversion 10:12:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:12:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:12:44 INFO - Cleaning up... 10:12:44 INFO - Return code: 0 10:12:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:12: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')]} 10:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12: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')]} 10:12:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:44 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 0x101acd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97b0cb2ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e1d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018d9b20>, '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.TZft7sE29M/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.pE25vmm5co/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:12:44 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 10:12:44 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 10:12:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TZft7sE29M/Render', 10:12:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:44 INFO - 'HOME': '/Users/cltbld', 10:12:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:44 INFO - 'LOGNAME': 'cltbld', 10:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:44 INFO - 'MOZ_NO_REMOTE': '1', 10:12:44 INFO - 'NO_EM_RESTART': '1', 10:12:44 INFO - 'PAGER': '/bin/cat', 10:12:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:44 INFO - 'PWD': '/builds/slave/test', 10:12:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:44 INFO - 'SHELL': '/bin/bash', 10:12:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pE25vmm5co/Listeners', 10:12:44 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:12:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:44 INFO - 'USER': 'cltbld', 10:12:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:44 INFO - 'XPC_FLAGS': '0x0', 10:12:44 INFO - 'XPC_SERVICE_NAME': '0', 10:12:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:12:44 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-0JLPSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:12:44 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)) 10:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:12:44 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-KOMLC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:12:44 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)) 10:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:12:44 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-WyMPAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:12:44 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-_wcYnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:12:44 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-f7yqwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:12:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:12:45 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-LSbLRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:12:45 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)) 10:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:12:45 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-KheIyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:12:45 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)) 10:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:12:45 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-qIyHk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:12:45 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)) 10:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:12:45 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-fMbS2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:12:45 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)) 10:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:12:45 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-_tY70B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:12:45 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)) 10:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:12:45 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-ZmToXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:12:45 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)) 10:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:12:45 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-kmrtWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:12:45 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)) 10:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:12:45 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Cq2uNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:12:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:12:45 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-JbbbR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:12:45 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)) 10:12:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:12:45 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-YC5lYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:12:46 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)) 10:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:12:46 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-qSfnIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:12:46 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)) 10:12:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:12:46 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-RNX3P6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:12:46 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)) 10:12:46 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)) 10:12:46 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)) 10:12:46 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)) 10:12:46 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)) 10:12:46 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)) 10:12:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:12:46 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 10:12:46 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 10:12:46 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 10:12:46 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 10:12:46 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 10:12:46 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 10:12:48 INFO - Downloading blessings-1.6.tar.gz 10:12:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:12:48 INFO - Installing collected packages: blessings 10:12:48 INFO - Running setup.py install for blessings 10:12:49 INFO - Successfully installed blessings 10:12:49 INFO - Cleaning up... 10:12:49 INFO - Return code: 0 10:12:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:12:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:12:49 INFO - Reading from file tmpfile_stdout 10:12:49 INFO - Current package versions: 10:12:49 INFO - blessings == 1.6 10:12:49 INFO - blobuploader == 1.2.4 10:12:49 INFO - docopt == 0.6.1 10:12:49 INFO - functools32 == 3.2.3-2 10:12:49 INFO - jsonschema == 2.5.1 10:12:49 INFO - manifestparser == 1.1 10:12:49 INFO - mozInstall == 1.12 10:12:49 INFO - mozcrash == 1.0 10:12:49 INFO - mozdebug == 0.1 10:12:49 INFO - mozdevice == 0.48 10:12:49 INFO - mozfile == 1.2 10:12:49 INFO - mozhttpd == 0.7 10:12:49 INFO - mozinfo == 0.9 10:12:49 INFO - mozleak == 0.1 10:12:49 INFO - mozlog == 3.4 10:12:49 INFO - moznetwork == 0.27 10:12:49 INFO - mozprocess == 0.23 10:12:49 INFO - mozprofile == 0.28 10:12:49 INFO - mozrunner == 6.13 10:12:49 INFO - mozscreenshot == 0.1 10:12:49 INFO - mozsystemmonitor == 0.3 10:12:49 INFO - moztest == 0.7 10:12:49 INFO - mozversion == 1.4 10:12:49 INFO - psutil == 3.1.1 10:12:49 INFO - requests == 1.2.3 10:12:49 INFO - wsgiref == 0.1.2 10:12:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:12:49 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')]} 10:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:49 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')]} 10:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:50 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 0x101acd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97b0cb2ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e1d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018d9b20>, '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.TZft7sE29M/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.pE25vmm5co/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:12:50 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 10:12:50 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 10:12:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TZft7sE29M/Render', 10:12:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:50 INFO - 'HOME': '/Users/cltbld', 10:12:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:50 INFO - 'LOGNAME': 'cltbld', 10:12:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:50 INFO - 'MOZ_NO_REMOTE': '1', 10:12:50 INFO - 'NO_EM_RESTART': '1', 10:12:50 INFO - 'PAGER': '/bin/cat', 10:12:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:50 INFO - 'PWD': '/builds/slave/test', 10:12:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:50 INFO - 'SHELL': '/bin/bash', 10:12:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pE25vmm5co/Listeners', 10:12:50 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:12:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:50 INFO - 'USER': 'cltbld', 10:12:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:50 INFO - 'XPC_FLAGS': '0x0', 10:12:50 INFO - 'XPC_SERVICE_NAME': '0', 10:12:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:50 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)) 10:12:50 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)) 10:12:50 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)) 10:12:50 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)) 10:12:50 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)) 10:12:50 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)) 10:12:50 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)) 10:12:50 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)) 10:12:50 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)) 10:12:50 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)) 10:12:50 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)) 10:12:50 INFO - Cleaning up... 10:12:50 INFO - Return code: 0 10:12:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:12:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:12:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:12:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:12:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:12:50 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 0x101acd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a43570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97b0cb2ab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018e1d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018d9b20>, '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.TZft7sE29M/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.pE25vmm5co/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 10:12:50 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 10:12:50 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 10:12:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TZft7sE29M/Render', 10:12:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:12:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:12:50 INFO - 'HOME': '/Users/cltbld', 10:12:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:12:50 INFO - 'LOGNAME': 'cltbld', 10:12:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:12:50 INFO - 'MOZ_NO_REMOTE': '1', 10:12:50 INFO - 'NO_EM_RESTART': '1', 10:12:50 INFO - 'PAGER': '/bin/cat', 10:12:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:12:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:12:50 INFO - 'PWD': '/builds/slave/test', 10:12:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:12:50 INFO - 'SHELL': '/bin/bash', 10:12:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pE25vmm5co/Listeners', 10:12:50 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:12:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:12:50 INFO - 'USER': 'cltbld', 10:12:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:12:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:12:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:12:50 INFO - 'XPC_FLAGS': '0x0', 10:12:50 INFO - 'XPC_SERVICE_NAME': '0', 10:12:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:12:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:12:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:12:51 INFO - Cleaning up... 10:12:51 INFO - Return code: 0 10:12:51 INFO - Running post-action listener: _resource_record_post_action 10:12:51 INFO - Running post-action listener: _start_resource_monitoring 10:12:51 INFO - Starting resource monitoring. 10:12:51 INFO - [mozharness: 2016-12-20 18:12:51.179097Z] Finished create-virtualenv step (success) 10:12:51 INFO - [mozharness: 2016-12-20 18:12:51.180073Z] Running install step. 10:12:51 INFO - Running pre-action listener: _resource_record_pre_action 10:12:51 INFO - Running main action method: install 10:12:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:12:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:12:51 INFO - Reading from file tmpfile_stdout 10:12:51 INFO - Detecting whether we're running mozinstall >=1.0... 10:12:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:12:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:12:51 INFO - Reading from file tmpfile_stdout 10:12:51 INFO - Output received: 10:12:51 INFO - Usage: mozinstall [options] installer 10:12:51 INFO - Options: 10:12:51 INFO - -h, --help show this help message and exit 10:12:51 INFO - -d DEST, --destination=DEST 10:12:51 INFO - Directory to install application into. [default: 10:12:51 INFO - "/builds/slave/test"] 10:12:51 INFO - --app=APP Application being installed. [default: firefox] 10:12:51 INFO - mkdir: /builds/slave/test/build/application 10:12:51 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'] 10:12:51 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 10:13:22 INFO - Reading from file tmpfile_stdout 10:13:22 INFO - Output received: 10:13:22 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:13:22 INFO - Running post-action listener: _resource_record_post_action 10:13:22 INFO - [mozharness: 2016-12-20 18:13:22.705629Z] Finished install step (success) 10:13:22 INFO - [mozharness: 2016-12-20 18:13:22.705781Z] Running run-tests step. 10:13:22 INFO - Running pre-action listener: _resource_record_pre_action 10:13:22 INFO - Running main action method: run_tests 10:13:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:13:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:13:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:13:22 INFO - 2016-12-20 10:13:22.720 screenresolution[1645:7330] starting screenresolution argv=screenresolution get 10:13:22 INFO - 2016-12-20 10:13:22.722 screenresolution[1645:7330] Display 0: 1600x1200x32@60 10:13:22 INFO - 2016-12-20 10:13:22.728 screenresolution[1646:7332] starting screenresolution argv=screenresolution list 10:13:22 INFO - Available Modes on Display 0 10:13:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:13:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:13:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:13:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:13:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:13:22 INFO - Intel Iris: 10:13:22 INFO - Chipset Model: Intel Iris 10:13:22 INFO - Type: GPU 10:13:22 INFO - Bus: Built-In 10:13:22 INFO - VRAM (Dynamic, Max): 1536 MB 10:13:22 INFO - Vendor: Intel (0x8086) 10:13:22 INFO - Device ID: 0x0a2e 10:13:22 INFO - Revision ID: 0x0009 10:13:22 INFO - Displays: 10:13:22 INFO - Display: 10:13:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:13:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:13:22 INFO - Display Serial Number: 1600x1200 60 10:13:22 INFO - Main Display: Yes 10:13:22 INFO - Mirror: Off 10:13:22 INFO - Online: Yes 10:13:22 INFO - Rotation: Supported 10:13:22 INFO - Return code: 0 10:13:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:13:22 INFO - Python 2.7.3 10:13:22 INFO - Return code: 0 10:13:22 INFO - grabbing minidump binary from tooltool 10:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:13:22 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 0x1018e1d30>, '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 0x101747db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018d9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:13:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:13:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:13:22 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 10:13:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:13:23 INFO - Return code: 0 10:13:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:13:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:13:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:13:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:13:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:13:23 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/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:13:23 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/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0314 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:13:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TZft7sE29M/Render', 10:13:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:13:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:13:23 INFO - 'HOME': '/Users/cltbld', 10:13:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:13:23 INFO - 'LOGNAME': 'cltbld', 10:13:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:13:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:13:23 INFO - 'MOZ_NO_REMOTE': '1', 10:13:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:13:23 INFO - 'NO_EM_RESTART': '1', 10:13:23 INFO - 'PAGER': '/bin/cat', 10:13:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:13:23 INFO - 'PWD': '/builds/slave/test', 10:13:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:13:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:13:23 INFO - 'SHELL': '/bin/bash', 10:13:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pE25vmm5co/Listeners', 10:13:23 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 10:13:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:13:23 INFO - 'USER': 'cltbld', 10:13:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:13:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:13:23 INFO - 'XPC_FLAGS': '0x0', 10:13:23 INFO - 'XPC_SERVICE_NAME': '0', 10:13:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:13:23 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/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:13:23 INFO - mozversion application_buildid: 20161220074553 10:13:23 INFO - mozversion application_changeset: 18ca74ed9f75b19ff614801c27e2ab50b10bd704 10:13:23 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:13:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:13:23 INFO - mozversion application_name: Firefox 10:13:23 INFO - mozversion application_remotingname: firefox-dev 10:13:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:13:23 INFO - mozversion application_vendor: Mozilla 10:13:23 INFO - mozversion application_version: 52.0a2 10:13:23 INFO - mozversion platform_buildid: 20161220074553 10:13:23 INFO - mozversion platform_changeset: 18ca74ed9f75b19ff614801c27e2ab50b10bd704 10:13:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:13:23 INFO - mozversion platform_version: 52.0a2 10:13:23 INFO - using testdate: 1482257603 10:13:23 INFO - actual date: 1482257603 10:13:23 INFO - starting webserver on 'localhost:49242' 10:13:23 INFO - SUITE-START | Running 2 tests 10:13:23 INFO - TEST-START | dromaeo_css 10:13:23 INFO - operating with platform_type : mac_ 10:13:23 INFO - Initialising browser for dromaeo_css test... 10:13:23 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpHFf1oR/profile http://localhost:49242/getInfo.html) 10:13:26 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 10:13:26 INFO - PROCESS | 1656 | colorDepth:24 10:13:26 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 10:13:26 INFO - PROCESS | 1656 | __metrics 10:13:26 INFO - PROCESS | 1656 | 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" 10:13:26 INFO - PROCESS | 1656 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:13:26 INFO - TEST-INFO | 1656: exit 0 10:13:26 INFO - Browser initialized. 10:13:26 INFO - Running cycle 1/1 for dromaeo_css test... 10:13:26 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpHFf1oR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:13:39 INFO - PROCESS | 1658 | RSS: Main: 222466048 10:13:39 INFO - PROCESS | 1658 | 10:14:30 INFO - PROCESS | 1658 | 1482257670162 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:16:45 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 10:16:47 INFO - PROCESS | 1658 | RSS: Main: 389099520 10:16:47 INFO - PROCESS | 1658 | 10:19:49 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 10:19:50 INFO - PROCESS | 1658 | RSS: Main: 349536256 10:19:50 INFO - PROCESS | 1658 | 10:22:52 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 10:22:53 INFO - PROCESS | 1658 | RSS: Main: 348045312 10:22:53 INFO - PROCESS | 1658 | 10:25:55 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 10:25:56 INFO - PROCESS | 1658 | RSS: Main: 329244672 10:25:56 INFO - PROCESS | 1658 | 10:28:59 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 10:29:00 INFO - PROCESS | 1658 | RSS: Main: 311656448 10:29:00 INFO - PROCESS | 1658 | 10:32:03 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 10:32:03 INFO - PROCESS | 1658 | RSS: Main: 362323968 10:32:03 INFO - PROCESS | 1658 | 10:32:03 INFO - PROCESS | 1658 | __start_tp_report 10:32:03 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 10:32:03 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 10:32:03 INFO - PROCESS | 1658 | |i|pagename|runs| 10:32:03 INFO - PROCESS | 1658 | |0;dojo.html;3121.9531707024385;3259.869605215791;3280.2455435249885;3299.2411745298596;3345.297487527619;5299.735013249332;5495.477929596687;5584.357798853128;5621.971890140544;5657.434256574341;5563.944360556393;5647.237622920905;5664.20701101846;5671.517920976712;5705.48650621444;4455.933161002587;5147.974260128694;5157.742112894359;5181.766820493068;5208;5092.694438333693;5105.668131571436;5114.846554603349;5146.639735218536;5261;5202.219667049935;5232;5234.057869583474;5235.764390602432;5255.789768409278;5210.739463026834;5224.190250511177;5229.790808367661;5231.686098834082;5234.895302093937;5105.234214867763;5178.948210517922;5184.8963020739375;5186.325777648919;5193.584513238932;5713.228714123582;5716.79991200306;5718.628289161191;5720.342160651537;5725.169850371714;6104.114903338991;6104.511639068864;6105.572609917308;6127.050307202347;6127.969360153171;6161.353057928898;6176.4132407421675;6189.442950134509;6215.7513699451965;6237.438630523275;5691.886162276773;5737.168110623936;5740.512056475204;5744.080947048494;5744.511716504102;5005.949940500548;6294.433500984887;6331.01869210264;6339.746410143634;6343.936560634381;5955.076963070731;5999.940000599938;6020.397960203944;6022.69886505673;6029.457348838626;6175.783847565313;6237.409460587612;6273.811785646347;6282.340354262828;6292.119103325538;6205.348438413992;6215.06773983906;6217.564770466024;6236.532260080512;6244.344343843922;6071.574989750675;6082.422169893853;6109.594897568601;6143.662098584531;6152.507799376039;4825.3485779419325;4875.171220892456;4897.657163998557;4900.926486102711;4906.533879281463;4364.148990946735;4388.736675799462;4390.714603550759;4410.117976404732;4412;5507.476789704971;5533.501984821305;5573.693446860461;5620.5503559715125;5659.603827732019;5117.616178786606;6138.419052470195;6149.477294429934;6184.6598437085495;6216.285127210338;4747.501512341223;5674.0637794764225;5682.829515114553;5689.886202275931;5727.4845263926445;5270.235815806686;5337.332833395825;5338.492843179892;5353.652012619167;5387.784488620489;5040.4455509894615;6232.25212974446;6273.121762953099;6303.6217826929615;6331.113644089924;6135.416406698409;6177.1969643945995;6185.226846644169;6211.627302361872;6273.99616061428;6308.148399965946;6338;6341.492680585634;6349.682515874096;6366.554341196072;6154.19995400613;6183.567150299616;6213.937860621336;6215.378462153749;6233.594816336924;4909.68087074339;6028.939710603014;6115.4190351916495;6117.571769976059;6142.0076949981085;5927.199828023162;5927.377625349276;6000.339962604023;6054.424829641177;6085.391460853879;5999.52003839683;6020.096985452042;6045.365236650088;6047.304559975659;6055.122007308827;5671.376148623565;5859.2090067842;5876.853078673067;5879.20630714848;5888.676122813286;5888.852778680396;6011.87976240464;6044.063170208625;6054;6129.542179248731;4870.269459581091;5810.954028274951;5814.04068328738;5820.476157145878;5886.05823068307 10:32:03 INFO - PROCESS | 1658 | |1;ext.html;476.8474088291748;481.0955403840779;516.08652684748;522.8039485193052;548.2625868207265;29820.552691709614;30563.235919102033;31473.89841355553;38290.23419531608;39935;40415.79792101035;40794.7960260199;40965.7951710241;41026.79486602563;41063.38404923921;9162.26701863851;9332.626694932194;9342.346035777498;9345.532723363805;9355.953220233889;9416.435013899154;9514.666986655468;9537.093976072261;9537.380070295443;9552.761180970496;9515.666951656693;9552.235821134327;9558.235341172724;9558.761030974212;9563.90436095637;9409.388389754713;9435.858462123073;9495.003024682412;9524.571394287274;9544;9252.444853308823;9331.906680933173;9343.159115679622;9347.672831450867;9369.672061477815;38593.42109868354;38905.83282501529;38924.80537597322;38962.22075558501;39073.80463097695;3253.544503769474;3298.472244440878;3309.1561651778643;3318.5188147718686;3338.21551935295;28558.571621425694;29274.121776346743;29355.55966660502;29411.852940735374;29959.700402996128;3082.260257538197;3117.610298712661;3159.304952910356;3174.8095114293214;3178.284885900654;487991;492564;493259;493277.53361233324;505289;58989.525261868795;59438;59527;59589.70205148947;59672.80654386888;34254.314913701586;35050.82474587611;35343.646563534036;36072;62986.0552091719;15531.922340388226;15685.13731744765;15711.68576628484;15711.764323535157;15726.21368931549;11504.597339093092;11963;12015;12071.818922716166;12101.15291929574;18439.53901152482;18488.445346639623;18507.722384164248;18520.907395463208;18574.071296435126;7640.73257435987;7654.82051384256;7718.3053525183;7770.145284018753;7788.727394541163;808.1716240853056;864.464032299978;899.9325050621229;922.7404592730874;1011.9949400252951;20539.58920821605;25125.497490050097;25241;25452.618210726854;25550.744492555208;987.9703608891745;990.8711867457184;1011.2112552209142;1066.1204506282347;1067.4342598422847;12668.49326026967;12966.611001669966;13208.933955330162;13291.601251962456;13329.800052999213;664981;668836;670179;671304;672793;55515;55945;56085.15872261919;56186.43813561812;56368.71815640978;1228.177121328712;1263.7409331086922;1265.569706299844;1373.2653030628765;1396.818413606245;19473.707894381878;26508.204753857415;26618.66906654621;26752.8662356687;27203.72796272012;52061;52750;52761.7361913188;52865.20702189393;52887;615468;622401;623512;624177.8791106015;624949;22920.083196671945;23457.88271058634;25410.618840717034;25807.741922581285;26187.738122619292;43399;43541.564584355016;44354.334684979105;44451.525531745814;44547.777261113486;36294.548218072036;36666.8166659165;36879;36989.815050924575;37543.43684844784;36429.08927276839;36821.63178368182;37946;37983.86048418552;38064.85805425842;28621;30864.074077777703;31326;31383.372332553678;31800.681993180697;27064;31722;32545.837270813496;33099.83450082734;33235.50146747849;21951.670724939457;22117.33647990563;22150.113995440002;22535.985880635737;22622.321330360115 10:32:03 INFO - PROCESS | 1658 | |2;jquery.html;351.6237625740459;371.6005294308618;375.58310275594084;385.13537109189866;406.41679190361833;14608.926955365221;14915.627109322273;15497.690046199083;19755.91342476163;20911.16355345788;19275.807241927578;19599;20092.296769613065;20218.696719549218;20577.27979520717;5840.620359676607;5860.489581627553;6055.394460553932;6081.604695694788;6088.482478989291;4598.586127248547;5947.107933809942;6017.157597936292;6018.939810601903;6022.397760223987;5561.4716601922755;5807.564432667546;5946.762129514815;5950.04799232123;5972.820815375546;5788.652680839142;5905.61218113743;5906;5912.113183022581;5918.556108291896;5570.888582228374;5723.627964182357;5731.971340143315;5750.309962804448;5760.6255593386295;13188.736225275441;13389.799153012713;13394.263315517643;15308.770368444482;24578.262652120466;2239.8992045357836;2303.090279339668;2313.803326717231;2417.842840215398;2452.1785201957364;9665.100469859015;9675.516224188832;10263.640772572999;10357.326773759756;10385.63650272237;1830.20386132033;1857.544901499128;1884.8209420105068;1892.2714754819356;1915.1477592471356;117007.41496292464;123463.38268308691;125305;126409;128106;11169.44152792357;11333.546658133748;11419.885801141882;11688.94155529217;11880.524779008916;14542.636434089021;14547.27263636814;14552.981291309507;14554.417823287162;14588.270586470846;8292.958535207285;8403.327733781283;8416;8426.410151289474;8588.785280367916;8242.670293188325;8274.544900030436;8361.540115293596;8420.663173473115;8434.325253979667;3766.6798322142927;4545.954540454553;4592.770361481913;4597.42532183477;4648.1865673507;1857.9933671634872;1882.8870267783702;1898.1078892920304;1910.3982245592595;1914.7606549181353;634.5558109323492;718.1454069657195;752.5559919647434;818.9426740128253;992.7964767222846;15306.464273750365;17105.059221742675;17808.91095544514;18533.90733046353;18656.53358666017;907.6596276396351;908.5366463103733;914.7118657622827;918.3571499949928;934.5654270764128;9036.638534458549;9628.47043412619;9664.371815831948;9690.515474226402;9797.608095676236;596839;605862;613871;628274;629105;106383.46808265909;114109.28836067466;119356.40321798336;121257.39371303264;129907;916.2074805293373;938.1697197979701;963.8602402651717;964.0600414595854;969.5394687523511;16598.17009149562;16820.57948551296;16829.747553786947;16966.74549881728;16988.895721778044;52222;54256.72871635616;56907.43092569021;57253.854922902145;57815.554611135056;81468;82068.58965705133;82079;82928;84262.31475370581;7926.762197134095;7962.243586859239;7970.880436793337;8012.439129260896;8115.75652730419;9825.950870245602;12266.815997760219;12287.262764234174;12373;12692.746145076862;9336.53317334117;10182.796344073226;10383.923215356808;10542.8418573723;10701.732456688645;10508.106810920852;10555.102816260704;10659.89340106589;10915.727106822393;11114.555417783522;10493.422861742678;10743.355398676105;10812.156456624014;10924.453777311262;10994.450277486254;7912.3274521664;7935.246151615587;8100.473469224482;8101.959490202511;8200.01599808027;8137.511749295061;10329.328593641389;10429.26995110335;10534;10620.89379106199 10:32:03 INFO - PROCESS | 1658 | |3;mootools.html;1300.9154404963676;1423.921684307363;1539.4457995121754;1732.3763445159748;1775.6093659394928;8171.877421838677;8546.70086546971;8549.529775862326;8551.828963420729;8567.357448191387;7502.774916752493;7776.105747839;7783.92216077839;8012;8358.791030224247;2291.0835665733716;2312.9768702313013;2355.6702061711376;2356.2695564375013;2360.0441821062495;2348.3307257431634;2371.300466362419;2391.079434417753;2393.9760602393926;2394.2338451695464;2305.124052859919;2332.8716920569364;2354.1760383865594;2359.9646005309933;2361.1027809432476;2318.1654604342425;2324.976750232501;2345.988270058656;2347.694799676048;2347.835651504395;2289.267434420986;2348.330725743172;2352.094443639194;2357.481354102095;2363.7399886012527;8284.792880177984;8346.916530834735;8355.164483551656;8367.99584049918;8391.244787969052;5102.69383836971;5153.819616313449;5240.056789777838;5262.210668399733;5264.210368444726;3209.3878832895393;3339.482380231068;3459.6713312235297;3468.0462872709954;3476.1657202271526;1036.9081977866576;1051.7738686182431;1069.9679009629724;1070.218740319571;1091.410638255349;10655.627053053184;10772.245942784008;10879.020888120107;10886.564537418415;10928.508217130247;4394.209042372339;4596.103759766814;4700.55344742249;4708.223143181337;4714.764261786965;9489.857652135224;9515.381500202464;9518.381305215138;9521.143097121292;9524.904750952403;5187.429382767892;5228.686278823283;5233.267342572043;5247.396549396792;5261.237120617489;4280.91438171241;4442.955570444255;4608;4670.7664616769025;4754.381930349033;3177.6822317768037;3778.6977041836585;3878.2437424702475;3921.8823435296986;3924;1045.2369770067742;1177.3995262416229;1190.4345435918042;1274.2099898063261;1285.8521270053877;740.3558903753768;746.7349091072699;819.4714409206148;820.7278717987097;851.1105894340429;5157.613179011589;5210.452902445264;5230.973845130826;5316.548093412026;5372.650777699437;426.7247625281676;428.4815373398223;435.2057495071507;447.48092213032726;448.0546047839057;2707.9864600677265;3438.381091403571;3493.2314890724;3497.1606814364877;3579.749417540799;9664.613415463302;9892.208623310118;10040.79918401628;10163.542640581192;10169.13562347194;9874.358166719141;9908.306418550632;10135;10136.138428233535;10151.238657100746;7479.513831600929;7735.806604834812;7873.055233371918;7908.802279943047;7908.920910790818;5109.5912327013;5129.563987061141;5196.480351964925;5320.255164277081;5373.408925018323;9421.434713917188;9431.434113953184;9446.866376034919;9476;9479.241660666992;9060.275177985613;9422.869255689362;9457.716268511957;9508.762280943032;9533.856992144985;6653.534252602271;6857.862842743217;6871.5189936703955;7162.928370716226;7338.412926965937;6423.96788016057;7855.175206603224;8024.919750802417;8033.517988920684;8091.919080809352;6835;7153.427725781821;7412.11054673421;7420.740274090381;7486.43851711113;7223.097112860893;7283.271672832674;7431.182569917413;7445.4043676506835;7473.40212782987;5283.445238250082;6527.3472652734345;6618.933810662024;6643;6654.567453115532;4681.776215862059;4726.976365118152;4826.734529600905;4830.1305764961235;4880.365552478155;4778.402699662543;4779.856604301876;4852.951470485391;4868.002059577849;4922.138625740553 10:32:03 INFO - PROCESS | 1658 | |4;prototype.html;296.4574828064642;299.4011976047906;304.9828820882357;312.75136266668;314.83313843662836;5104.886019528085;5202.895942081156;5237.397699264581;5252.89494210116;5276.34045744282;5140.922886156702;5169.224616307546;5268.604854635899;5335.653182543132;5386.622936394444;4088.550259471456;4097.487814023247;4106.733062350952;4152.584741525839;4172.165566886612;3624.601293857687;3850.4609354690365;4109.465769449967;4114;4153.210889930903;3601.9099522511883;3711.962880371202;3899.005753532845;4098.241825262321;4167.6249137577615;3440.569928758905;4060.9593904060876;4071.8850079688914;4097.938530922039;4114.876553703379;3576.78539287641;3587.3542762302777;3600.4239321708665;3605.6574625410544;3637.6907962823193;4337.850469625551;4860.6111511079025;5052.093473713627;5207.739613019334;5316.734163291859;3933.3116704576582;4673.369095172142;4682.484926658065;4703.2239680452685;4714.764261786897;4807.18277892759;5545.500904918536;5573.41479144688;5577.776888924438;5584.944150558483;3487.442009278503;3600.973722489104;3642.653947874974;3977.8066580025875;4000.739951903117;5752.712364381806;5779.757352169304;5782.45066718661;5790.536757059467;5820.35976042635;4009.7594144351433;4017.5982401760175;4033.2941735196223;4039.7374170678813;4040.393940908887;3653.6529029742132;3661.8901432956523;3887.416887466846;4087.652549533323;4098.094386110473;4285.314349704033;4465.620422264079;4636.420447444069;4686.27362758773;4737.028909073631;5568.32720294261;5593.944060559343;5700.23046888673;5713.171590119493;5722.599418040697;1063.8776540697763;1240.3922078181627;1266.0631132961726;1279.9680007999875;1328.2960031183488;785.8153333966513;792.6987744656993;886.3263919421287;898.9527200811112;920.7156017355751;4357.9564204358185;4642.675012749075;4679.789409476582;4693.568461619239;4891.26631005352;4585.839495617704;4965.503449655005;5143.665661731976;5340.225667278223;5347.51872331492;4309.547497512779;4585.72485650862;4750.976245118753;4841.806327746851;4883.731394773251;2654.389490417176;2677.7723893468883;2787.8466684332557;2837.943241135166;2838.6722887232686;4640.605548528413;4886.560209581155;4932.372317135409;5022.070916880389;5472.480114389126;4478.7088839226;4849.466558678542;4931.926021109686;5516.475934786269;5528.237743177029;4507.864764057083;4632.907341853144;5213.977855576663;5488.506034456918;5533.261035787311;4394.121850188132;5206.297149884868;5341.759620816995;5397.757100930546;5406.459354064562;4427.80074896624;5129.9743501282255;5399.379071406838;5422.526415707136;5660.943390566042;5385.569154467555;5779.624324418899;5797.6521408713925;5798.797042103506;5817.825465236049;3384.2418428980304;3834.9808250958567;3871;3875.341191997311;3914.2954268231993;4935.876603084952;5336.279602253646;5715.342735585295;5724;5762.4525670061275;5012;5049.747512624427;5527;5532.391436941853;5540.501354877919;4958.496069362932;5518.834434966958;5577.326801959477;5588.524975377139;5626.2685850839125;5193.610479213999;5477.424870388551;5491.972540137273;5582.525485333792;5617.494425501561;5262.97368513155;5285.312909321918;5596.38439771633;5599.524040456607;5626.409227031103;4440.800163992563;4621.260598304255;5483.341998960151;5520.53075488588;5536.7785288588 10:32:03 INFO - PROCESS | 1658 | |5;yui.html;707.9681414336354;736.0247671834817;754.6679461037144;761.7600455856408;808.6926967752255;14125.717485650293;14261.50084747034;14540.345684444199;14714.632134196623;14781.26093695315;12551.874481255185;13076.411561479732;13200.867991320083;13217.339133043333;13226;629.5309994054436;794.622554286715;805.0701439836997;807.6809660184224;809.7165991902816;801.9438639295252;807.5033854179696;807.9940474109742;808.8139727862596;810.3193317613204;849.7705619482736;851.2041241439243;851.6593362654926;853.5177624642089;853.7609469348592;841.2092632925062;844.6368061733452;846.6147902704255;846.9237768600856;848.4612271207797;619.2507066449601;623.1742940603718;624.406813527151;625.3964923848463;625.5933643131955;12341.876581234252;12486.25082495044;12637.368131593475;12679;12704;11452.427378631119;11507.482163302588;11693.883061169365;11732.589359372381;11748.353840538766;10049.346792458467;10211.081002709792;10279.331843430227;10300.227482938735;10485.318454300523;3021.9358036931467;3439.208981934144;3486.9128271793156;3610.2598967211657;3642.1805093853936;87742;89443.55278223632;92018.61972070357;92514.53742731243;92583.53708231416;8776.561171941377;8778.780530486789;8779.429337093068;9284.164425201629;9544.856827147598;19222.519437013907;19470.929098899414;19523.926184060012;19583;19596.608067838562;6859.314068593101;7031.753888613874;7098.68055937484;7393.630318484162;7468.626568671653;3922.627350401707;4053.6217685986317;4061.0401123584597;4159.355299928516;4187.476565429321;3220.162757683019;3357.8992630221132;3367.6127245367097;3368.7136593389346;3388.1360253135786;617.0867116667357;620.5097972601594;630.6972653126525;632.8860805055042;636.3445650979498;478.88746144655977;534.5189329603311;570.6262398129232;584.2200662116122;586.633354153654;7489.662965166581;8009.719659811879;8017.198280171936;8132.634031468479;8283.337333013344;535.6545028456624;535.6732393240198;579.9623024503478;592.2301008688773;636.8726254749068;3833.3674943633987;4032.495938007749;4129.587041295847;4163.542010378856;4170.457840480718;68589.65705171542;68636.313636864;69014;105982;107143.46428267809;63979.72040559256;86544;87940.5602971981;90624.18751624931;93726;59311;83093.58453207817;83143.5842820782;83371.58314208512;85953.28093438222;6788.370791010221;8037.397195210266;8178.7955301117945;8254.83490330202;8271.95864020676;43659.56340436555;50429.49570504248;51314.74342628412;51901.7404912973;52078.73960630173;72233.98095085994;73278.63360683163;73786;75880.30633260673;77288.6135569341;11675.649730508097;11861.881381186078;12232.355076427371;12375.69060773488;12978.870211297766;12650.367481625697;13518.59444216675;13940.02419830602;14207.28963551839;14734.484293049692;11828.34944078084;11850.940745296219;12581;12607.180533265675;12841.486340546273;12232.510699571918;12762.42569084412;12780.105392622427;13037.47850085986;13618.38717257706;11486.885131148581;12002.279863208236;12583.370831458573;12589.118761686961;12653.367331633566;10471.947640262055;10996.395198263852;11421.960244556763;11639.243449175776;11741.354225517678;10252.743681408025;10724.195685323788;10770.838437423288;10786.892131078903;10836.295640783324 10:32:03 INFO - PROCESS | 1658 | __end_tp_report 10:32:03 INFO - PROCESS | 1658 | __start_cc_report 10:32:03 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1951 10:32:03 INFO - PROCESS | 1658 | __end_cc_report 10:32:03 INFO - PROCESS | 1658 | __startTimestamp1482258723758__endTimestamp 10:32:03 INFO - PROCESS | 1658 | 10:32:03 INFO - PROCESS | 1658 | ------- Summary: start ------- 10:32:03 INFO - PROCESS | 1658 | Number of tests: 6 10:32:03 INFO - PROCESS | 1658 | 10:32:03 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5651.19 Median:5814.04 stddev:656.88 (11.3%) stddev-sans-first:628.21 10:32:03 INFO - PROCESS | 1658 | Values: 3122.0 3259.9 3280.2 3299.2 3345.3 5299.7 5495.5 5584.4 5622.0 5657.4 5563.9 5647.2 5664.2 5671.5 5705.5 4455.9 5148.0 5157.7 5181.8 5208.0 5092.7 5105.7 5114.8 5146.6 5261.0 5202.2 5232.0 5234.1 5235.8 5255.8 5210.7 5224.2 5229.8 5231.7 5234.9 5105.2 5178.9 5184.9 5186.3 5193.6 5713.2 5716.8 5718.6 5720.3 5725.2 6104.1 6104.5 6105.6 6127.1 6128.0 6161.4 6176.4 6189.4 6215.8 6237.4 5691.9 5737.2 5740.5 5744.1 5744.5 5005.9 6294.4 6331.0 6339.7 6343.9 5955.1 5999.9 6020.4 6022.7 6029.5 6175.8 6237.4 6273.8 6282.3 6292.1 6205.3 6215.1 6217.6 6236.5 6244.3 6071.6 6082.4 6109.6 6143.7 6152.5 4825.3 4875.2 4897.7 4900.9 4906.5 4364.1 4388.7 4390.7 4410.1 4412.0 5507.5 5533.5 5573.7 5620.6 5659.6 5117.6 6138.4 6149.5 6184.7 6216.3 4747.5 5674.1 5682.8 5689.9 5727.5 5270.2 5337.3 5338.5 5353.7 5387.8 5040.4 6232.3 6273.1 6303.6 6331.1 6135.4 6177.2 6185.2 6211.6 6274.0 6308.1 6338.0 6341.5 6349.7 6366.6 6154.2 6183.6 6213.9 6215.4 6233.6 4909.7 6028.9 6115.4 6117.6 6142.0 5927.2 5927.4 6000.3 6054.4 6085.4 5999.5 6020.1 6045.4 6047.3 6055.1 5671.4 5859.2 5876.9 5879.2 5888.7 5888.9 6011.9 6044.1 6054.0 6129.5 4870.3 5811.0 5814.0 5820.5 5886.1 10:32:03 INFO - PROCESS | 1658 | 10:32:03 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:70551.82 Median:38177.55 stddev:160972.50 (421.6%) stddev-sans-first:161338.09 10:32:03 INFO - PROCESS | 1658 | Values: 476.8 481.1 516.1 522.8 548.3 29820.6 30563.2 31473.9 38290.2 39935.0 40415.8 40794.8 40965.8 41026.8 41063.4 9162.3 9332.6 9342.3 9345.5 9356.0 9416.4 9514.7 9537.1 9537.4 9552.8 9515.7 9552.2 9558.2 9558.8 9563.9 9409.4 9435.9 9495.0 9524.6 9544.0 9252.4 9331.9 9343.2 9347.7 9369.7 38593.4 38905.8 38924.8 38962.2 39073.8 3253.5 3298.5 3309.2 3318.5 3338.2 28558.6 29274.1 29355.6 29411.9 29959.7 3082.3 3117.6 3159.3 3174.8 3178.3 487991.0 492564.0 493259.0 493277.5 505289.0 58989.5 59438.0 59527.0 59589.7 59672.8 34254.3 35050.8 35343.6 36072.0 62986.1 15531.9 15685.1 15711.7 15711.8 15726.2 11504.6 11963.0 12015.0 12071.8 12101.2 18439.5 18488.4 18507.7 18520.9 18574.1 7640.7 7654.8 7718.3 7770.1 7788.7 808.2 864.5 899.9 922.7 1012.0 20539.6 25125.5 25241.0 25452.6 25550.7 988.0 990.9 1011.2 1066.1 1067.4 12668.5 12966.6 13208.9 13291.6 13329.8 664981.0 668836.0 670179.0 671304.0 672793.0 55515.0 55945.0 56085.2 56186.4 56368.7 1228.2 1263.7 1265.6 1373.3 1396.8 19473.7 26508.2 26618.7 26752.9 27203.7 52061.0 52750.0 52761.7 52865.2 52887.0 615468.0 622401.0 623512.0 624177.9 624949.0 22920.1 23457.9 25410.6 25807.7 26187.7 43399.0 43541.6 44354.3 44451.5 44547.8 36294.5 36666.8 36879.0 36989.8 37543.4 36429.1 36821.6 37946.0 37983.9 38064.9 28621.0 30864.1 31326.0 31383.4 31800.7 27064.0 31722.0 32545.8 33099.8 33235.5 21951.7 22117.3 22150.1 22536.0 22622.3 10:32:03 INFO - PROCESS | 1658 | 10:32:03 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:34933.08 Median:2476.19 stddev:102579.98 (4142.7%) stddev-sans-first:102834.88 10:32:03 INFO - PROCESS | 1658 | Values: 351.6 371.6 375.6 385.1 406.4 14608.9 14915.6 15497.7 19755.9 20911.2 19275.8 19599.0 20092.3 20218.7 20577.3 5840.6 5860.5 6055.4 6081.6 6088.5 4598.6 5947.1 6017.2 6018.9 6022.4 5561.5 5807.6 5946.8 5950.0 5972.8 5788.7 5905.6 5906.0 5912.1 5918.6 5570.9 5723.6 5732.0 5750.3 5760.6 13188.7 13389.8 13394.3 15308.8 24578.3 2239.9 2303.1 2313.8 2417.8 2452.2 9665.1 9675.5 10263.6 10357.3 10385.6 1830.2 1857.5 1884.8 1892.3 1915.1 117007.4 123463.4 125305.0 126409.0 128106.0 11169.4 11333.5 11419.9 11688.9 11880.5 14542.6 14547.3 14553.0 14554.4 14588.3 8293.0 8403.3 8416.0 8426.4 8588.8 8242.7 8274.5 8361.5 8420.7 8434.3 3766.7 4546.0 4592.8 4597.4 4648.2 1858.0 1882.9 1898.1 1910.4 1914.8 634.6 718.1 752.6 818.9 992.8 15306.5 17105.1 17808.9 18533.9 18656.5 907.7 908.5 914.7 918.4 934.6 9036.6 9628.5 9664.4 9690.5 9797.6 596839.0 605862.0 613871.0 628274.0 629105.0 106383.5 114109.3 119356.4 121257.4 129907.0 916.2 938.2 963.9 964.1 969.5 16598.2 16820.6 16829.7 16966.7 16988.9 52222.0 54256.7 56907.4 57253.9 57815.6 81468.0 82068.6 82079.0 82928.0 84262.3 7926.8 7962.2 7970.9 8012.4 8115.8 9826.0 12266.8 12287.3 12373.0 12692.7 9336.5 10182.8 10383.9 10542.8 10701.7 10508.1 10555.1 10659.9 10915.7 11114.6 10493.4 10743.4 10812.2 10924.5 10994.5 7912.3 7935.2 8100.5 8102.0 8200.0 8137.5 10329.3 10429.3 10534.0 10620.9 10:32:03 INFO - PROCESS | 1658 | 10:32:03 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5397.91 Median:5012.42 stddev:3015.31 (60.2%) stddev-sans-first:3008.05 10:32:03 INFO - PROCESS | 1658 | Values: 1300.9 1423.9 1539.4 1732.4 1775.6 8171.9 8546.7 8549.5 8551.8 8567.4 7502.8 7776.1 7783.9 8012.0 8358.8 2291.1 2313.0 2355.7 2356.3 2360.0 2348.3 2371.3 2391.1 2394.0 2394.2 2305.1 2332.9 2354.2 2360.0 2361.1 2318.2 2325.0 2346.0 2347.7 2347.8 2289.3 2348.3 2352.1 2357.5 2363.7 8284.8 8346.9 8355.2 8368.0 8391.2 5102.7 5153.8 5240.1 5262.2 5264.2 3209.4 3339.5 3459.7 3468.0 3476.2 1036.9 1051.8 1070.0 1070.2 1091.4 10655.6 10772.2 10879.0 10886.6 10928.5 4394.2 4596.1 4700.6 4708.2 4714.8 9489.9 9515.4 9518.4 9521.1 9524.9 5187.4 5228.7 5233.3 5247.4 5261.2 4280.9 4443.0 4608.0 4670.8 4754.4 3177.7 3778.7 3878.2 3921.9 3924.0 1045.2 1177.4 1190.4 1274.2 1285.9 740.4 746.7 819.5 820.7 851.1 5157.6 5210.5 5231.0 5316.5 5372.7 426.7 428.5 435.2 447.5 448.1 2708.0 3438.4 3493.2 3497.2 3579.7 9664.6 9892.2 10040.8 10163.5 10169.1 9874.4 9908.3 10135.0 10136.1 10151.2 7479.5 7735.8 7873.1 7908.8 7908.9 5109.6 5129.6 5196.5 5320.3 5373.4 9421.4 9431.4 9446.9 9476.0 9479.2 9060.3 9422.9 9457.7 9508.8 9533.9 6653.5 6857.9 6871.5 7162.9 7338.4 6424.0 7855.2 8024.9 8033.5 8091.9 6835.0 7153.4 7412.1 7420.7 7486.4 7223.1 7283.3 7431.2 7445.4 7473.4 5283.4 6527.3 6618.9 6643.0 6654.6 4681.8 4727.0 4826.7 4830.1 4880.4 4778.4 4779.9 4853.0 4868.0 4922.1 10:32:03 INFO - PROCESS | 1658 | 10:32:03 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4429.96 Median:4872.17 stddev:1334.37 (27.4%) stddev-sans-first:1301.55 10:32:03 INFO - PROCESS | 1658 | Values: 296.5 299.4 305.0 312.8 314.8 5104.9 5202.9 5237.4 5252.9 5276.3 5140.9 5169.2 5268.6 5335.7 5386.6 4088.6 4097.5 4106.7 4152.6 4172.2 3624.6 3850.5 4109.5 4114.0 4153.2 3601.9 3712.0 3899.0 4098.2 4167.6 3440.6 4061.0 4071.9 4097.9 4114.9 3576.8 3587.4 3600.4 3605.7 3637.7 4337.9 4860.6 5052.1 5207.7 5316.7 3933.3 4673.4 4682.5 4703.2 4714.8 4807.2 5545.5 5573.4 5577.8 5584.9 3487.4 3601.0 3642.7 3977.8 4000.7 5752.7 5779.8 5782.5 5790.5 5820.4 4009.8 4017.6 4033.3 4039.7 4040.4 3653.7 3661.9 3887.4 4087.7 4098.1 4285.3 4465.6 4636.4 4686.3 4737.0 5568.3 5593.9 5700.2 5713.2 5722.6 1063.9 1240.4 1266.1 1280.0 1328.3 785.8 792.7 886.3 899.0 920.7 4358.0 4642.7 4679.8 4693.6 4891.3 4585.8 4965.5 5143.7 5340.2 5347.5 4309.5 4585.7 4751.0 4841.8 4883.7 2654.4 2677.8 2787.8 2837.9 2838.7 4640.6 4886.6 4932.4 5022.1 5472.5 4478.7 4849.5 4931.9 5516.5 5528.2 4507.9 4632.9 5214.0 5488.5 5533.3 4394.1 5206.3 5341.8 5397.8 5406.5 4427.8 5130.0 5399.4 5422.5 5660.9 5385.6 5779.6 5797.7 5798.8 5817.8 3384.2 3835.0 3871.0 3875.3 3914.3 4935.9 5336.3 5715.3 5724.0 5762.5 5012.0 5049.7 5527.0 5532.4 5540.5 4958.5 5518.8 5577.3 5588.5 5626.3 5193.6 5477.4 5492.0 5582.5 5617.5 5263.0 5285.3 5596.4 5599.5 5626.4 4440.8 4621.3 5483.3 5520.5 5536.8 10:32:03 INFO - PROCESS | 1658 | 10:32:03 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:18972.26 Median:51608.24 stddev:27389.98 (53.1%) stddev-sans-first:27432.49 10:32:03 INFO - PROCESS | 1658 | Values: 708.0 736.0 754.7 761.8 808.7 14125.7 14261.5 14540.3 14714.6 14781.3 12551.9 13076.4 13200.9 13217.3 13226.0 629.5 794.6 805.1 807.7 809.7 801.9 807.5 808.0 808.8 810.3 849.8 851.2 851.7 853.5 853.8 841.2 844.6 846.6 846.9 848.5 619.3 623.2 624.4 625.4 625.6 12341.9 12486.3 12637.4 12679.0 12704.0 11452.4 11507.5 11693.9 11732.6 11748.4 10049.3 10211.1 10279.3 10300.2 10485.3 3021.9 3439.2 3486.9 3610.3 3642.2 87742.0 89443.6 92018.6 92514.5 92583.5 8776.6 8778.8 8779.4 9284.2 9544.9 19222.5 19470.9 19523.9 19583.0 19596.6 6859.3 7031.8 7098.7 7393.6 7468.6 3922.6 4053.6 4061.0 4159.4 4187.5 3220.2 3357.9 3367.6 3368.7 3388.1 617.1 620.5 630.7 632.9 636.3 478.9 534.5 570.6 584.2 586.6 7489.7 8009.7 8017.2 8132.6 8283.3 535.7 535.7 580.0 592.2 636.9 3833.4 4032.5 4129.6 4163.5 4170.5 68589.7 68636.3 69014.0 105982.0 107143.5 63979.7 86544.0 87940.6 90624.2 93726.0 59311.0 83093.6 83143.6 83371.6 85953.3 6788.4 8037.4 8178.8 8254.8 8272.0 43659.6 50429.5 51314.7 51901.7 52078.7 72234.0 73278.6 73786.0 75880.3 77288.6 11675.6 11861.9 12232.4 12375.7 12978.9 12650.4 13518.6 13940.0 14207.3 14734.5 11828.3 11850.9 12581.0 12607.2 12841.5 12232.5 12762.4 12780.1 13037.5 13618.4 11486.9 12002.3 12583.4 12589.1 12653.4 10471.9 10996.4 11422.0 11639.2 11741.4 10252.7 10724.2 10770.8 10786.9 10836.3 10:32:03 INFO - PROCESS | 1658 | -------- Summary: end -------- 10:32:03 INFO - PROCESS | 1658 | 10:32:04 INFO - TEST-INFO | 1658: exit 0 10:32:04 INFO - TEST-OK | dromaeo_css | took 1121112ms 10:32:04 INFO - TEST-START | kraken 10:32:04 INFO - operating with platform_type : mac_ 10:32:04 INFO - Initialising browser for kraken test... 10:32:04 INFO - TEST-INFO | started process 1781 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpTNOsQf/profile http://localhost:49242/getInfo.html) 10:32:06 INFO - PROCESS | 1781 | __metrics Screen width/height:1600/1200 10:32:06 INFO - PROCESS | 1781 | colorDepth:24 10:32:06 INFO - PROCESS | 1781 | Browser inner width/height: 1024/689 10:32:06 INFO - PROCESS | 1781 | __metrics 10:32:06 INFO - PROCESS | 1781 | 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" 10:32:06 INFO - PROCESS | 1781 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:32:07 INFO - TEST-INFO | 1781: exit 0 10:32:07 INFO - Browser initialized. 10:32:07 INFO - Running cycle 1/1 for kraken test... 10:32:07 INFO - TEST-INFO | started process 1782 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpTNOsQf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:32:19 INFO - PROCESS | 1782 | RSS: Main: 222785536 10:32:19 INFO - PROCESS | 1782 | 10:32:22 INFO - PROCESS | 1782 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 10:32:23 INFO - PROCESS | 1782 | RSS: Main: 273235968 10:32:23 INFO - PROCESS | 1782 | 10:32:26 INFO - PROCESS | 1782 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 10:32:26 INFO - PROCESS | 1782 | RSS: Main: 283762688 10:32:26 INFO - PROCESS | 1782 | 10:32:30 INFO - PROCESS | 1782 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 10:32:30 INFO - PROCESS | 1782 | RSS: Main: 381894656 10:32:30 INFO - PROCESS | 1782 | 10:32:32 INFO - PROCESS | 1782 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 10:32:33 INFO - PROCESS | 1782 | RSS: Main: 283590656 10:32:33 INFO - PROCESS | 1782 | 10:32:35 INFO - PROCESS | 1782 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:32:36 INFO - PROCESS | 1782 | RSS: Main: 284672000 10:32:36 INFO - PROCESS | 1782 | 10:32:38 INFO - PROCESS | 1782 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 10:32:39 INFO - PROCESS | 1782 | RSS: Main: 326721536 10:32:39 INFO - PROCESS | 1782 | 10:32:42 INFO - PROCESS | 1782 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 10:32:42 INFO - PROCESS | 1782 | RSS: Main: 327294976 10:32:42 INFO - PROCESS | 1782 | 10:32:45 INFO - PROCESS | 1782 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 10:32:46 INFO - PROCESS | 1782 | RSS: Main: 323416064 10:32:46 INFO - PROCESS | 1782 | 10:32:47 INFO - PROCESS | 1782 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:32:48 INFO - PROCESS | 1782 | RSS: Main: 299646976 10:32:48 INFO - PROCESS | 1782 | 10:32:50 INFO - PROCESS | 1782 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:32:50 INFO - PROCESS | 1782 | RSS: Main: 302141440 10:32:50 INFO - PROCESS | 1782 | 10:32:53 INFO - PROCESS | 1782 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:32:53 INFO - PROCESS | 1782 | RSS: Main: 303927296 10:32:53 INFO - PROCESS | 1782 | 10:32:56 INFO - PROCESS | 1782 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:32:57 INFO - PROCESS | 1782 | RSS: Main: 315469824 10:32:57 INFO - PROCESS | 1782 | 10:33:00 INFO - PROCESS | 1782 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:33:01 INFO - PROCESS | 1782 | RSS: Main: 315056128 10:33:01 INFO - PROCESS | 1782 | 10:33:02 INFO - PROCESS | 1782 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 10:33:03 INFO - PROCESS | 1782 | RSS: Main: 358412288 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | __start_tp_report 10:33:03 INFO - PROCESS | 1782 | _x_x_mozilla_page_load 10:33:03 INFO - PROCESS | 1782 | _x_x_mozilla_page_load_details 10:33:03 INFO - PROCESS | 1782 | |i|pagename|runs| 10:33:03 INFO - PROCESS | 1782 | |0;ai-astar;115;114;115;114;115;114;135;169;115;114 10:33:03 INFO - PROCESS | 1782 | |1;audio-beat-detection;149;110;112;147;141;140;142;110;140;110 10:33:03 INFO - PROCESS | 1782 | |2;audio-dft;185;192;262;207;183;182;181;184;183;183 10:33:03 INFO - PROCESS | 1782 | |3;audio-fft;84;58;59;89;65;60;58;58;82;59 10:33:03 INFO - PROCESS | 1782 | |4;audio-oscillator;84;70;94;69;68;69;89;67;69;93 10:33:03 INFO - PROCESS | 1782 | |5;imaging-gaussian-blur;88;88;90;86;88;84;89;85;88;86 10:33:03 INFO - PROCESS | 1782 | |6;imaging-darkroom;94;94;94;94;94;94;93;94;94;92 10:33:03 INFO - PROCESS | 1782 | |7;imaging-desaturate;81;83;79;82;83;94;137;123;81;83 10:33:03 INFO - PROCESS | 1782 | |8;json-parse-financial;54;54;53;53;56;58;57;57;57;57 10:33:03 INFO - PROCESS | 1782 | |9;json-stringify-tinderbox;65;73;66;78;72;72;68;70;71;72 10:33:03 INFO - PROCESS | 1782 | |10;stanford-crypto-aes;84;83;84;93;101;98;100;99;84;83 10:33:03 INFO - PROCESS | 1782 | |11;stanford-crypto-ccm;141;139;139;138;139;142;142;150;213;158 10:33:03 INFO - PROCESS | 1782 | |12;stanford-crypto-pbkdf2;165;146;155;164;168;229;206;167;164;165 10:33:03 INFO - PROCESS | 1782 | |13;stanford-crypto-sha256-iterative;67;67;68;84;86;86;89;72;66;66 10:33:03 INFO - PROCESS | 1782 | __end_tp_report 10:33:03 INFO - PROCESS | 1782 | __start_cc_report 10:33:03 INFO - PROCESS | 1782 | _x_x_mozilla_cycle_collect,2000 10:33:03 INFO - PROCESS | 1782 | __end_cc_report 10:33:03 INFO - PROCESS | 1782 | __startTimestamp1482258783185__endTimestamp 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | ------- Summary: start ------- 10:33:03 INFO - PROCESS | 1782 | Number of tests: 14 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | [#0] ai-astar Cycles:10 Average:122.00 Median:115.00 stddev:17.73 (15.4%) stddev-sans-first:18.63 10:33:03 INFO - PROCESS | 1782 | Values: 115.0 114.0 115.0 114.0 115.0 114.0 135.0 169.0 115.0 114.0 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | [#1] audio-beat-detection Cycles:10 Average:130.10 Median:140.50 stddev:17.12 (12.2%) stddev-sans-first:16.74 10:33:03 INFO - PROCESS | 1782 | Values: 149.0 110.0 112.0 147.0 141.0 140.0 142.0 110.0 140.0 110.0 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | [#2] audio-dft Cycles:10 Average:194.20 Median:184.50 stddev:25.06 (13.6%) stddev-sans-first:26.36 10:33:03 INFO - PROCESS | 1782 | Values: 185.0 192.0 262.0 207.0 183.0 182.0 181.0 184.0 183.0 183.0 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | [#3] audio-fft Cycles:10 Average:67.20 Median:62.50 stddev:12.57 (20.1%) stddev-sans-first:11.77 10:33:03 INFO - PROCESS | 1782 | Values: 84.0 58.0 59.0 89.0 65.0 60.0 58.0 58.0 82.0 59.0 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:77.00 stddev:11.35 (14.7%) stddev-sans-first:11.77 10:33:03 INFO - PROCESS | 1782 | Values: 84.0 70.0 94.0 69.0 68.0 69.0 89.0 67.0 69.0 93.0 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:88.00 stddev:1.87 (2.1%) stddev-sans-first:1.96 10:33:03 INFO - PROCESS | 1782 | Values: 88.0 88.0 90.0 86.0 88.0 84.0 89.0 85.0 88.0 86.0 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 10:33:03 INFO - PROCESS | 1782 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 92.0 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | [#7] imaging-desaturate Cycles:10 Average:92.60 Median:82.50 stddev:20.39 (24.7%) stddev-sans-first:21.19 10:33:03 INFO - PROCESS | 1782 | Values: 81.0 83.0 79.0 82.0 83.0 94.0 137.0 123.0 81.0 83.0 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:57.00 stddev:1.90 (3.3%) stddev-sans-first:1.92 10:33:03 INFO - PROCESS | 1782 | Values: 54.0 54.0 53.0 53.0 56.0 58.0 57.0 57.0 57.0 57.0 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:72.00 stddev:3.74 (5.2%) stddev-sans-first:3.35 10:33:03 INFO - PROCESS | 1782 | Values: 65.0 73.0 66.0 78.0 72.0 72.0 68.0 70.0 71.0 72.0 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:84.00 stddev:7.98 (9.5%) stddev-sans-first:8.06 10:33:03 INFO - PROCESS | 1782 | Values: 84.0 83.0 84.0 93.0 101.0 98.0 100.0 99.0 84.0 83.0 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | [#11] stanford-crypto-ccm Cycles:10 Average:150.10 Median:142.00 stddev:22.97 (16.2%) stddev-sans-first:24.13 10:33:03 INFO - PROCESS | 1782 | Values: 141.0 139.0 139.0 138.0 139.0 142.0 142.0 150.0 213.0 158.0 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:166.00 stddev:25.01 (15.1%) stddev-sans-first:26.36 10:33:03 INFO - PROCESS | 1782 | Values: 165.0 146.0 155.0 164.0 168.0 229.0 206.0 167.0 164.0 165.0 10:33:03 INFO - PROCESS | 1782 | 10:33:03 INFO - PROCESS | 1782 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.10 Median:78.00 stddev:9.81 (12.6%) stddev-sans-first:9.96 10:33:03 INFO - PROCESS | 1782 | Values: 67.0 67.0 68.0 84.0 86.0 86.0 89.0 72.0 66.0 66.0 10:33:03 INFO - PROCESS | 1782 | -------- Summary: end -------- 10:33:03 INFO - PROCESS | 1782 | 10:33:04 INFO - TEST-INFO | 1782: exit 0 10:33:04 INFO - TEST-OK | kraken | took 59845ms 10:33:04 INFO - SUITE-END | took 1180s 10:33:04 INFO - Completed test suite (00:19:41) 10:33:04 INFO - javascript benchmark 10:33:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3121.9531707024385, 3259.869605215791, 3280.2455435249885, 3299.2411745298596, 3345.297487527619, 5299.735013249332, 5495.477929596687, 5584.357798853128, 5621.971890140544, 5657.434256574341, 5563.944360556393, 5647.237622920905, 5664.20701101846, 5671.517920976712, 5705.48650621444, 4455.933161002587, 5147.974260128694, 5157.742112894359, 5181.766820493068, 5208.0, 5092.694438333693, 5105.668131571436, 5114.846554603349, 5146.639735218536, 5261.0, 5202.219667049935, 5232.0, 5234.057869583474, 5235.764390602432, 5255.789768409278, 5210.739463026834, 5224.190250511177, 5229.790808367661, 5231.686098834082, 5234.895302093937, 5105.234214867763, 5178.948210517922, 5184.8963020739375, 5186.325777648919, 5193.584513238932, 5713.228714123582, 5716.79991200306, 5718.628289161191, 5720.342160651537, 5725.169850371714, 6104.114903338991, 6104.511639068864, 6105.572609917308, 6127.050307202347, 6127.969360153171, 6161.353057928898, 6176.4132407421675, 6189.442950134509, 6215.7513699451965, 6237.438630523275, 5691.886162276773, 5737.168110623936, 5740.512056475204, 5744.080947048494, 5744.511716504102, 5005.949940500548, 6294.433500984887, 6331.01869210264, 6339.746410143634, 6343.936560634381, 5955.076963070731, 5999.940000599938, 6020.397960203944, 6022.69886505673, 6029.457348838626, 6175.783847565313, 6237.409460587612, 6273.811785646347, 6282.340354262828, 6292.119103325538, 6205.348438413992, 6215.06773983906, 6217.564770466024, 6236.532260080512, 6244.344343843922, 6071.574989750675, 6082.422169893853, 6109.594897568601, 6143.662098584531, 6152.507799376039, 4825.3485779419325, 4875.171220892456, 4897.657163998557, 4900.926486102711, 4906.533879281463, 4364.148990946735, 4388.736675799462, 4390.714603550759, 4410.117976404732, 4412.0, 5507.476789704971, 5533.501984821305, 5573.693446860461, 5620.5503559715125, 5659.603827732019, 5117.616178786606, 6138.419052470195, 6149.477294429934, 6184.6598437085495, 6216.285127210338, 4747.501512341223, 5674.0637794764225, 5682.829515114553, 5689.886202275931, 5727.4845263926445, 5270.235815806686, 5337.332833395825, 5338.492843179892, 5353.652012619167, 5387.784488620489, 5040.4455509894615, 6232.25212974446, 6273.121762953099, 6303.6217826929615, 6331.113644089924, 6135.416406698409, 6177.1969643945995, 6185.226846644169, 6211.627302361872, 6273.99616061428, 6308.148399965946, 6338.0, 6341.492680585634, 6349.682515874096, 6366.554341196072, 6154.19995400613, 6183.567150299616, 6213.937860621336, 6215.378462153749, 6233.594816336924, 4909.68087074339, 6028.939710603014, 6115.4190351916495, 6117.571769976059, 6142.0076949981085, 5927.199828023162, 5927.377625349276, 6000.339962604023, 6054.424829641177, 6085.391460853879, 5999.52003839683, 6020.096985452042, 6045.365236650088, 6047.304559975659, 6055.122007308827, 5671.376148623565, 5859.2090067842, 5876.853078673067, 5879.20630714848, 5888.676122813286, 5888.852778680396, 6011.87976240464, 6044.063170208625, 6054.0, 6129.542179248731, 4870.269459581091, 5810.954028274951, 5814.04068328738, 5820.476157145878, 5886.05823068307], "value": 5610.060470793545, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.8474088291748, 481.0955403840779, 516.08652684748, 522.8039485193052, 548.2625868207265, 29820.552691709614, 30563.235919102033, 31473.89841355553, 38290.23419531608, 39935.0, 40415.79792101035, 40794.7960260199, 40965.7951710241, 41026.79486602563, 41063.38404923921, 9162.26701863851, 9332.626694932194, 9342.346035777498, 9345.532723363805, 9355.953220233889, 9416.435013899154, 9514.666986655468, 9537.093976072261, 9537.380070295443, 9552.761180970496, 9515.666951656693, 9552.235821134327, 9558.235341172724, 9558.761030974212, 9563.90436095637, 9409.388389754713, 9435.858462123073, 9495.003024682412, 9524.571394287274, 9544.0, 9252.444853308823, 9331.906680933173, 9343.159115679622, 9347.672831450867, 9369.672061477815, 38593.42109868354, 38905.83282501529, 38924.80537597322, 38962.22075558501, 39073.80463097695, 3253.544503769474, 3298.472244440878, 3309.1561651778643, 3318.5188147718686, 3338.21551935295, 28558.571621425694, 29274.121776346743, 29355.55966660502, 29411.852940735374, 29959.700402996128, 3082.260257538197, 3117.610298712661, 3159.304952910356, 3174.8095114293214, 3178.284885900654, 487991.0, 492564.0, 493259.0, 493277.53361233324, 505289.0, 58989.525261868795, 59438.0, 59527.0, 59589.70205148947, 59672.80654386888, 34254.314913701586, 35050.82474587611, 35343.646563534036, 36072.0, 62986.0552091719, 15531.922340388226, 15685.13731744765, 15711.68576628484, 15711.764323535157, 15726.21368931549, 11504.597339093092, 11963.0, 12015.0, 12071.818922716166, 12101.15291929574, 18439.53901152482, 18488.445346639623, 18507.722384164248, 18520.907395463208, 18574.071296435126, 7640.73257435987, 7654.82051384256, 7718.3053525183, 7770.145284018753, 7788.727394541163, 808.1716240853056, 864.464032299978, 899.9325050621229, 922.7404592730874, 1011.9949400252951, 20539.58920821605, 25125.497490050097, 25241.0, 25452.618210726854, 25550.744492555208, 987.9703608891745, 990.8711867457184, 1011.2112552209142, 1066.1204506282347, 1067.4342598422847, 12668.49326026967, 12966.611001669966, 13208.933955330162, 13291.601251962456, 13329.800052999213, 664981.0, 668836.0, 670179.0, 671304.0, 672793.0, 55515.0, 55945.0, 56085.15872261919, 56186.43813561812, 56368.71815640978, 1228.177121328712, 1263.7409331086922, 1265.569706299844, 1373.2653030628765, 1396.818413606245, 19473.707894381878, 26508.204753857415, 26618.66906654621, 26752.8662356687, 27203.72796272012, 52061.0, 52750.0, 52761.7361913188, 52865.20702189393, 52887.0, 615468.0, 622401.0, 623512.0, 624177.8791106015, 624949.0, 22920.083196671945, 23457.88271058634, 25410.618840717034, 25807.741922581285, 26187.738122619292, 43399.0, 43541.564584355016, 44354.334684979105, 44451.525531745814, 44547.777261113486, 36294.548218072036, 36666.8166659165, 36879.0, 36989.815050924575, 37543.43684844784, 36429.08927276839, 36821.63178368182, 37946.0, 37983.86048418552, 38064.85805425842, 28621.0, 30864.074077777703, 31326.0, 31383.372332553678, 31800.681993180697, 27064.0, 31722.0, 32545.837270813496, 33099.83450082734, 33235.50146747849, 21951.670724939457, 22117.33647990563, 22150.113995440002, 22535.985880635737, 22622.321330360115], "value": 18830.329130427486, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.6237625740459, 371.6005294308618, 375.58310275594084, 385.13537109189866, 406.41679190361833, 14608.926955365221, 14915.627109322273, 15497.690046199083, 19755.91342476163, 20911.16355345788, 19275.807241927578, 19599.0, 20092.296769613065, 20218.696719549218, 20577.27979520717, 5840.620359676607, 5860.489581627553, 6055.394460553932, 6081.604695694788, 6088.482478989291, 4598.586127248547, 5947.107933809942, 6017.157597936292, 6018.939810601903, 6022.397760223987, 5561.4716601922755, 5807.564432667546, 5946.762129514815, 5950.04799232123, 5972.820815375546, 5788.652680839142, 5905.61218113743, 5906.0, 5912.113183022581, 5918.556108291896, 5570.888582228374, 5723.627964182357, 5731.971340143315, 5750.309962804448, 5760.6255593386295, 13188.736225275441, 13389.799153012713, 13394.263315517643, 15308.770368444482, 24578.262652120466, 2239.8992045357836, 2303.090279339668, 2313.803326717231, 2417.842840215398, 2452.1785201957364, 9665.100469859015, 9675.516224188832, 10263.640772572999, 10357.326773759756, 10385.63650272237, 1830.20386132033, 1857.544901499128, 1884.8209420105068, 1892.2714754819356, 1915.1477592471356, 117007.41496292464, 123463.38268308691, 125305.0, 126409.0, 128106.0, 11169.44152792357, 11333.546658133748, 11419.885801141882, 11688.94155529217, 11880.524779008916, 14542.636434089021, 14547.27263636814, 14552.981291309507, 14554.417823287162, 14588.270586470846, 8292.958535207285, 8403.327733781283, 8416.0, 8426.410151289474, 8588.785280367916, 8242.670293188325, 8274.544900030436, 8361.540115293596, 8420.663173473115, 8434.325253979667, 3766.6798322142927, 4545.954540454553, 4592.770361481913, 4597.42532183477, 4648.1865673507, 1857.9933671634872, 1882.8870267783702, 1898.1078892920304, 1910.3982245592595, 1914.7606549181353, 634.5558109323492, 718.1454069657195, 752.5559919647434, 818.9426740128253, 992.7964767222846, 15306.464273750365, 17105.059221742675, 17808.91095544514, 18533.90733046353, 18656.53358666017, 907.6596276396351, 908.5366463103733, 914.7118657622827, 918.3571499949928, 934.5654270764128, 9036.638534458549, 9628.47043412619, 9664.371815831948, 9690.515474226402, 9797.608095676236, 596839.0, 605862.0, 613871.0, 628274.0, 629105.0, 106383.46808265909, 114109.28836067466, 119356.40321798336, 121257.39371303264, 129907.0, 916.2074805293373, 938.1697197979701, 963.8602402651717, 964.0600414595854, 969.5394687523511, 16598.17009149562, 16820.57948551296, 16829.747553786947, 16966.74549881728, 16988.895721778044, 52222.0, 54256.72871635616, 56907.43092569021, 57253.854922902145, 57815.554611135056, 81468.0, 82068.58965705133, 82079.0, 82928.0, 84262.31475370581, 7926.762197134095, 7962.243586859239, 7970.880436793337, 8012.439129260896, 8115.75652730419, 9825.950870245602, 12266.815997760219, 12287.262764234174, 12373.0, 12692.746145076862, 9336.53317334117, 10182.796344073226, 10383.923215356808, 10542.8418573723, 10701.732456688645, 10508.106810920852, 10555.102816260704, 10659.89340106589, 10915.727106822393, 11114.555417783522, 10493.422861742678, 10743.355398676105, 10812.156456624014, 10924.453777311262, 10994.450277486254, 7912.3274521664, 7935.246151615587, 8100.473469224482, 8101.959490202511, 8200.01599808027, 8137.511749295061, 10329.328593641389, 10429.26995110335, 10534.0, 10620.89379106199], "value": 9170.608619576276, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.9154404963676, 1423.921684307363, 1539.4457995121754, 1732.3763445159748, 1775.6093659394928, 8171.877421838677, 8546.70086546971, 8549.529775862326, 8551.828963420729, 8567.357448191387, 7502.774916752493, 7776.105747839, 7783.92216077839, 8012.0, 8358.791030224247, 2291.0835665733716, 2312.9768702313013, 2355.6702061711376, 2356.2695564375013, 2360.0441821062495, 2348.3307257431634, 2371.300466362419, 2391.079434417753, 2393.9760602393926, 2394.2338451695464, 2305.124052859919, 2332.8716920569364, 2354.1760383865594, 2359.9646005309933, 2361.1027809432476, 2318.1654604342425, 2324.976750232501, 2345.988270058656, 2347.694799676048, 2347.835651504395, 2289.267434420986, 2348.330725743172, 2352.094443639194, 2357.481354102095, 2363.7399886012527, 8284.792880177984, 8346.916530834735, 8355.164483551656, 8367.99584049918, 8391.244787969052, 5102.69383836971, 5153.819616313449, 5240.056789777838, 5262.210668399733, 5264.210368444726, 3209.3878832895393, 3339.482380231068, 3459.6713312235297, 3468.0462872709954, 3476.1657202271526, 1036.9081977866576, 1051.7738686182431, 1069.9679009629724, 1070.218740319571, 1091.410638255349, 10655.627053053184, 10772.245942784008, 10879.020888120107, 10886.564537418415, 10928.508217130247, 4394.209042372339, 4596.103759766814, 4700.55344742249, 4708.223143181337, 4714.764261786965, 9489.857652135224, 9515.381500202464, 9518.381305215138, 9521.143097121292, 9524.904750952403, 5187.429382767892, 5228.686278823283, 5233.267342572043, 5247.396549396792, 5261.237120617489, 4280.91438171241, 4442.955570444255, 4608.0, 4670.7664616769025, 4754.381930349033, 3177.6822317768037, 3778.6977041836585, 3878.2437424702475, 3921.8823435296986, 3924.0, 1045.2369770067742, 1177.3995262416229, 1190.4345435918042, 1274.2099898063261, 1285.8521270053877, 740.3558903753768, 746.7349091072699, 819.4714409206148, 820.7278717987097, 851.1105894340429, 5157.613179011589, 5210.452902445264, 5230.973845130826, 5316.548093412026, 5372.650777699437, 426.7247625281676, 428.4815373398223, 435.2057495071507, 447.48092213032726, 448.0546047839057, 2707.9864600677265, 3438.381091403571, 3493.2314890724, 3497.1606814364877, 3579.749417540799, 9664.613415463302, 9892.208623310118, 10040.79918401628, 10163.542640581192, 10169.13562347194, 9874.358166719141, 9908.306418550632, 10135.0, 10136.138428233535, 10151.238657100746, 7479.513831600929, 7735.806604834812, 7873.055233371918, 7908.802279943047, 7908.920910790818, 5109.5912327013, 5129.563987061141, 5196.480351964925, 5320.255164277081, 5373.408925018323, 9421.434713917188, 9431.434113953184, 9446.866376034919, 9476.0, 9479.241660666992, 9060.275177985613, 9422.869255689362, 9457.716268511957, 9508.762280943032, 9533.856992144985, 6653.534252602271, 6857.862842743217, 6871.5189936703955, 7162.928370716226, 7338.412926965937, 6423.96788016057, 7855.175206603224, 8024.919750802417, 8033.517988920684, 8091.919080809352, 6835.0, 7153.427725781821, 7412.11054673421, 7420.740274090381, 7486.43851711113, 7223.097112860893, 7283.271672832674, 7431.182569917413, 7445.4043676506835, 7473.40212782987, 5283.445238250082, 6527.3472652734345, 6618.933810662024, 6643.0, 6654.567453115532, 4681.776215862059, 4726.976365118152, 4826.734529600905, 4830.1305764961235, 4880.365552478155, 4778.402699662543, 4779.856604301876, 4852.951470485391, 4868.002059577849, 4922.138625740553], "value": 4282.578390004163, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.4574828064642, 299.4011976047906, 304.9828820882357, 312.75136266668, 314.83313843662836, 5104.886019528085, 5202.895942081156, 5237.397699264581, 5252.89494210116, 5276.34045744282, 5140.922886156702, 5169.224616307546, 5268.604854635899, 5335.653182543132, 5386.622936394444, 4088.550259471456, 4097.487814023247, 4106.733062350952, 4152.584741525839, 4172.165566886612, 3624.601293857687, 3850.4609354690365, 4109.465769449967, 4114.0, 4153.210889930903, 3601.9099522511883, 3711.962880371202, 3899.005753532845, 4098.241825262321, 4167.6249137577615, 3440.569928758905, 4060.9593904060876, 4071.8850079688914, 4097.938530922039, 4114.876553703379, 3576.78539287641, 3587.3542762302777, 3600.4239321708665, 3605.6574625410544, 3637.6907962823193, 4337.850469625551, 4860.6111511079025, 5052.093473713627, 5207.739613019334, 5316.734163291859, 3933.3116704576582, 4673.369095172142, 4682.484926658065, 4703.2239680452685, 4714.764261786897, 4807.18277892759, 5545.500904918536, 5573.41479144688, 5577.776888924438, 5584.944150558483, 3487.442009278503, 3600.973722489104, 3642.653947874974, 3977.8066580025875, 4000.739951903117, 5752.712364381806, 5779.757352169304, 5782.45066718661, 5790.536757059467, 5820.35976042635, 4009.7594144351433, 4017.5982401760175, 4033.2941735196223, 4039.7374170678813, 4040.393940908887, 3653.6529029742132, 3661.8901432956523, 3887.416887466846, 4087.652549533323, 4098.094386110473, 4285.314349704033, 4465.620422264079, 4636.420447444069, 4686.27362758773, 4737.028909073631, 5568.32720294261, 5593.944060559343, 5700.23046888673, 5713.171590119493, 5722.599418040697, 1063.8776540697763, 1240.3922078181627, 1266.0631132961726, 1279.9680007999875, 1328.2960031183488, 785.8153333966513, 792.6987744656993, 886.3263919421287, 898.9527200811112, 920.7156017355751, 4357.9564204358185, 4642.675012749075, 4679.789409476582, 4693.568461619239, 4891.26631005352, 4585.839495617704, 4965.503449655005, 5143.665661731976, 5340.225667278223, 5347.51872331492, 4309.547497512779, 4585.72485650862, 4750.976245118753, 4841.806327746851, 4883.731394773251, 2654.389490417176, 2677.7723893468883, 2787.8466684332557, 2837.943241135166, 2838.6722887232686, 4640.605548528413, 4886.560209581155, 4932.372317135409, 5022.070916880389, 5472.480114389126, 4478.7088839226, 4849.466558678542, 4931.926021109686, 5516.475934786269, 5528.237743177029, 4507.864764057083, 4632.907341853144, 5213.977855576663, 5488.506034456918, 5533.261035787311, 4394.121850188132, 5206.297149884868, 5341.759620816995, 5397.757100930546, 5406.459354064562, 4427.80074896624, 5129.9743501282255, 5399.379071406838, 5422.526415707136, 5660.943390566042, 5385.569154467555, 5779.624324418899, 5797.6521408713925, 5798.797042103506, 5817.825465236049, 3384.2418428980304, 3834.9808250958567, 3871.0, 3875.341191997311, 3914.2954268231993, 4935.876603084952, 5336.279602253646, 5715.342735585295, 5724.0, 5762.4525670061275, 5012.0, 5049.747512624427, 5527.0, 5532.391436941853, 5540.501354877919, 4958.496069362932, 5518.834434966958, 5577.326801959477, 5588.524975377139, 5626.2685850839125, 5193.610479213999, 5477.424870388551, 5491.972540137273, 5582.525485333792, 5617.494425501561, 5262.97368513155, 5285.312909321918, 5596.38439771633, 5599.524040456607, 5626.409227031103, 4440.800163992563, 4621.260598304255, 5483.341998960151, 5520.53075488588, 5536.7785288588], "value": 4001.2796684302457, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.9681414336354, 736.0247671834817, 754.6679461037144, 761.7600455856408, 808.6926967752255, 14125.717485650293, 14261.50084747034, 14540.345684444199, 14714.632134196623, 14781.26093695315, 12551.874481255185, 13076.411561479732, 13200.867991320083, 13217.339133043333, 13226.0, 629.5309994054436, 794.622554286715, 805.0701439836997, 807.6809660184224, 809.7165991902816, 801.9438639295252, 807.5033854179696, 807.9940474109742, 808.8139727862596, 810.3193317613204, 849.7705619482736, 851.2041241439243, 851.6593362654926, 853.5177624642089, 853.7609469348592, 841.2092632925062, 844.6368061733452, 846.6147902704255, 846.9237768600856, 848.4612271207797, 619.2507066449601, 623.1742940603718, 624.406813527151, 625.3964923848463, 625.5933643131955, 12341.876581234252, 12486.25082495044, 12637.368131593475, 12679.0, 12704.0, 11452.427378631119, 11507.482163302588, 11693.883061169365, 11732.589359372381, 11748.353840538766, 10049.346792458467, 10211.081002709792, 10279.331843430227, 10300.227482938735, 10485.318454300523, 3021.9358036931467, 3439.208981934144, 3486.9128271793156, 3610.2598967211657, 3642.1805093853936, 87742.0, 89443.55278223632, 92018.61972070357, 92514.53742731243, 92583.53708231416, 8776.561171941377, 8778.780530486789, 8779.429337093068, 9284.164425201629, 9544.856827147598, 19222.519437013907, 19470.929098899414, 19523.926184060012, 19583.0, 19596.608067838562, 6859.314068593101, 7031.753888613874, 7098.68055937484, 7393.630318484162, 7468.626568671653, 3922.627350401707, 4053.6217685986317, 4061.0401123584597, 4159.355299928516, 4187.476565429321, 3220.162757683019, 3357.8992630221132, 3367.6127245367097, 3368.7136593389346, 3388.1360253135786, 617.0867116667357, 620.5097972601594, 630.6972653126525, 632.8860805055042, 636.3445650979498, 478.88746144655977, 534.5189329603311, 570.6262398129232, 584.2200662116122, 586.633354153654, 7489.662965166581, 8009.719659811879, 8017.198280171936, 8132.634031468479, 8283.337333013344, 535.6545028456624, 535.6732393240198, 579.9623024503478, 592.2301008688773, 636.8726254749068, 3833.3674943633987, 4032.495938007749, 4129.587041295847, 4163.542010378856, 4170.457840480718, 68589.65705171542, 68636.313636864, 69014.0, 105982.0, 107143.46428267809, 63979.72040559256, 86544.0, 87940.5602971981, 90624.18751624931, 93726.0, 59311.0, 83093.58453207817, 83143.5842820782, 83371.58314208512, 85953.28093438222, 6788.370791010221, 8037.397195210266, 8178.7955301117945, 8254.83490330202, 8271.95864020676, 43659.56340436555, 50429.49570504248, 51314.74342628412, 51901.7404912973, 52078.73960630173, 72233.98095085994, 73278.63360683163, 73786.0, 75880.30633260673, 77288.6135569341, 11675.649730508097, 11861.881381186078, 12232.355076427371, 12375.69060773488, 12978.870211297766, 12650.367481625697, 13518.59444216675, 13940.02419830602, 14207.28963551839, 14734.484293049692, 11828.34944078084, 11850.940745296219, 12581.0, 12607.180533265675, 12841.486340546273, 12232.510699571918, 12762.42569084412, 12780.105392622427, 13037.47850085986, 13618.38717257706, 11486.885131148581, 12002.279863208236, 12583.370831458573, 12589.118761686961, 12653.367331633566, 10471.947640262055, 10996.395198263852, 11421.960244556763, 11639.243449175776, 11741.354225517678, 10252.743681408025, 10724.195685323788, 10770.838437423288, 10786.892131078903, 10836.295640783324], "value": 6871.768138459262, "unit": "score"}], "name": "dromaeo_css", "value": 6964.24205464793, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 115.0, 114.0, 115.0, 114.0, 135.0, 169.0, 115.0, 114.0], "value": 122.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 110.0, 112.0, 147.0, 141.0, 140.0, 142.0, 110.0, 140.0, 110.0], "value": 130.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 192.0, 262.0, 207.0, 183.0, 182.0, 181.0, 184.0, 183.0, 183.0], "value": 194.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 59.0, 89.0, 65.0, 60.0, 58.0, 58.0, 82.0, 59.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 94.0, 69.0, 68.0, 69.0, 89.0, 67.0, 69.0, 93.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 90.0, 86.0, 88.0, 84.0, 89.0, 85.0, 88.0, 86.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 92.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 79.0, 82.0, 83.0, 94.0, 137.0, 123.0, 81.0, 83.0], "value": 92.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 53.0, 56.0, 58.0, 57.0, 57.0, 57.0, 57.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 66.0, 78.0, 72.0, 72.0, 68.0, 70.0, 71.0, 72.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 93.0, 101.0, 98.0, 100.0, 99.0, 84.0, 83.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 139.0, 138.0, 139.0, 142.0, 142.0, 150.0, 213.0, 158.0], "value": 150.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 146.0, 155.0, 164.0, 168.0, 229.0, 206.0, 167.0, 164.0, 165.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 84.0, 86.0, 86.0, 89.0, 72.0, 66.0, 66.0], "value": 75.1, "unit": "score"}], "name": "kraken", "value": 1479.5000000000002, "alertThreshold": 2.0}]} 10:33:04 INFO - Return code: 0 10:33:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:33:04 INFO - # TBPL SUCCESS # 10:33:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:33:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:33:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:33:04 INFO - 2016-12-20 10:33:04.522 screenresolution[1792:9916] starting screenresolution argv=screenresolution get 10:33:04 INFO - 2016-12-20 10:33:04.525 screenresolution[1792:9916] Display 0: 1600x1200x32@60 10:33:04 INFO - 2016-12-20 10:33:04.531 screenresolution[1793:9919] starting screenresolution argv=screenresolution list 10:33:04 INFO - Available Modes on Display 0 10:33:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:33:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:33:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:33:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:33:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:33:04 INFO - Intel Iris: 10:33:04 INFO - Chipset Model: Intel Iris 10:33:04 INFO - Type: GPU 10:33:04 INFO - Bus: Built-In 10:33:04 INFO - VRAM (Dynamic, Max): 1536 MB 10:33:04 INFO - Vendor: Intel (0x8086) 10:33:04 INFO - Device ID: 0x0a2e 10:33:04 INFO - Revision ID: 0x0009 10:33:04 INFO - Displays: 10:33:04 INFO - Display: 10:33:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:33:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:33:04 INFO - Display Serial Number: 1600x1200 60 10:33:04 INFO - Main Display: Yes 10:33:04 INFO - Mirror: Off 10:33:04 INFO - Online: Yes 10:33:04 INFO - Rotation: Supported 10:33:04 INFO - Return code: 0 10:33:04 INFO - Running post-action listener: _resource_record_post_action 10:33:04 INFO - [mozharness: 2016-12-20 18:33:04.775123Z] Finished run-tests step (success) 10:33:04 INFO - Running post-run listener: _resource_record_post_run 10:33:04 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37852160; Write bytes: 660218368; Read time: 237; Write time: 1937 10:33:04 INFO - TinderboxPrint: CPU usage
53.5% 10:33:04 INFO - TinderboxPrint: I/O read bytes / time
37,852,160 / 237 10:33:04 INFO - TinderboxPrint: I/O write bytes / time
660,218,368 / 1,937 10:33:04 INFO - TinderboxPrint: CPU idle
2,258.3 (46.5%) 10:33:04 INFO - TinderboxPrint: CPU system
969.8 (20.0%) 10:33:04 INFO - TinderboxPrint: CPU user
1,625.4 (33.5%) 10:33:04 INFO - TinderboxPrint: Swap in / out
386,535,424 / 0 10:33:04 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344220160; Write bytes: 344946176; Read time: 27071; Write time: 710 10:33:04 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 35890176; Write bytes: 308395008; Read time: 220; Write time: 1201 10:33:05 INFO - Running post-run listener: _upload_blobber_files 10:33:05 INFO - Blob upload gear active. 10:33:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:33:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:33:05 INFO - (blobuploader) - INFO - Open directory for files ... 10:33:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:33:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:33:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:33:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:33:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:33:06 INFO - (blobuploader) - INFO - Done attempting. 10:33:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:33:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:33:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:33:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:33:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:33:09 INFO - (blobuploader) - INFO - Done attempting. 10:33:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:33:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:33:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:33:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:33:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:33:11 INFO - (blobuploader) - INFO - Done attempting. 10:33:11 INFO - (blobuploader) - INFO - Iteration through files over. 10:33:11 INFO - Return code: 0 10:33:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:33:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:33:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6dee564a0c88cbc6b45f879c1269933ad61d6433c3de336742555ad42251dabee7252d1383e16dfb6f6b2982b0d79fae785809f1ee97c0586ebad76b94ff0543", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e36b4626827747329d39fb2a806c16af5172be15e2b0b37dae78138d118117c70328a8cc5d04278cd1ac3501fe88b203b732bd3ff30a072893bf9cbf101ccf2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/209db3f0fe7feb16474346139f387c1cfabf9d2cd6353badec93efaf3d57a6c0ffb4a1b4f8e4616d2b930edf8a7323d80415ec4a99bf986d4ce093620678631d"} 10:33:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:33:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:33:11 INFO - Contents: 10:33:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6dee564a0c88cbc6b45f879c1269933ad61d6433c3de336742555ad42251dabee7252d1383e16dfb6f6b2982b0d79fae785809f1ee97c0586ebad76b94ff0543", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e36b4626827747329d39fb2a806c16af5172be15e2b0b37dae78138d118117c70328a8cc5d04278cd1ac3501fe88b203b732bd3ff30a072893bf9cbf101ccf2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/209db3f0fe7feb16474346139f387c1cfabf9d2cd6353badec93efaf3d57a6c0ffb4a1b4f8e4616d2b930edf8a7323d80415ec4a99bf986d4ce093620678631d"} 10:33:11 INFO - Running post-run listener: copy_logs_to_upload_dir 10:33:11 INFO - Copying logs to upload dir... 10:33:11 INFO - mkdir: /builds/slave/test/build/upload/logs 10:33:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.175932 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-12-20 10:33:11.364471) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 10:33:11.368202) ========= 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.TZft7sE29M/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.pE25vmm5co/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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/6dee564a0c88cbc6b45f879c1269933ad61d6433c3de336742555ad42251dabee7252d1383e16dfb6f6b2982b0d79fae785809f1ee97c0586ebad76b94ff0543", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e36b4626827747329d39fb2a806c16af5172be15e2b0b37dae78138d118117c70328a8cc5d04278cd1ac3501fe88b203b732bd3ff30a072893bf9cbf101ccf2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/209db3f0fe7feb16474346139f387c1cfabf9d2cd6353badec93efaf3d57a6c0ffb4a1b4f8e4616d2b930edf8a7323d80415ec4a99bf986d4ce093620678631d"} build_url:https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010721 build_url: 'https://queue.taskcluster.net/v1/task/TlKZA70yTtOfiJbQd_2KsA/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/6dee564a0c88cbc6b45f879c1269933ad61d6433c3de336742555ad42251dabee7252d1383e16dfb6f6b2982b0d79fae785809f1ee97c0586ebad76b94ff0543", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e36b4626827747329d39fb2a806c16af5172be15e2b0b37dae78138d118117c70328a8cc5d04278cd1ac3501fe88b203b732bd3ff30a072893bf9cbf101ccf2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/209db3f0fe7feb16474346139f387c1cfabf9d2cd6353badec93efaf3d57a6c0ffb4a1b4f8e4616d2b930edf8a7323d80415ec4a99bf986d4ce093620678631d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 10:33:11.402402) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 10:33:11.402731) ========= 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.TZft7sE29M/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.pE25vmm5co/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.005127 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 10:33:11.451424) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 10:33:11.451714) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 10:33:11.452029) ========= ========= Total master_lag: 0.22 =========