builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0212 starttime: 1480353665.29 results: success (0) buildid: 20161128065958 builduid: 2506a827837b40f0bd535c7d46854fab revision: 786488b0735ed48d429723c2aba4e543c1b69b37 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-28 09:21:05.292501) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-28 09:21:05.293004) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-28 09:21:05.293278) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-28 09:21:05.320069) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 09:21:05.320454) ========= 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.fu7cYDdySq/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.LBGXazLp1d/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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-11-28 09:21:05-- 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% 34.2M=0s 2016-11-28 09:21:05 (34.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.180402 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 09:21:05.526862) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 09:21:05.527374) ========= 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.fu7cYDdySq/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.LBGXazLp1d/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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.024552 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 09:21:05.576627) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 09:21:05.577063) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 786488b0735ed48d429723c2aba4e543c1b69b37 --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 786488b0735ed48d429723c2aba4e543c1b69b37 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fu7cYDdySq/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.LBGXazLp1d/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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-11-28 09:21:05,672 truncating revision to first 12 chars 2016-11-28 09:21:05,672 Setting DEBUG logging. 2016-11-28 09:21:05,672 attempt 1/10 2016-11-28 09:21:05,672 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/786488b0735e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-28 09:21:06,340 unpacking tar archive at: mozilla-aurora-786488b0735e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.937549 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 09:21:06.538202) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-28 09:21:06.538539) ========= script_repo_revision: 786488b0735ed48d429723c2aba4e543c1b69b37 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-28 09:21:06.538936) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-28 09:21:06.539213) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-28 09:21:06.559052) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-28 09:21:06.559499) ========= /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.fu7cYDdySq/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.LBGXazLp1d/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:21:06 INFO - MultiFileLogger online at 20161128 09:21:06 in /builds/slave/test 09:21:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:21:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:21:06 INFO - {'append_to_log': False, 09:21:06 INFO - 'base_work_dir': '/builds/slave/test', 09:21:06 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:21:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:21:06 INFO - 'branch': 'Mozilla-Aurora', 09:21:06 INFO - 'buildbot_json_path': 'buildprops.json', 09:21:06 INFO - 'config_files': ('talos/mac_config.py',), 09:21:06 INFO - 'default_actions': ('clobber', 09:21:06 INFO - 'read-buildbot-config', 09:21:06 INFO - 'download-and-extract', 09:21:06 INFO - 'populate-webroot', 09:21:06 INFO - 'create-virtualenv', 09:21:06 INFO - 'install', 09:21:06 INFO - 'run-tests'), 09:21:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:21:06 INFO - 'download_minidump_stackwalk': True, 09:21:06 INFO - 'download_symbols': 'ondemand', 09:21:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:21:06 INFO - 'tooltool.py': '/tools/tooltool.py', 09:21:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:21:06 INFO - '/tools/misc-python/virtualenv.py')}, 09:21:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:21:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:21:06 INFO - 'installer_path': 'installer.exe', 09:21:06 INFO - 'log_level': 'info', 09:21:06 INFO - 'log_name': 'talos', 09:21:06 INFO - 'log_to_console': True, 09:21:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:21:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:21:06 INFO - 'opt_config_files': (), 09:21:06 INFO - 'pip_index': False, 09:21:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:21:06 INFO - 'cmd': ('bash', 09:21:06 INFO - '-c', 09:21:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:21:06 INFO - 'enabled': True, 09:21:06 INFO - 'halt_on_failure': False, 09:21:06 INFO - 'name': 'check_screen_resolution'},), 09:21:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:21:06 INFO - 'cmd': ('bash', 09:21:06 INFO - '-c', 09:21:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:21:06 INFO - 'enabled': True, 09:21:06 INFO - 'halt_on_failure': False, 09:21:06 INFO - 'name': 'check_screen_resolution'},), 09:21:06 INFO - 'run_cmd_checks_enabled': True, 09:21:06 INFO - 'sps_profile': False, 09:21:06 INFO - 'sps_profile_interval': 0, 09:21:06 INFO - 'suite': 'dromaeojs', 09:21:06 INFO - 'system_bits': '32', 09:21:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:21:06 INFO - 'title': 't-yosemite-r7-0212', 09:21:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:21:06 INFO - 'use_talos_json': True, 09:21:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:21:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:21:06 INFO - 'work_dir': 'build'} 09:21:06 INFO - [mozharness: 2016-11-28 17:21:06.762693Z] Running clobber step. 09:21:06 INFO - Running pre-action listener: _resource_record_pre_action 09:21:06 INFO - Running main action method: clobber 09:21:06 INFO - rmtree: /builds/slave/test/build 09:21:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:21:09 INFO - Running post-action listener: _resource_record_post_action 09:21:09 INFO - [mozharness: 2016-11-28 17:21:09.177708Z] Finished clobber step (success) 09:21:09 INFO - [mozharness: 2016-11-28 17:21:09.177834Z] Running read-buildbot-config step. 09:21:09 INFO - Running pre-action listener: _resource_record_pre_action 09:21:09 INFO - Running main action method: read_buildbot_config 09:21:09 INFO - Using buildbot properties: 09:21:09 INFO - { 09:21:09 INFO - "project": "", 09:21:09 INFO - "product": "firefox", 09:21:09 INFO - "script_repo_revision": "production", 09:21:09 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:21:09 INFO - "repository": "", 09:21:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:21:09 INFO - "buildid": "20161128065958", 09:21:09 INFO - "pgo_build": "False", 09:21:09 INFO - "basedir": "/builds/slave/test", 09:21:09 INFO - "buildnumber": 1, 09:21:09 INFO - "slavename": "t-yosemite-r7-0212", 09:21:09 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:21:09 INFO - "platform": "yosemite_r7", 09:21:09 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:21:09 INFO - "branch": "mozilla-aurora", 09:21:09 INFO - "repo_path": "releases/mozilla-aurora", 09:21:09 INFO - "revision": "786488b0735ed48d429723c2aba4e543c1b69b37", 09:21:09 INFO - "stage_platform": "macosx64", 09:21:09 INFO - "builduid": "2506a827837b40f0bd535c7d46854fab", 09:21:09 INFO - "slavebuilddir": "test" 09:21:09 INFO - } 09:21:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:21:09 INFO - Running post-action listener: _resource_record_post_action 09:21:09 INFO - [mozharness: 2016-11-28 17:21:09.181464Z] Finished read-buildbot-config step (success) 09:21:09 INFO - [mozharness: 2016-11-28 17:21:09.181560Z] Running download-and-extract step. 09:21:09 INFO - Running pre-action listener: _resource_record_pre_action 09:21:09 INFO - Running main action method: download_and_extract 09:21:09 INFO - mkdir: /builds/slave/test/build/tests 09:21:09 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')]} 09:21:09 INFO - trying https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:21:09 INFO - Downloading https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/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 09:21:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/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 09:21:10 INFO - Downloaded 1199 bytes. 09:21:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:21:10 INFO - Using the following test package requirements: 09:21:10 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:21:10 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:10 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:21:10 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:10 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:21:10 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:10 INFO - u'jsshell-mac.zip'], 09:21:10 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:10 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:21:10 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:21:10 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:10 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:21:10 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:10 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:21:10 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:10 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:21:10 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:21:10 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:21:10 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:21:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:21:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:21:10 INFO - Fetch https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:21:10 INFO - Http code: 200 09:21:10 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 09:21:10 INFO - x-amz-cf-id: 3B3GMl8QuFpYRqR-g1FAcI14DtSGND496PIeq7yLa0pxkqPLcexQVA== 09:21:10 INFO - x-amz-version-id: PB5xXA4QA0K0J02RKFcU127EdARns.am 09:21:11 INFO - Expected file size: 16280330 09:21:11 INFO - Obtained file size: 16280330 09:21:17 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 09:21:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:21:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:21:17 INFO - Fetch https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:21:18 INFO - Http code: 200 09:21:18 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 09:21:18 INFO - x-amz-cf-id: MEi95HRuufOnl6M8mRGEsZIMEWo76fcJhzpy8l0qzHb4ZFRclS2UsQ== 09:21:18 INFO - x-amz-version-id: PB5xXA4QA0K0J02RKFcU127EdARns.am 09:21:18 INFO - Expected file size: 16280330 09:21:18 INFO - Obtained file size: 16280330 09:21:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:21:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:21:24 INFO - Fetch https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:21:25 INFO - Http code: 200 09:21:25 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 09:21:25 INFO - x-amz-cf-id: t4zpaNlxDRsKEz2sMYNpigzWfSVx3lUDtEmejyeHzCNJnuKelg2K_Q== 09:21:25 INFO - x-amz-version-id: xZ_KVhuNXeZNJNXjGLdq7LElqJc3oqOn 09:21:25 INFO - Expected file size: 14409038 09:21:25 INFO - Obtained file size: 14409038 09:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:26 INFO - trying https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:21:26 INFO - Downloading https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:21:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/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 09:21:28 INFO - Downloaded 109779831 bytes. 09:21:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:21:28 INFO - mkdir: /builds/slave/test/properties 09:21:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:21:28 INFO - Writing to file /builds/slave/test/properties/build_url 09:21:28 INFO - Contents: 09:21:28 INFO - build_url:https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:21:29 INFO - Running post-action listener: _resource_record_post_action 09:21:29 INFO - Running post-action listener: set_extra_try_arguments 09:21:29 INFO - [mozharness: 2016-11-28 17:21:29.600769Z] Finished download-and-extract step (success) 09:21:29 INFO - [mozharness: 2016-11-28 17:21:29.600922Z] Running populate-webroot step. 09:21:29 INFO - Running pre-action listener: _resource_record_pre_action 09:21:29 INFO - Running main action method: populate_webroot 09:21:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:21:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:21:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:21:29 INFO - '--tppagecycles', 09:21:29 INFO - '7'], 09:21:29 INFO - 'tests': ['tsvgm']}}, 09:21:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:21:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:21:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:21:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:21:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:21:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:21:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:29 INFO - 'talos_options': ['--disable-e10s'], 09:21:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:21:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:21:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:29 INFO - 'talos_options': ['--disable-e10s'], 09:21:29 INFO - 'tests': ['damp', 'tps']}, 09:21:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:29 INFO - 'tests': ['damp', 'tps']}, 09:21:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:21:29 INFO - 'tests': ['dromaeo_dom']}, 09:21:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:21:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:21:29 INFO - 'tests': ['basic_compositor_video']}, 09:21:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:21:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:21:29 INFO - 'tests': ['a11yr', 09:21:29 INFO - 'ts_paint', 09:21:29 INFO - 'tpaint', 09:21:29 INFO - 'sessionrestore', 09:21:29 INFO - 'sessionrestore_no_auto_restore', 09:21:29 INFO - 'tabpaint']}, 09:21:29 INFO - 'other-e10s': {'tests': ['a11yr', 09:21:29 INFO - 'ts_paint', 09:21:29 INFO - 'tpaint', 09:21:29 INFO - 'sessionrestore', 09:21:29 INFO - 'sessionrestore_no_auto_restore', 09:21:29 INFO - 'tabpaint']}, 09:21:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:21:29 INFO - 'ts_paint', 09:21:29 INFO - 'tpaint', 09:21:29 INFO - 'sessionrestore', 09:21:29 INFO - 'sessionrestore_no_auto_restore', 09:21:29 INFO - 'tabpaint']}, 09:21:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:21:29 INFO - 'ts_paint', 09:21:29 INFO - 'tpaint', 09:21:29 INFO - 'sessionrestore', 09:21:29 INFO - 'sessionrestore_no_auto_restore', 09:21:29 INFO - 'tabpaint']}, 09:21:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:21:29 INFO - 'tests': ['a11yr', 09:21:29 INFO - 'ts_paint', 09:21:29 INFO - 'tpaint', 09:21:29 INFO - 'sessionrestore', 09:21:29 INFO - 'sessionrestore_no_auto_restore', 09:21:29 INFO - 'tabpaint']}, 09:21:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:21:29 INFO - 'tests': ['a11yr', 09:21:29 INFO - 'ts_paint', 09:21:29 INFO - 'tpaint', 09:21:29 INFO - 'sessionrestore', 09:21:29 INFO - 'sessionrestore_no_auto_restore', 09:21:29 INFO - 'tabpaint']}, 09:21:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:21:29 INFO - 'tests': ['tsvgx', 09:21:29 INFO - 'tsvgr_opacity', 09:21:29 INFO - 'tart', 09:21:29 INFO - 'tscrollx', 09:21:29 INFO - 'cart']}, 09:21:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:21:29 INFO - 'tsvgr_opacity', 09:21:29 INFO - 'tart', 09:21:29 INFO - 'tscrollx', 09:21:29 INFO - 'cart']}, 09:21:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:29 INFO - 'talos_options': ['--disable-e10s'], 09:21:29 INFO - 'tests': ['tp5o']}, 09:21:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:29 INFO - 'tests': ['tp5o']}, 09:21:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:29 INFO - 'talos_options': ['--disable-e10s', 09:21:29 INFO - '--xperf_path', 09:21:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:21:29 INFO - 'tests': ['tp5n']}, 09:21:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:21:29 INFO - 'talos_options': ['--xperf_path', 09:21:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:21:29 INFO - 'tests': ['tp5n']}}, 09:21:29 INFO - 'talos.zip': {'path': '', 09:21:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:21:29 INFO - Running post-action listener: _resource_record_post_action 09:21:29 INFO - [mozharness: 2016-11-28 17:21:29.619757Z] Finished populate-webroot step (success) 09:21:29 INFO - [mozharness: 2016-11-28 17:21:29.619886Z] Running create-virtualenv step. 09:21:29 INFO - Running pre-action listener: _resource_record_pre_action 09:21:29 INFO - Running main action method: create_virtualenv 09:21:29 INFO - Creating virtualenv /builds/slave/test/build/venv 09:21:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:21:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:21:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:21:29 INFO - Using real prefix '/tools/python27' 09:21:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:21:30 INFO - Installing distribute.............................................................................................................................................................................................done. 09:21:33 INFO - Installing pip.................done. 09:21:33 INFO - Return code: 0 09:21:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:21:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:21:33 INFO - Reading from file tmpfile_stdout 09:21:33 INFO - Output received: 09:21:33 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) 09:21:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:21:33 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')]} 09:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:33 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')]} 09:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:33 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 0x100988490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008fe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf72ca4c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10079cd30>, '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 0x100602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100794b20>, '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.fu7cYDdySq/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.LBGXazLp1d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:21:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:21:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:21:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fu7cYDdySq/Render', 09:21:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:33 INFO - 'HOME': '/Users/cltbld', 09:21:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:33 INFO - 'LOGNAME': 'cltbld', 09:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:33 INFO - 'MOZ_NO_REMOTE': '1', 09:21:33 INFO - 'NO_EM_RESTART': '1', 09:21:33 INFO - 'PAGER': '/bin/cat', 09:21:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:33 INFO - 'PWD': '/builds/slave/test', 09:21:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:33 INFO - 'SHELL': '/bin/bash', 09:21:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LBGXazLp1d/Listeners', 09:21:33 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 09:21:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:33 INFO - 'USER': 'cltbld', 09:21:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:33 INFO - 'XPC_FLAGS': '0x0', 09:21:33 INFO - 'XPC_SERVICE_NAME': '0', 09:21:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:21:33 INFO - Cleaning up... 09:21:33 INFO - Return code: 0 09:21:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:21:33 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')]} 09:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:33 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')]} 09:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:33 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 0x100988490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008fe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf72ca4c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10079cd30>, '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 0x100602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100794b20>, '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.fu7cYDdySq/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.LBGXazLp1d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:21:33 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 09:21:33 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 09:21:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fu7cYDdySq/Render', 09:21:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:33 INFO - 'HOME': '/Users/cltbld', 09:21:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:33 INFO - 'LOGNAME': 'cltbld', 09:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:33 INFO - 'MOZ_NO_REMOTE': '1', 09:21:33 INFO - 'NO_EM_RESTART': '1', 09:21:33 INFO - 'PAGER': '/bin/cat', 09:21:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:33 INFO - 'PWD': '/builds/slave/test', 09:21:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:33 INFO - 'SHELL': '/bin/bash', 09:21:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LBGXazLp1d/Listeners', 09:21:33 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 09:21:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:33 INFO - 'USER': 'cltbld', 09:21:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:33 INFO - 'XPC_FLAGS': '0x0', 09:21:33 INFO - 'XPC_SERVICE_NAME': '0', 09:21:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:34 INFO - Downloading/unpacking psutil>=3.1.1 09:21: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 09:21: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 09:21: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 09:21: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 09:21: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 09:21: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 09:21:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:21:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:21:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:21:37 INFO - Installing collected packages: psutil 09:21:37 INFO - Running setup.py install for psutil 09:21:37 INFO - building 'psutil._psutil_osx' extension 09:21:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:21:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:21:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:21:38 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:21:38 INFO - building 'psutil._psutil_posix' extension 09:21:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:21:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:21:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:21:38 INFO - ^ 09:21:38 INFO - 1 warning generated. 09:21:38 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:21:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:21:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:21:38 INFO - Successfully installed psutil 09:21:38 INFO - Cleaning up... 09:21:38 INFO - Return code: 0 09:21:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:21:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:38 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 0x100988490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008fe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf72ca4c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10079cd30>, '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 0x100602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100794b20>, '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.fu7cYDdySq/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.LBGXazLp1d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:21:38 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 09:21:38 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 09:21:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fu7cYDdySq/Render', 09:21:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:38 INFO - 'HOME': '/Users/cltbld', 09:21:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:38 INFO - 'LOGNAME': 'cltbld', 09:21:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:38 INFO - 'MOZ_NO_REMOTE': '1', 09:21:38 INFO - 'NO_EM_RESTART': '1', 09:21:38 INFO - 'PAGER': '/bin/cat', 09:21:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:38 INFO - 'PWD': '/builds/slave/test', 09:21:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:38 INFO - 'SHELL': '/bin/bash', 09:21:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LBGXazLp1d/Listeners', 09:21:38 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 09:21:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:38 INFO - 'USER': 'cltbld', 09:21:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:38 INFO - 'XPC_FLAGS': '0x0', 09:21:38 INFO - 'XPC_SERVICE_NAME': '0', 09:21:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:21: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 09:21: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 09:21: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 09:21: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 09:21: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 09:21: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 09:21:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:21:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:21:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:21:41 INFO - Installing collected packages: mozsystemmonitor 09:21:41 INFO - Running setup.py install for mozsystemmonitor 09:21:41 INFO - Successfully installed mozsystemmonitor 09:21:41 INFO - Cleaning up... 09:21:41 INFO - Return code: 0 09:21:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:21:41 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')]} 09:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:41 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')]} 09:21:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:41 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 0x100988490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008fe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf72ca4c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10079cd30>, '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 0x100602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100794b20>, '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.fu7cYDdySq/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.LBGXazLp1d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:21:41 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 09:21:41 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 09:21:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fu7cYDdySq/Render', 09:21:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:41 INFO - 'HOME': '/Users/cltbld', 09:21:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:41 INFO - 'LOGNAME': 'cltbld', 09:21:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:41 INFO - 'MOZ_NO_REMOTE': '1', 09:21:41 INFO - 'NO_EM_RESTART': '1', 09:21:41 INFO - 'PAGER': '/bin/cat', 09:21:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:41 INFO - 'PWD': '/builds/slave/test', 09:21:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:41 INFO - 'SHELL': '/bin/bash', 09:21:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LBGXazLp1d/Listeners', 09:21:41 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 09:21:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:41 INFO - 'USER': 'cltbld', 09:21:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:41 INFO - 'XPC_FLAGS': '0x0', 09:21:41 INFO - 'XPC_SERVICE_NAME': '0', 09:21:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:41 INFO - Downloading/unpacking jsonschema==2.5.1 09:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:21:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:45 INFO - Downloading functools32-3.2.3-2.tar.gz 09:21:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:21:45 INFO - warning: no files found matching '*.txt' 09:21:45 INFO - no previously-included directories found matching 'build' 09:21:45 INFO - no previously-included directories found matching 'dist' 09:21:45 INFO - no previously-included directories found matching '.git*' 09:21:45 INFO - Installing collected packages: jsonschema, functools32 09:21:45 INFO - Running setup.py install for functools32 09:21:45 INFO - warning: no files found matching '*.txt' 09:21:45 INFO - no previously-included directories found matching 'build' 09:21:45 INFO - no previously-included directories found matching 'dist' 09:21:45 INFO - no previously-included directories found matching '.git*' 09:21:45 INFO - Successfully installed jsonschema functools32 09:21:45 INFO - Cleaning up... 09:21:45 INFO - Return code: 0 09:21:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:45 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 0x100988490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008fe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf72ca4c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10079cd30>, '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 0x100602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100794b20>, '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.fu7cYDdySq/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.LBGXazLp1d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:21:45 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 09:21:45 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 09:21:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fu7cYDdySq/Render', 09:21:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:45 INFO - 'HOME': '/Users/cltbld', 09:21:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:45 INFO - 'LOGNAME': 'cltbld', 09:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:45 INFO - 'MOZ_NO_REMOTE': '1', 09:21:45 INFO - 'NO_EM_RESTART': '1', 09:21:45 INFO - 'PAGER': '/bin/cat', 09:21:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:45 INFO - 'PWD': '/builds/slave/test', 09:21:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:45 INFO - 'SHELL': '/bin/bash', 09:21:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LBGXazLp1d/Listeners', 09:21:45 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 09:21:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:45 INFO - 'USER': 'cltbld', 09:21:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:45 INFO - 'XPC_FLAGS': '0x0', 09:21:45 INFO - 'XPC_SERVICE_NAME': '0', 09:21:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:21:45 INFO - Cleaning up... 09:21:45 INFO - Return code: 0 09:21:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:45 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 0x100988490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008fe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf72ca4c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10079cd30>, '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 0x100602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100794b20>, '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.fu7cYDdySq/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.LBGXazLp1d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:21:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:21:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:21:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fu7cYDdySq/Render', 09:21:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:45 INFO - 'HOME': '/Users/cltbld', 09:21:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:45 INFO - 'LOGNAME': 'cltbld', 09:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:45 INFO - 'MOZ_NO_REMOTE': '1', 09:21:45 INFO - 'NO_EM_RESTART': '1', 09:21:45 INFO - 'PAGER': '/bin/cat', 09:21:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:45 INFO - 'PWD': '/builds/slave/test', 09:21:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:45 INFO - 'SHELL': '/bin/bash', 09:21:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LBGXazLp1d/Listeners', 09:21:45 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 09:21:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:45 INFO - 'USER': 'cltbld', 09:21:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:45 INFO - 'XPC_FLAGS': '0x0', 09:21:45 INFO - 'XPC_SERVICE_NAME': '0', 09:21:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:45 INFO - Downloading/unpacking blobuploader==1.2.4 09:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:48 INFO - Downloading blobuploader-1.2.4.tar.gz 09:21:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:21:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:21:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:49 INFO - Downloading docopt-0.6.1.tar.gz 09:21:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:21:49 INFO - Installing collected packages: blobuploader, requests, docopt 09:21:49 INFO - Running setup.py install for blobuploader 09:21:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:21:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:21:49 INFO - Running setup.py install for requests 09:21:50 INFO - Running setup.py install for docopt 09:21:50 INFO - Successfully installed blobuploader requests docopt 09:21:50 INFO - Cleaning up... 09:21:50 INFO - Return code: 0 09:21:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:21: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')]} 09:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21: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')]} 09:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:50 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 0x100988490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008fe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf72ca4c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10079cd30>, '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 0x100602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100794b20>, '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.fu7cYDdySq/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.LBGXazLp1d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:21:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:21:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:21:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fu7cYDdySq/Render', 09:21:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:50 INFO - 'HOME': '/Users/cltbld', 09:21:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:50 INFO - 'LOGNAME': 'cltbld', 09:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:50 INFO - 'MOZ_NO_REMOTE': '1', 09:21:50 INFO - 'NO_EM_RESTART': '1', 09:21:50 INFO - 'PAGER': '/bin/cat', 09:21:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:50 INFO - 'PWD': '/builds/slave/test', 09:21:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:50 INFO - 'SHELL': '/bin/bash', 09:21:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LBGXazLp1d/Listeners', 09:21:50 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 09:21:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:50 INFO - 'USER': 'cltbld', 09:21:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:50 INFO - 'XPC_FLAGS': '0x0', 09:21:50 INFO - 'XPC_SERVICE_NAME': '0', 09:21:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:21:51 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-KXqQcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:21:51 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-c5vyx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:21:51 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Me5cfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:21:51 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-klwyWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:21:51 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-fHVz0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:21:51 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-9YQHm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:21:51 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-A8Mbso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:21:51 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-eMs8qP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:21:51 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-6kMSwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:21:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:21:51 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Wnrtir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:21:52 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-YDgWlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:21:52 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-HGDrYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:21:52 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-cDmTdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:21:52 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-78AiED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:52 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-zsVQK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:21:52 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-AJczAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:21:52 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-kj6KD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:21:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:21:52 INFO - Running setup.py install for manifestparser 09:21:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:21:52 INFO - Running setup.py install for mozcrash 09:21:53 INFO - Running setup.py install for mozdebug 09:21:53 INFO - Running setup.py install for mozdevice 09:21:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:21:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:21:53 INFO - Running setup.py install for mozfile 09:21:53 INFO - Running setup.py install for mozhttpd 09:21:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:21:53 INFO - Running setup.py install for mozinfo 09:21:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:21:53 INFO - Running setup.py install for mozInstall 09:21:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:21:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:21:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:21:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:21:53 INFO - Running setup.py install for mozleak 09:21:54 INFO - Running setup.py install for mozlog 09:21:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:21:54 INFO - Running setup.py install for moznetwork 09:21:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:21:54 INFO - Running setup.py install for mozprocess 09:21:54 INFO - Running setup.py install for mozprofile 09:21:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:21:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:21:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:21:54 INFO - Running setup.py install for mozrunner 09:21:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:21:54 INFO - Running setup.py install for mozscreenshot 09:21:55 INFO - Running setup.py install for moztest 09:21:55 INFO - Running setup.py install for mozversion 09:21:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:21:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:21:55 INFO - Cleaning up... 09:21:55 INFO - Return code: 0 09:21:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:21:55 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')]} 09:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:55 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')]} 09:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:21:55 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 0x100988490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008fe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf72ca4c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10079cd30>, '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 0x100602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100794b20>, '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.fu7cYDdySq/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.LBGXazLp1d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:21:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fu7cYDdySq/Render', 09:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:21:55 INFO - 'HOME': '/Users/cltbld', 09:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:21:55 INFO - 'LOGNAME': 'cltbld', 09:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:21:55 INFO - 'MOZ_NO_REMOTE': '1', 09:21:55 INFO - 'NO_EM_RESTART': '1', 09:21:55 INFO - 'PAGER': '/bin/cat', 09:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:21:55 INFO - 'PWD': '/builds/slave/test', 09:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:21:55 INFO - 'SHELL': '/bin/bash', 09:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LBGXazLp1d/Listeners', 09:21:55 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 09:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:21:55 INFO - 'USER': 'cltbld', 09:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:21:55 INFO - 'XPC_FLAGS': '0x0', 09:21:55 INFO - 'XPC_SERVICE_NAME': '0', 09:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:21:55 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-MCDnBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:21:55 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-ioPWO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:21:55 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)) 09:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:21:55 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-ysbt76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:21:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:21:56 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-_ZKiyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:21:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:21:56 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-o6xXdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:21:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:21:56 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-7QJYD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:21:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:21:56 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-hKjtDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:21:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:21:56 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-YwdGsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:21:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:21:56 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-OsXtfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:21:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:21:56 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-P_hjox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:21:56 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)) 09:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:21:56 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-UpZCX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:21:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:21:56 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Cr7FGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:21:56 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)) 09:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:21:56 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-OBCkhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:21:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:21:57 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-IfuF4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:21:57 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)) 09:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:57 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-jvy9Sd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:21:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:21:57 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-DCwFbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:21:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:21:57 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Z187Ie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:21:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:21:57 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)) 09:21:57 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)) 09:21:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:21:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:21:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:21:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:21:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:21:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:22:00 INFO - Downloading blessings-1.6.tar.gz 09:22:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:22:00 INFO - Installing collected packages: blessings 09:22:00 INFO - Running setup.py install for blessings 09:22:00 INFO - Successfully installed blessings 09:22:00 INFO - Cleaning up... 09:22:00 INFO - Return code: 0 09:22:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:22:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:22:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:22:00 INFO - Reading from file tmpfile_stdout 09:22:00 INFO - Current package versions: 09:22:00 INFO - blessings == 1.6 09:22:00 INFO - blobuploader == 1.2.4 09:22:00 INFO - docopt == 0.6.1 09:22:00 INFO - functools32 == 3.2.3-2 09:22:00 INFO - jsonschema == 2.5.1 09:22:00 INFO - manifestparser == 1.1 09:22:00 INFO - mozInstall == 1.12 09:22:00 INFO - mozcrash == 1.0 09:22:00 INFO - mozdebug == 0.1 09:22:00 INFO - mozdevice == 0.48 09:22:00 INFO - mozfile == 1.2 09:22:00 INFO - mozhttpd == 0.7 09:22:00 INFO - mozinfo == 0.9 09:22:00 INFO - mozleak == 0.1 09:22:00 INFO - mozlog == 3.4 09:22:00 INFO - moznetwork == 0.27 09:22:00 INFO - mozprocess == 0.23 09:22:00 INFO - mozprofile == 0.28 09:22:00 INFO - mozrunner == 6.13 09:22:00 INFO - mozscreenshot == 0.1 09:22:00 INFO - mozsystemmonitor == 0.3 09:22:00 INFO - moztest == 0.7 09:22:00 INFO - mozversion == 1.4 09:22:00 INFO - psutil == 3.1.1 09:22:00 INFO - requests == 1.2.3 09:22:00 INFO - wsgiref == 0.1.2 09:22:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:00 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 0x100988490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008fe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf72ca4c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10079cd30>, '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 0x100602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100794b20>, '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.fu7cYDdySq/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.LBGXazLp1d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:22:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:22:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:22:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fu7cYDdySq/Render', 09:22:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:00 INFO - 'HOME': '/Users/cltbld', 09:22:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:00 INFO - 'LOGNAME': 'cltbld', 09:22:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:00 INFO - 'MOZ_NO_REMOTE': '1', 09:22:00 INFO - 'NO_EM_RESTART': '1', 09:22:00 INFO - 'PAGER': '/bin/cat', 09:22:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:00 INFO - 'PWD': '/builds/slave/test', 09:22:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:00 INFO - 'SHELL': '/bin/bash', 09:22:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LBGXazLp1d/Listeners', 09:22:00 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 09:22:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:00 INFO - 'USER': 'cltbld', 09:22:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:00 INFO - 'XPC_FLAGS': '0x0', 09:22:00 INFO - 'XPC_SERVICE_NAME': '0', 09:22:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:22:01 INFO - Cleaning up... 09:22:01 INFO - Return code: 0 09:22:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:22:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:22:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:22:01 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 0x100988490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008fe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf72ca4c40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10079cd30>, '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 0x100602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100794b20>, '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.fu7cYDdySq/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.LBGXazLp1d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:22:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:22:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:22:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fu7cYDdySq/Render', 09:22:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:01 INFO - 'HOME': '/Users/cltbld', 09:22:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:01 INFO - 'LOGNAME': 'cltbld', 09:22:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:01 INFO - 'MOZ_NO_REMOTE': '1', 09:22:01 INFO - 'NO_EM_RESTART': '1', 09:22:01 INFO - 'PAGER': '/bin/cat', 09:22:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:01 INFO - 'PWD': '/builds/slave/test', 09:22:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:01 INFO - 'SHELL': '/bin/bash', 09:22:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LBGXazLp1d/Listeners', 09:22:01 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 09:22:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:01 INFO - 'USER': 'cltbld', 09:22:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:01 INFO - 'XPC_FLAGS': '0x0', 09:22:01 INFO - 'XPC_SERVICE_NAME': '0', 09:22:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:22:01 INFO - Cleaning up... 09:22:01 INFO - Return code: 0 09:22:01 INFO - Running post-action listener: _resource_record_post_action 09:22:01 INFO - Running post-action listener: _start_resource_monitoring 09:22:01 INFO - Starting resource monitoring. 09:22:01 INFO - [mozharness: 2016-11-28 17:22:01.385568Z] Finished create-virtualenv step (success) 09:22:01 INFO - [mozharness: 2016-11-28 17:22:01.386614Z] Running install step. 09:22:01 INFO - Running pre-action listener: _resource_record_pre_action 09:22:01 INFO - Running main action method: install 09:22:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:22:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:22:01 INFO - Reading from file tmpfile_stdout 09:22:01 INFO - Detecting whether we're running mozinstall >=1.0... 09:22:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:22:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:22:01 INFO - Reading from file tmpfile_stdout 09:22:01 INFO - Output received: 09:22:01 INFO - Usage: mozinstall [options] installer 09:22:01 INFO - Options: 09:22:01 INFO - -h, --help show this help message and exit 09:22:01 INFO - -d DEST, --destination=DEST 09:22:01 INFO - Directory to install application into. [default: 09:22:01 INFO - "/builds/slave/test"] 09:22:01 INFO - --app=APP Application being installed. [default: firefox] 09:22:01 INFO - mkdir: /builds/slave/test/build/application 09:22:01 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'] 09:22:01 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 09:22:32 INFO - Reading from file tmpfile_stdout 09:22:32 INFO - Output received: 09:22:32 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:22:32 INFO - Running post-action listener: _resource_record_post_action 09:22:32 INFO - [mozharness: 2016-11-28 17:22:32.729968Z] Finished install step (success) 09:22:32 INFO - [mozharness: 2016-11-28 17:22:32.730097Z] Running run-tests step. 09:22:32 INFO - Running pre-action listener: _resource_record_pre_action 09:22:32 INFO - Running main action method: run_tests 09:22:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:22:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:22:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:22:32 INFO - 2016-11-28 09:22:32.742 screenresolution[1629:7483] starting screenresolution argv=screenresolution get 09:22:32 INFO - 2016-11-28 09:22:32.744 screenresolution[1629:7483] Display 0: 1600x1200x32@60 09:22:32 INFO - 2016-11-28 09:22:32.749 screenresolution[1630:7485] starting screenresolution argv=screenresolution list 09:22:32 INFO - Available Modes on Display 0 09:22:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:22:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:22:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:22:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:22:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:22:32 INFO - Intel Iris: 09:22:32 INFO - Chipset Model: Intel Iris 09:22:32 INFO - Type: GPU 09:22:32 INFO - Bus: Built-In 09:22:32 INFO - VRAM (Dynamic, Max): 1536 MB 09:22:32 INFO - Vendor: Intel (0x8086) 09:22:32 INFO - Device ID: 0x0a2e 09:22:32 INFO - Revision ID: 0x0009 09:22:32 INFO - Displays: 09:22:32 INFO - Display: 09:22:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:22:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:22:32 INFO - Display Serial Number: 1600x1200 60 09:22:32 INFO - Main Display: Yes 09:22:32 INFO - Mirror: Off 09:22:32 INFO - Online: Yes 09:22:32 INFO - Rotation: Supported 09:22:32 INFO - Return code: 0 09:22:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:22:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:22:32 INFO - Python 2.7.3 09:22:32 INFO - Return code: 0 09:22:32 INFO - grabbing minidump binary from tooltool 09:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:22:32 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10079cd30>, '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 0x100602db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100794b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:22:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:22:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:22:32 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 09:22:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:22:33 INFO - Return code: 0 09:22:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:22:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:22:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:22:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:22:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:22:33 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/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0212', '--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 09:22:33 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/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0212 --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 09:22:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fu7cYDdySq/Render', 09:22:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:22:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:22:33 INFO - 'HOME': '/Users/cltbld', 09:22:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:22:33 INFO - 'LOGNAME': 'cltbld', 09:22:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:22:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:22:33 INFO - 'MOZ_NO_REMOTE': '1', 09:22:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:22:33 INFO - 'NO_EM_RESTART': '1', 09:22:33 INFO - 'PAGER': '/bin/cat', 09:22:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:22:33 INFO - 'PWD': '/builds/slave/test', 09:22:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:22:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:22:33 INFO - 'SHELL': '/bin/bash', 09:22:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LBGXazLp1d/Listeners', 09:22:33 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 09:22:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:22:33 INFO - 'USER': 'cltbld', 09:22:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:22:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:22:33 INFO - 'XPC_FLAGS': '0x0', 09:22:33 INFO - 'XPC_SERVICE_NAME': '0', 09:22:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:22:33 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/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0212', '--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 09:22:33 INFO - mozversion application_buildid: 20161128065958 09:22:33 INFO - mozversion application_changeset: 786488b0735ed48d429723c2aba4e543c1b69b37 09:22:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:22:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:22:33 INFO - mozversion application_name: Firefox 09:22:33 INFO - mozversion application_remotingname: firefox-dev 09:22:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:22:33 INFO - mozversion application_vendor: Mozilla 09:22:33 INFO - mozversion application_version: 52.0a2 09:22:33 INFO - mozversion platform_buildid: 20161128065958 09:22:33 INFO - mozversion platform_changeset: 786488b0735ed48d429723c2aba4e543c1b69b37 09:22:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:22:33 INFO - mozversion platform_version: 52.0a2 09:22:33 INFO - using testdate: 1480353753 09:22:33 INFO - actual date: 1480353753 09:22:33 INFO - starting webserver on 'localhost:49251' 09:22:33 INFO - SUITE-START | Running 2 tests 09:22:33 INFO - TEST-START | dromaeo_css 09:22:33 INFO - operating with platform_type : mac_ 09:22:33 INFO - Initialising browser for dromaeo_css test... 09:22:33 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmp7HjuOL/profile http://localhost:49251/getInfo.html) 09:22:35 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 09:22:35 INFO - PROCESS | 1640 | colorDepth:24 09:22:35 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 09:22:35 INFO - PROCESS | 1640 | __metrics 09:22:36 INFO - PROCESS | 1640 | 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" 09:22:36 INFO - PROCESS | 1640 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:22:36 INFO - TEST-INFO | 1640: exit 0 09:22:36 INFO - Browser initialized. 09:22:36 INFO - Running cycle 1/1 for dromaeo_css test... 09:22:36 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmp7HjuOL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:49 INFO - PROCESS | 1642 | RSS: Main: 223285248 09:22:49 INFO - PROCESS | 1642 | 09:23:40 INFO - PROCESS | 1642 | 1480353820319 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] 09:25:53 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 09:25:54 INFO - PROCESS | 1642 | RSS: Main: 355926016 09:25:54 INFO - PROCESS | 1642 | 09:28:57 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 09:28:58 INFO - PROCESS | 1642 | RSS: Main: 350486528 09:28:58 INFO - PROCESS | 1642 | 09:32:00 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 09:32:01 INFO - PROCESS | 1642 | RSS: Main: 317329408 09:32:01 INFO - PROCESS | 1642 | 09:35:03 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 09:35:04 INFO - PROCESS | 1642 | RSS: Main: 314073088 09:35:04 INFO - PROCESS | 1642 | 09:38:07 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 09:38:08 INFO - PROCESS | 1642 | RSS: Main: 310132736 09:38:08 INFO - PROCESS | 1642 | 09:41:11 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 09:41:11 INFO - PROCESS | 1642 | RSS: Main: 364539904 09:41:11 INFO - PROCESS | 1642 | 09:41:11 INFO - PROCESS | 1642 | __start_tp_report 09:41:11 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 09:41:11 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 09:41:11 INFO - PROCESS | 1642 | |i|pagename|runs| 09:41:11 INFO - PROCESS | 1642 | |0;dojo.html;3280.6883346082127;3286.326303107863;3289.2434740009803;3301.9339613207735;3346.4980252962055;4083.9177617931273;4308.827646894124;4815.759212039393;5240.082985477546;5459.508644222019;5406.8918621627645;5451.073317536018;5465.180222966557;5503.3120859892515;5516.172574113885;5034.748262586874;5038.4457709652;5042.672226305279;5070.923936140961;5102.948970510303;5041.193409054552;5041.899162016757;5057.671251368649;5110.310108135392;5174.353205849269;5060.39275286966;5083.389993200821;5119.692818430887;5125.256837758523;5126.871828204287;4070.6743460523235;5126.102931986888;5132.692038477666;5136.614753893436;5175.404828444739;5092.159793634046;5095.719735414551;5119.6160287978555;5125.897482050375;5152.690838549662;5396;5398.811041613525;5430.239766432703;5431.972840135813;5452.1276595744885;5952.880942381172;5955.43423374783;5971.193888824996;5990.221271234712;6000.819975400745;6064.848378790522;6075;6075.969620151871;6083.087536869461;6086.726097325587;5449.264349312832;5499.340079190522;5510.421405752419;5517.751701173497;5571.749271282802;6187.288461826857;6222.564420490522;6222.782202622887;6226.470749986302;6260.968695156495;5145.536901678867;5619.606627536115;5829.5044921181325;5831.358550559435;5854.356020837705;5798.884022319615;5924.940750592439;5933.6736479493175;5988.041913293852;6009.729562169713;5837.854053648693;5911.527077833763;5925.111233314951;5929.703514824241;5929.822105336847;5955.851103722355;5956.940430595639;5960.791372302036;5970.910436343458;5972.492338151219;4638.327442520883;4784.162771514971;4787.042591481718;4798.376211092605;4821.421429428491;4249.192653395845;4258.5954334338185;4306.633936115465;4318.913621727611;4338.674599405057;5644.943550564524;5660.235868157746;5661.745221465044;5662;5662.8301150965535;5949.10763385487;5974.163617093611;5975.52195824333;5988.610740301867;5989.101634754822;4358.738475691405;5522.447755224526;5629.8592535186945;5678.4605462480995;5679.574031947621;5179.119549676564;5199.558037566773;5204.947950520522;5207.010667973073;5208.453112423141;6320.988641817287;6335;6340.4293613575;6348.904766428507;6353.714082866374;5976.641401515749;6028.547858910511;6032.8793424132145;6081.787137450345;6091.725872335667;4862.440819305683;6322.936770632235;6341.524385671185;6344.492440604648;6360.872782544231;5769.365369809401;5794.7102644866745;5836.970815146067;5844.707764611668;5938.376470470538;5934.762609495745;5964.701764911824;5976.9103463448955;5989.131575921554;6025.698715064317;5553.0837411828215;5743.62666426681;5748.683822389641;5751.309842818889;5760.8271751847515;5517.393086760533;5583.357913839798;5738.5696072795545;5748.798792042258;5761.625494342854;4672.135654903854;5380.704061276668;5664;5699.8860022800145;5736.627119237236;5819.127130930225;5819.5344372450945;5838;5839.912401314069;5843.065109582446;5680.147977803197;5683.318001839806;5687.886242275049;5699.51554117888;5734.741936612779 09:41:11 INFO - PROCESS | 1642 | |1;ext.html;412.36289932054973;495.30162470916014;511.69809812210787;522.5062316111274;529.3753371022194;29995.850020749895;30325.090247292588;31580.52629210564;31648;31730.04809855708;32087.839560802222;32688.509672354874;32715.182120447;32772.508412373776;40284.59715402845;9136.497492637902;9402.012788657195;9423.623055077824;9429.858552121723;9433.669821556212;9273.258139348836;9358.017408172145;9359;9369.29730270229;9392.577334019985;9330.440173589606;9386.859197112015;9386.906130938705;9414.340996130273;9425.38734982224;8187;9210.723678289662;9308.394954327947;9358.766030849216;9384.624615015426;9226.53867306632;9305.302102342352;9321.673741419017;9326.44041357514;9329.766755831091;38018.619813801786;38234.235315293816;38235;38253.61746382556;38342.80828595867;3115.5482455044116;3223.2425380035675;3334.4498157803932;3634.9636503635156;3787.5644300905474;30244.8487757562;30246.69753302461;30573.541396879064;30673.69326306753;30913.227169320722;3120.4851199552045;3173.1749745066218;3195.888143914952;3196.8721251149927;3247.9675203247903;498194;498540;499224;500239;500244.99999999633;33003.83498082494;57796;58338.41661583415;58377.8324433509;58387.416125839045;33731.662683373346;33820;34714.30571388609;34821.30357392838;34912.476312855324;14434.278286085653;14540.927295363666;14578.489752858599;14585.343659535347;14589.854101459061;12215.267083974812;12311.322877241659;12311.56909508163;12322.137450378388;12322.630321090382;7648.470305938835;7758.456908016497;7773.86211346598;7827.765167044998;7828.530288182727;2696.6763988321136;3152.8896488622786;3155.25851424915;3187.15540381799;3202.151429871086;898.7079199260266;991.548845275398;1004.4776716107583;1010.6664800615779;1014.1987829614669;28502.287442814097;28747.425051498853;29029;29215.85392073026;29293.267668308035;841.8021764885247;1031.504877658728;1039.152721542967;1041.6406339812752;1154.4804837823012;14709.485168019068;14795.334209960578;14827.481038163613;14890.106593604418;14893.859227985959;586797;704704;705303.9469605341;705640;706596;55492;55740;55891;55903.440965590635;55924;1220.0605533738972;1246.8815462531045;1336.1782503759923;1348.4336578636996;1370.4792178972127;24152;28516;29306;30187;31723.84138079295;46179;52337.21494177666;53387;53579;56139.8772024549;617386;622679.8866005641;622941;623488;625109;23965;24744.752552474965;24791.132310369052;25368.619470707585;26454.206373808818;44043.96346977476;44278;44575.33137003012;44867.32699009452;44876;36762.080947976516;36894;37278.44082338821;38427.61572384352;38578.228435431694;30735;36962.07594810151;37092.07269818276;37209.62790372062;37331.69339073119;29385.559216611342;35556.64443355533;35767.82116089507;36173.276534468634;36708;29036.983705570205;31142.53286200754;31272.06183814489;32566.511502327958;33110.86781548864;20961.475963100434;21111.366659000254;24683.01267949262;24873.253802385956;25123.371915702253 09:41:11 INFO - PROCESS | 1642 | |2;jquery.html;320.6729136280993;350.95262139611145;371.85869369639533;373.1064101476962;398.98458423312667;14989.291233719452;15291.464798732046;16487.999999999985;16973.3210671573;20563.897180514115;18326.266949322013;19218.135183916722;19933.700994485058;20860.687089693667;20930.581388372222;5775.104858746901;6156.907646385308;6159.291681456645;6165.506759459233;6174.5060395168275;3967.9206415871663;5991.940080599204;6818.454523638122;6902.965485172567;6907.861842763142;5766.365699773021;6812.727490900358;6834.453243740513;6837.863242735142;6841.726330946756;6003.999999999978;6139.938600614026;6151.784687535959;6152.753889844401;6167.414095660905;5811.099279611667;5860.677662728548;5906.143609176688;5934.881302373971;5997.7001149942325;13125.146865453715;13137.343132843414;13147.079704420694;13347.399367028507;13374.33128343579;2119.078200982562;2123.097683484513;2134.2636790307474;2162.308061420346;2276.226083131743;9917.702468925943;10343.275970682056;10349.327293725884;10513.579456821719;14720.411183552646;1816.3370369814922;1896.7249748786348;1910.388675623801;1922.09661459114;1925.7482636286386;107639;115423;124009.75990240072;128966.42067158605;133026;9287.071292870658;10755.56977720898;11002.779944401067;11453;11586.304821710724;14275.64310892236;14295.927805414636;14309.78535321971;14319.85680143206;14330.85669143316;8282.461639993382;8554.272886804567;8605.827883442296;8689.739307820775;8712.346574006975;8521;8536.359773016924;8542.572871356408;8714.607842647096;8722.433041852259;3704.2961837250837;4386.956130438719;4470.865874023784;4483.417155769795;4591.311303304531;1590.6421055262474;1838.3014454507202;1892.5457890106275;1902.73361729358;1906.485248982767;838.2455789789238;890.643742502991;996.6910257820099;998.4408731110456;1024.6618615856748;20402.89798550998;21235.893820531106;21469.355919322446;21486.247981320892;21529.67705484419;830.3564737328497;910.1671970147241;918.894327152386;923.9769005774775;973.4597298499259;5753.309602847685;5862.912056319158;5904.261967254093;5914.615549989237;6047.667378294236;529424;601070;625029;646934;656322;114965;123872;124715.37642311912;134464.32767836098;136070;428.5157771717939;449.7526360501711;450.86023332766945;451.4199253958647;452.6650278793735;11140.442977850913;11343.319400835977;11470.885291146982;11617.709557261136;11687.94808467242;48204.86668366545;50043.99560044112;50510.73723156803;51284.48715512797;53277;60259.397406027136;72450.63774681093;72717.6364118176;73878.63060684662;75908;7582.886256706043;7693.422993275415;7785.182555831556;7972.561509117054;8059.153788852086;9924;11361.602343917923;12098.455569499218;12141.332226727262;12376.319302438453;10041.799164016826;10092.646757363456;10130.898691012995;10225.130863876653;10481.685549433529;9204.815903682023;9552.713418597175;9821.754456138653;9837.967013463769;10297.124744396511;8411.663533458594;10484.842727358942;10529.526171322155;10566.735831604272;10623.628173013907;7635.312821846061;7714.729984450517;8025;8025.5987200638565;8106.39202059836;9138.451692898447;9744.366616169926;10085.991400859855;10251.538680759533;10365 09:41:11 INFO - PROCESS | 1642 | |3;mootools.html;1289.1427200911394;1473.6463248820282;1485.7919891215229;1648.67026594681;1815.0561707911877;6593.340665933404;7853.371730261572;7862.764117076483;7862.76411707649;7867.449278550504;7977.20227977202;8284.461510001845;8329.125441828612;8359.247667709906;8364.079951205378;2296.7243930728337;2329.406001469623;2330.755270696578;2335.2760644200266;2335.2994101769486;1962.479942815155;2301.309607117866;2343.4727186383097;2344.6248600223967;2348.3189874936247;2272.4432514034083;2288.5079707862806;2301.2060839010514;2306.9538609227807;2307.8730669813153;2254.7407048189502;2260.8756518391483;2284.394635421598;2288.290629904718;2288.668143119247;2291.633338665822;2316.5830150572892;2317;2318.4783423729527;2318.9652155217686;8271.007479102545;8316.126806685272;8374.49753014821;8393.874091888627;8395.496270223744;5040.6471546991725;5050.090983622983;5208.869778255536;5213.426523082458;5218.217267409919;3247.6427592964756;3327.73378129749;3438.3123375325035;3445.4487282035007;3450.0339904826687;1053.615430367918;1064.446487826326;1076.2842709598108;1084.5607528950788;1086.5219303506433;10533.999270069407;10685.626003089932;10850.620228292051;10859.239853210278;10874.45627718611;4546.318052292117;4613.792379342937;4659.697119687209;4722.527747225318;4727.338172655831;9521.761955951157;9530.046995300414;9558.522073896278;9560.665376711782;9563.330566860253;5243.030039442715;5249.107651699221;5250.947490525123;5257.4742525747115;5272.578193744491;3959.9604003960167;4205.474315710536;4323.978380108079;4326.766871441624;4708.081924024851;3379.8648054078053;3830.482884810571;3865.7100717446306;3880.952142921452;3939.980300098481;1228.65597632663;1237.1710953661068;1246.6634008817568;1264.051961029228;1275.4260582737804;776.5030380556449;860.5095095795461;902.4991129922956;907.650554536495;919.8896132464014;5626.324841019104;5753.913691294634;5757.827265182052;5854.4730974212525;5971.701414929236;905.9547022648841;908.8000639859221;918.995405022984;922.718570835888;985.6303886042734;3736.6076561961145;3766;3873.6320049595242;3879.4180872869297;3974.1058261890844;9775.90224097764;10270.435126068138;10273.845892311621;10275.691729248134;10289.588416463408;10243.334183278212;10258.794824103476;10263.640772572924;10278.280520363503;10283.023112804272;6447.774327898501;7787.493812902257;7802.570858602831;7806.6096695165015;7815.02312210985;5553.389127196086;5715.8571035724435;5741.196232527533;5743.052396354722;5750.568707346882;9127.178553930178;9135;9157.679481218125;9194.218491428304;9409.29430292717;7472.775816725507;9478.38390504615;9487;9606.231501480004;9634.807303854024;7035.296470352924;7167.641617919187;7182;7196.244394338519;7202.639868006683;7503.287187717158;7668.118166410711;7759.92240077592;7764.107127680163;7768.145503994443;7005.684744186422;7008.123984501937;7043.647817609201;7187.281271872771;7203.063601731951;6887.311268873073;6981.476389270927;7041.647917604202;7060.188078371053;7076.398506126831;6578.572392794453;6582.210134783857;6616.636085015371;6635.369639884203;6751.898721519279;4758.095961767253;4767.833125840579;4830.500907765107;4859.416869975626;4947.208446648519;4831.782569784298;4868.9756551217015;4943.406791185081;4955.72743499111;4984.277279794482 09:41:11 INFO - PROCESS | 1642 | |4;prototype.html;297.187193027071;301.80835169667256;306.14585306993484;310.5279974438853;313.4326868368252;5097.464766199551;5103.311053007843;5126.307948426966;5184.974075129629;5187.455317191688;4030.4281330281215;4039.5255731657953;4412.955870441295;4518.277075667894;5190.455002224769;3523.4010218262956;3747.4566187902738;3937.507811523545;3969.384745364473;4005.6194661507184;3938.2911076006308;3995.5804640512765;4011.4183443400834;4029.5970402959615;4066.6543343815665;3154.1326135312856;3296.3901678189613;3443.7933723976644;3722.2133375979547;3979.8408063677416;3299.785513941586;3486.3375958568045;3579.910502237439;3746.531683539571;3754.342989976743;3032.605761251023;3366.6633336666137;3416.5479671139665;3439.7076248518906;3471.94792078119;3771.6039815819495;4582.977085114587;4864.854054378375;4941.629377796679;4981.352424184833;3556.039869235318;3685.981570092159;4118.073433477473;4272.4445822042935;4416.558344165558;4854.417469903634;5139.820106296299;5261.000409922102;5333.599980001515;5369.959415422154;3174.5238214267815;3623.6738693517445;3681.4293784463453;3709.3694072007825;3753.680937120348;4249.383839343278;4977.0792403405485;5375.43557926416;5448.673079615196;5546.168074788733;3152.763542734304;3164.9525257121163;3733.815717805114;3777.320082385197;3795.7532760370486;3451.0854623524788;3818.816166988187;3852.24881148179;3855.4216867470104;3857.28640201558;4207.389928460418;4370.192787933248;4379.28484339705;4392.626626736763;4427.712198707074;5242.9213561796605;5304.522592966651;5359.330083739533;5411.998780225592;5439.6672815159745;1022.8721409823772;1139.5384869128015;1214.36245970866;1214.975700486003;1234.703671118925;672.1430176524872;689.4828878341244;802.2739420824164;836.8367968523756;860.475110182788;4357.694961352675;4809.282755243346;5009.169819052224;5044.142495775727;5174.404943431478;5246.895062098737;5499.450054994469;5517.062099443105;5518.475744804237;5536.446355364512;3989.1223930735773;4583.495815460296;4597.563231493003;4778.737169455644;5143.922841157386;2321.129576408847;2405.7233418157134;2473.4646986838634;2585.5216784894856;2672.154155824041;5147.5881929446305;5480.355893232094;5493.945060549423;5519.240379810095;5539.2522009529;4438.622717069021;4547.47704014036;4553.724957012042;4558.475775285885;4570.611498022638;5492.835214943558;5499.50504454601;5508.421615730371;5520.779168833202;5565.053940830068;4729.618322899144;4743.5019322971975;5188.766505507186;5207.71357575337;5211.55701765339;4700.905981880412;4794.9280760787915;4983.601311895112;5100.413452452867;5120.950205207998;4766.5471780182215;4917.606591472745;4927.679700819435;5046.268291097844;5388.973055134699;3512.227309991798;3516;3520.2783429397423;3522.718182545339;3551.911202219965;4766.833160839354;5128.923066153936;5145.536901678867;5179.48205179479;5217.686938783685;4412.955870441255;4475.9328610071525;5026.597872170145;5040.647154699136;5060.84817455477;4463.821447142078;5001.924971125509;5025.798968041384;5031.6226283029655;5066.746662666926;4294.957050429456;4366.214081465367;4711.175544279806;4819.096531809246;4961.801527938842;4228;4768.237082066853;4949.480304567968;5218.712970786643;5362.490563396471;4584.954150458586;4644.907101858012;5123.692578445305;5257;5260.342457192851 09:41:11 INFO - PROCESS | 1642 | |5;yui.html;691.5435812363838;722.9783106506807;731.4660297982474;746.4066067476356;754.1252147508891;14107;14769.335379907916;14790.186539740336;14799.260036998134;14808.037477563965;12288.508459661603;12291.385430728473;12352.505899763999;12388;12388.752224955519;648.0861984601712;671.1576970901511;674.4536925090667;675.7263308360122;814.336315902539;667.2993356975181;667.9231888332831;802.0615879421091;829.6059371798377;837.4933165434916;868.891388576428;883.8585826267798;887.6138879587368;887.6626881784945;889.6130183370223;874.4141425245101;875.5315905990265;876.0713643537838;876.1019954546578;880.9779755506164;502.8039064764745;513.7662363624542;519.1200914450009;520.9583233341324;646.2148489585177;12034.157608967376;13021.28382938938;13500.797488037688;13527.661808454768;13856.307184640727;13299.933500332532;13735.725285494234;13798.93100534501;13800.861991380058;13827.170369777845;10938.179636527293;11122.666320010412;11136.33182009082;11440.027597654209;11501.367424791633;3649.4708267301094;3668.834902429397;3709.51776269086;3726.5900750917376;3756.887293381203;82294.58852705758;87397;87753.56123219407;88372;88453;10237.539310731036;10344.482775861328;10477.37135771856;10485.951404859605;10516.68449946503;19851.503712407306;19931.60136797256;20052.39842804718;20063.197472100954;20076.297329593395;7425.740099096506;7516.098068231847;7526.5107767994905;7556.962215188999;7775.105862825847;4184.225918205142;4271.337942618899;4287.7212981156745;4323.286657701507;4421.712588681726;3385.0691059958217;3483.2336885885065;3487.8256087195537;3488.66857648523;3513.94729079064;597.2385208858653;644.8710257948429;645.6126324205533;649.9317571654908;651.5439192565224;520.7865672981943;530.6444682063027;608.6013661052018;646.1793522226745;657.263864471785;8306.335493160532;8629.870551941725;8762.95618521916;8799.91200087991;8828.249598784047;590.167863312727;610.6397225636896;632.4466092169351;637.8405398650337;660.5013215022627;3420.7776494527775;3642.863900493454;4361.520232774392;4398.582134697199;4491.191585514639;75587.86618200732;98855.50572247093;102282;112050.31924521139;121762.78237217515;86530.56734716412;87690;91984;95956.52021739987;98187.52718709224;78754.42491150145;80015.59992200119;80557.59721201591;82017;84808;9238.584263708284;9567.186789123003;9679.032096790264;9681.9031809681;9686;52613;53819.73090134524;54362.728186358814;54814;56995;77527.83708244268;77608.22391776236;78945;82739.69041238283;84164.57917710372;11395.202335836411;11675.824862627238;11806.940965295118;12037.157398981988;12113.75772484563;13274.668133296746;14098.718025639635;14244.786328204878;14933.477328293458;14974.326155322819;12161.695957600778;12303.56937507183;12463.688407789878;12609.810852837032;12757.48970041225;11894.107941904218;12023.398830058637;12198;12322.137450378388;12456.75086498283;12490.125691201893;12665.683357916127;12690.555830546256;12715.237085774883;12883.935580322039;10551.670706027464;10587.841182382117;10662.360258384522;10729.141668666643;11290;10964.342139471657;11187.272827266203;11191.832122518006;11278.718032049264;11310.321380717183 09:41:11 INFO - PROCESS | 1642 | __end_tp_report 09:41:11 INFO - PROCESS | 1642 | __start_cc_report 09:41:11 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,1406 09:41:11 INFO - PROCESS | 1642 | __end_cc_report 09:41:11 INFO - PROCESS | 1642 | __startTimestamp1480354871528__endTimestamp 09:41:11 INFO - PROCESS | 1642 | 09:41:11 INFO - PROCESS | 1642 | ------- Summary: start ------- 09:41:11 INFO - PROCESS | 1642 | Number of tests: 6 09:41:11 INFO - PROCESS | 1642 | 09:41:11 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5495.73 Median:5678.46 stddev:643.81 (11.3%) stddev-sans-first:621.89 09:41:11 INFO - PROCESS | 1642 | Values: 3280.7 3286.3 3289.2 3301.9 3346.5 4083.9 4308.8 4815.8 5240.1 5459.5 5406.9 5451.1 5465.2 5503.3 5516.2 5034.7 5038.4 5042.7 5070.9 5102.9 5041.2 5041.9 5057.7 5110.3 5174.4 5060.4 5083.4 5119.7 5125.3 5126.9 4070.7 5126.1 5132.7 5136.6 5175.4 5092.2 5095.7 5119.6 5125.9 5152.7 5396.0 5398.8 5430.2 5432.0 5452.1 5952.9 5955.4 5971.2 5990.2 6000.8 6064.8 6075.0 6076.0 6083.1 6086.7 5449.3 5499.3 5510.4 5517.8 5571.7 6187.3 6222.6 6222.8 6226.5 6261.0 5145.5 5619.6 5829.5 5831.4 5854.4 5798.9 5924.9 5933.7 5988.0 6009.7 5837.9 5911.5 5925.1 5929.7 5929.8 5955.9 5956.9 5960.8 5970.9 5972.5 4638.3 4784.2 4787.0 4798.4 4821.4 4249.2 4258.6 4306.6 4318.9 4338.7 5644.9 5660.2 5661.7 5662.0 5662.8 5949.1 5974.2 5975.5 5988.6 5989.1 4358.7 5522.4 5629.9 5678.5 5679.6 5179.1 5199.6 5204.9 5207.0 5208.5 6321.0 6335.0 6340.4 6348.9 6353.7 5976.6 6028.5 6032.9 6081.8 6091.7 4862.4 6322.9 6341.5 6344.5 6360.9 5769.4 5794.7 5837.0 5844.7 5938.4 5934.8 5964.7 5976.9 5989.1 6025.7 5553.1 5743.6 5748.7 5751.3 5760.8 5517.4 5583.4 5738.6 5748.8 5761.6 4672.1 5380.7 5664.0 5699.9 5736.6 5819.1 5819.5 5838.0 5839.9 5843.1 5680.1 5683.3 5687.9 5699.5 5734.7 09:41:11 INFO - PROCESS | 1642 | 09:41:11 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:70353.33 Median:20171.48 stddev:162974.54 (807.9%) stddev-sans-first:163347.12 09:41:11 INFO - PROCESS | 1642 | Values: 412.4 495.3 511.7 522.5 529.4 29995.9 30325.1 31580.5 31648.0 31730.0 32087.8 32688.5 32715.2 32772.5 40284.6 9136.5 9402.0 9423.6 9429.9 9433.7 9273.3 9358.0 9359.0 9369.3 9392.6 9330.4 9386.9 9386.9 9414.3 9425.4 8187.0 9210.7 9308.4 9358.8 9384.6 9226.5 9305.3 9321.7 9326.4 9329.8 38018.6 38234.2 38235.0 38253.6 38342.8 3115.5 3223.2 3334.4 3635.0 3787.6 30244.8 30246.7 30573.5 30673.7 30913.2 3120.5 3173.2 3195.9 3196.9 3248.0 498194.0 498540.0 499224.0 500239.0 500245.0 33003.8 57796.0 58338.4 58377.8 58387.4 33731.7 33820.0 34714.3 34821.3 34912.5 14434.3 14540.9 14578.5 14585.3 14589.9 12215.3 12311.3 12311.6 12322.1 12322.6 7648.5 7758.5 7773.9 7827.8 7828.5 2696.7 3152.9 3155.3 3187.2 3202.2 898.7 991.5 1004.5 1010.7 1014.2 28502.3 28747.4 29029.0 29215.9 29293.3 841.8 1031.5 1039.2 1041.6 1154.5 14709.5 14795.3 14827.5 14890.1 14893.9 586797.0 704704.0 705303.9 705640.0 706596.0 55492.0 55740.0 55891.0 55903.4 55924.0 1220.1 1246.9 1336.2 1348.4 1370.5 24152.0 28516.0 29306.0 30187.0 31723.8 46179.0 52337.2 53387.0 53579.0 56139.9 617386.0 622679.9 622941.0 623488.0 625109.0 23965.0 24744.8 24791.1 25368.6 26454.2 44044.0 44278.0 44575.3 44867.3 44876.0 36762.1 36894.0 37278.4 38427.6 38578.2 30735.0 36962.1 37092.1 37209.6 37331.7 29385.6 35556.6 35767.8 36173.3 36708.0 29037.0 31142.5 31272.1 32566.5 33110.9 20961.5 21111.4 24683.0 24873.3 25123.4 09:41:11 INFO - PROCESS | 1642 | 09:41:11 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:34307.89 Median:4477.14 stddev:102428.58 (2287.8%) stddev-sans-first:102684.13 09:41:11 INFO - PROCESS | 1642 | Values: 320.7 351.0 371.9 373.1 399.0 14989.3 15291.5 16488.0 16973.3 20563.9 18326.3 19218.1 19933.7 20860.7 20930.6 5775.1 6156.9 6159.3 6165.5 6174.5 3967.9 5991.9 6818.5 6903.0 6907.9 5766.4 6812.7 6834.5 6837.9 6841.7 6004.0 6139.9 6151.8 6152.8 6167.4 5811.1 5860.7 5906.1 5934.9 5997.7 13125.1 13137.3 13147.1 13347.4 13374.3 2119.1 2123.1 2134.3 2162.3 2276.2 9917.7 10343.3 10349.3 10513.6 14720.4 1816.3 1896.7 1910.4 1922.1 1925.7 107639.0 115423.0 124009.8 128966.4 133026.0 9287.1 10755.6 11002.8 11453.0 11586.3 14275.6 14295.9 14309.8 14319.9 14330.9 8282.5 8554.3 8605.8 8689.7 8712.3 8521.0 8536.4 8542.6 8714.6 8722.4 3704.3 4387.0 4470.9 4483.4 4591.3 1590.6 1838.3 1892.5 1902.7 1906.5 838.2 890.6 996.7 998.4 1024.7 20402.9 21235.9 21469.4 21486.2 21529.7 830.4 910.2 918.9 924.0 973.5 5753.3 5862.9 5904.3 5914.6 6047.7 529424.0 601070.0 625029.0 646934.0 656322.0 114965.0 123872.0 124715.4 134464.3 136070.0 428.5 449.8 450.9 451.4 452.7 11140.4 11343.3 11470.9 11617.7 11687.9 48204.9 50044.0 50510.7 51284.5 53277.0 60259.4 72450.6 72717.6 73878.6 75908.0 7582.9 7693.4 7785.2 7972.6 8059.2 9924.0 11361.6 12098.5 12141.3 12376.3 10041.8 10092.6 10130.9 10225.1 10481.7 9204.8 9552.7 9821.8 9838.0 10297.1 8411.7 10484.8 10529.5 10566.7 10623.6 7635.3 7714.7 8025.0 8025.6 8106.4 9138.5 9744.4 10086.0 10251.5 10365.0 09:41:11 INFO - PROCESS | 1642 | 09:41:11 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5421.43 Median:5129.48 stddev:2967.99 (57.9%) stddev-sans-first:2960.06 09:41:11 INFO - PROCESS | 1642 | Values: 1289.1 1473.6 1485.8 1648.7 1815.1 6593.3 7853.4 7862.8 7862.8 7867.4 7977.2 8284.5 8329.1 8359.2 8364.1 2296.7 2329.4 2330.8 2335.3 2335.3 1962.5 2301.3 2343.5 2344.6 2348.3 2272.4 2288.5 2301.2 2307.0 2307.9 2254.7 2260.9 2284.4 2288.3 2288.7 2291.6 2316.6 2317.0 2318.5 2319.0 8271.0 8316.1 8374.5 8393.9 8395.5 5040.6 5050.1 5208.9 5213.4 5218.2 3247.6 3327.7 3438.3 3445.4 3450.0 1053.6 1064.4 1076.3 1084.6 1086.5 10534.0 10685.6 10850.6 10859.2 10874.5 4546.3 4613.8 4659.7 4722.5 4727.3 9521.8 9530.0 9558.5 9560.7 9563.3 5243.0 5249.1 5250.9 5257.5 5272.6 3960.0 4205.5 4324.0 4326.8 4708.1 3379.9 3830.5 3865.7 3881.0 3940.0 1228.7 1237.2 1246.7 1264.1 1275.4 776.5 860.5 902.5 907.7 919.9 5626.3 5753.9 5757.8 5854.5 5971.7 906.0 908.8 919.0 922.7 985.6 3736.6 3766.0 3873.6 3879.4 3974.1 9775.9 10270.4 10273.8 10275.7 10289.6 10243.3 10258.8 10263.6 10278.3 10283.0 6447.8 7787.5 7802.6 7806.6 7815.0 5553.4 5715.9 5741.2 5743.1 5750.6 9127.2 9135.0 9157.7 9194.2 9409.3 7472.8 9478.4 9487.0 9606.2 9634.8 7035.3 7167.6 7182.0 7196.2 7202.6 7503.3 7668.1 7759.9 7764.1 7768.1 7005.7 7008.1 7043.6 7187.3 7203.1 6887.3 6981.5 7041.6 7060.2 7076.4 6578.6 6582.2 6616.6 6635.4 6751.9 4758.1 4767.8 4830.5 4859.4 4947.2 4831.8 4869.0 4943.4 4955.7 4984.3 09:41:11 INFO - PROCESS | 1642 | 09:41:11 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4177.54 Median:4584.22 stddev:1283.88 (28.0%) stddev-sans-first:1254.01 09:41:11 INFO - PROCESS | 1642 | Values: 297.2 301.8 306.1 310.5 313.4 5097.5 5103.3 5126.3 5185.0 5187.5 4030.4 4039.5 4413.0 4518.3 5190.5 3523.4 3747.5 3937.5 3969.4 4005.6 3938.3 3995.6 4011.4 4029.6 4066.7 3154.1 3296.4 3443.8 3722.2 3979.8 3299.8 3486.3 3579.9 3746.5 3754.3 3032.6 3366.7 3416.5 3439.7 3471.9 3771.6 4583.0 4864.9 4941.6 4981.4 3556.0 3686.0 4118.1 4272.4 4416.6 4854.4 5139.8 5261.0 5333.6 5370.0 3174.5 3623.7 3681.4 3709.4 3753.7 4249.4 4977.1 5375.4 5448.7 5546.2 3152.8 3165.0 3733.8 3777.3 3795.8 3451.1 3818.8 3852.2 3855.4 3857.3 4207.4 4370.2 4379.3 4392.6 4427.7 5242.9 5304.5 5359.3 5412.0 5439.7 1022.9 1139.5 1214.4 1215.0 1234.7 672.1 689.5 802.3 836.8 860.5 4357.7 4809.3 5009.2 5044.1 5174.4 5246.9 5499.5 5517.1 5518.5 5536.4 3989.1 4583.5 4597.6 4778.7 5143.9 2321.1 2405.7 2473.5 2585.5 2672.2 5147.6 5480.4 5493.9 5519.2 5539.3 4438.6 4547.5 4553.7 4558.5 4570.6 5492.8 5499.5 5508.4 5520.8 5565.1 4729.6 4743.5 5188.8 5207.7 5211.6 4700.9 4794.9 4983.6 5100.4 5121.0 4766.5 4917.6 4927.7 5046.3 5389.0 3512.2 3516.0 3520.3 3522.7 3551.9 4766.8 5128.9 5145.5 5179.5 5217.7 4413.0 4475.9 5026.6 5040.6 5060.8 4463.8 5001.9 5025.8 5031.6 5066.7 4295.0 4366.2 4711.2 4819.1 4961.8 4228.0 4768.2 4949.5 5218.7 5362.5 4585.0 4644.9 5123.7 5257.0 5260.3 09:41:11 INFO - PROCESS | 1642 | 09:41:11 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:20154.34 Median:4410.15 stddev:29335.48 (665.2%) stddev-sans-first:29381.37 09:41:11 INFO - PROCESS | 1642 | Values: 691.5 723.0 731.5 746.4 754.1 14107.0 14769.3 14790.2 14799.3 14808.0 12288.5 12291.4 12352.5 12388.0 12388.8 648.1 671.2 674.5 675.7 814.3 667.3 667.9 802.1 829.6 837.5 868.9 883.9 887.6 887.7 889.6 874.4 875.5 876.1 876.1 881.0 502.8 513.8 519.1 521.0 646.2 12034.2 13021.3 13500.8 13527.7 13856.3 13299.9 13735.7 13798.9 13800.9 13827.2 10938.2 11122.7 11136.3 11440.0 11501.4 3649.5 3668.8 3709.5 3726.6 3756.9 82294.6 87397.0 87753.6 88372.0 88453.0 10237.5 10344.5 10477.4 10486.0 10516.7 19851.5 19931.6 20052.4 20063.2 20076.3 7425.7 7516.1 7526.5 7557.0 7775.1 4184.2 4271.3 4287.7 4323.3 4421.7 3385.1 3483.2 3487.8 3488.7 3513.9 597.2 644.9 645.6 649.9 651.5 520.8 530.6 608.6 646.2 657.3 8306.3 8629.9 8763.0 8799.9 8828.2 590.2 610.6 632.4 637.8 660.5 3420.8 3642.9 4361.5 4398.6 4491.2 75587.9 98855.5 102282.0 112050.3 121762.8 86530.6 87690.0 91984.0 95956.5 98187.5 78754.4 80015.6 80557.6 82017.0 84808.0 9238.6 9567.2 9679.0 9681.9 9686.0 52613.0 53819.7 54362.7 54814.0 56995.0 77527.8 77608.2 78945.0 82739.7 84164.6 11395.2 11675.8 11806.9 12037.2 12113.8 13274.7 14098.7 14244.8 14933.5 14974.3 12161.7 12303.6 12463.7 12609.8 12757.5 11894.1 12023.4 12198.0 12322.1 12456.8 12490.1 12665.7 12690.6 12715.2 12883.9 10551.7 10587.8 10662.4 10729.1 11290.0 10964.3 11187.3 11191.8 11278.7 11310.3 09:41:11 INFO - PROCESS | 1642 | -------- Summary: end -------- 09:41:11 INFO - PROCESS | 1642 | 09:41:12 INFO - TEST-INFO | 1642: exit 0 09:41:12 INFO - TEST-OK | dromaeo_css | took 1118902ms 09:41:12 INFO - TEST-START | kraken 09:41:12 INFO - operating with platform_type : mac_ 09:41:12 INFO - Initialising browser for kraken test... 09:41:12 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmp4RyRpA/profile http://localhost:49251/getInfo.html) 09:41:15 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 09:41:15 INFO - PROCESS | 1652 | colorDepth:24 09:41:15 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 09:41:15 INFO - PROCESS | 1652 | __metrics 09:41:15 INFO - PROCESS | 1652 | 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" 09:41:15 INFO - PROCESS | 1652 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:41:16 INFO - TEST-INFO | 1652: exit 0 09:41:16 INFO - Browser initialized. 09:41:16 INFO - Running cycle 1/1 for kraken test... 09:41:16 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmp4RyRpA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:41:28 INFO - PROCESS | 1653 | RSS: Main: 223334400 09:41:28 INFO - PROCESS | 1653 | 09:41:31 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 09:41:32 INFO - PROCESS | 1653 | RSS: Main: 274579456 09:41:32 INFO - PROCESS | 1653 | 09:41:34 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 09:41:35 INFO - PROCESS | 1653 | RSS: Main: 285265920 09:41:35 INFO - PROCESS | 1653 | 09:41:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 09:41:39 INFO - PROCESS | 1653 | RSS: Main: 380977152 09:41:39 INFO - PROCESS | 1653 | 09:41:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 09:41:41 INFO - PROCESS | 1653 | RSS: Main: 282546176 09:41:41 INFO - PROCESS | 1653 | 09:41:43 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:41:44 INFO - PROCESS | 1653 | RSS: Main: 283627520 09:41:44 INFO - PROCESS | 1653 | 09:41:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 09:41:47 INFO - PROCESS | 1653 | RSS: Main: 324620288 09:41:47 INFO - PROCESS | 1653 | 09:41:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 09:41:51 INFO - PROCESS | 1653 | RSS: Main: 326356992 09:41:51 INFO - PROCESS | 1653 | 09:41:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 09:41:54 INFO - PROCESS | 1653 | RSS: Main: 322723840 09:41:54 INFO - PROCESS | 1653 | 09:41:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:41:56 INFO - PROCESS | 1653 | RSS: Main: 298364928 09:41:56 INFO - PROCESS | 1653 | 09:41:58 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:41:59 INFO - PROCESS | 1653 | RSS: Main: 301363200 09:41:59 INFO - PROCESS | 1653 | 09:42:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:42:02 INFO - PROCESS | 1653 | RSS: Main: 303853568 09:42:02 INFO - PROCESS | 1653 | 09:42:05 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:42:05 INFO - PROCESS | 1653 | RSS: Main: 316157952 09:42:05 INFO - PROCESS | 1653 | 09:42:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:42:09 INFO - PROCESS | 1653 | RSS: Main: 316334080 09:42:09 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 09:42:11 INFO - PROCESS | 1653 | RSS: Main: 359628800 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | __start_tp_report 09:42:11 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 09:42:11 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 09:42:11 INFO - PROCESS | 1653 | |i|pagename|runs| 09:42:11 INFO - PROCESS | 1653 | |0;ai-astar;124;170;142;113;112;112;114;112;112;112 09:42:11 INFO - PROCESS | 1653 | |1;audio-beat-detection;149;117;112;148;115;115;107;113;144;113 09:42:11 INFO - PROCESS | 1653 | |2;audio-dft;186;182;186;184;182;183;182;182;185;184 09:42:11 INFO - PROCESS | 1653 | |3;audio-fft;80;58;57;87;60;58;59;57;85;50 09:42:11 INFO - PROCESS | 1653 | |4;audio-oscillator;87;70;95;70;66;69;91;68;69;91 09:42:11 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;87;86;87;85;87;84;88;85;87;84 09:42:11 INFO - PROCESS | 1653 | |6;imaging-darkroom;93;93;92;93;93;92;93;94;92;92 09:42:11 INFO - PROCESS | 1653 | |7;imaging-desaturate;81;80;78;81;82;81;83;85;81;86 09:42:11 INFO - PROCESS | 1653 | |8;json-parse-financial;55;55;53;54;57;58;59;59;60;57 09:42:11 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;66;74;78;74;73;70;68;70;70;71 09:42:11 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;84;84;88;94;102;83;86;90;103;88 09:42:11 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;146;158;159;146;147;146;149;152;231;152 09:42:11 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;171;162;167;166;168;163;166;167;165;165 09:42:11 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;66;66;70;79;90;89;71;67;66;65 09:42:11 INFO - PROCESS | 1653 | __end_tp_report 09:42:11 INFO - PROCESS | 1653 | __start_cc_report 09:42:11 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,2141 09:42:11 INFO - PROCESS | 1653 | __end_cc_report 09:42:11 INFO - PROCESS | 1653 | __startTimestamp1480354931459__endTimestamp 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | ------- Summary: start ------- 09:42:11 INFO - PROCESS | 1653 | Number of tests: 14 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:122.30 Median:113.50 stddev:19.30 (17.0%) stddev-sans-first:20.46 09:42:11 INFO - PROCESS | 1653 | Values: 124.0 170.0 142.0 113.0 112.0 112.0 114.0 112.0 112.0 112.0 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:116.00 stddev:16.61 (14.3%) stddev-sans-first:14.78 09:42:11 INFO - PROCESS | 1653 | Values: 149.0 117.0 112.0 148.0 115.0 115.0 107.0 113.0 144.0 113.0 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.65 (0.9%) stddev-sans-first:1.50 09:42:11 INFO - PROCESS | 1653 | Values: 186.0 182.0 186.0 184.0 182.0 183.0 182.0 182.0 185.0 184.0 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:65.10 Median:59.50 stddev:13.42 (22.6%) stddev-sans-first:13.11 09:42:11 INFO - PROCESS | 1653 | Values: 80.0 58.0 57.0 87.0 60.0 58.0 59.0 57.0 85.0 50.0 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:78.50 stddev:11.74 (15.0%) stddev-sans-first:11.95 09:42:11 INFO - PROCESS | 1653 | Values: 87.0 70.0 95.0 70.0 66.0 69.0 91.0 68.0 69.0 91.0 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.41 (1.6%) stddev-sans-first:1.45 09:42:11 INFO - PROCESS | 1653 | Values: 87.0 86.0 87.0 85.0 87.0 84.0 88.0 85.0 87.0 84.0 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:92.70 Median:93.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:42:11 INFO - PROCESS | 1653 | Values: 93.0 93.0 92.0 93.0 93.0 92.0 93.0 94.0 92.0 92.0 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:81.80 Median:81.50 stddev:2.35 (2.9%) stddev-sans-first:2.47 09:42:11 INFO - PROCESS | 1653 | Values: 81.0 80.0 78.0 81.0 82.0 81.0 83.0 85.0 81.0 86.0 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.50 stddev:2.36 (4.1%) stddev-sans-first:2.42 09:42:11 INFO - PROCESS | 1653 | Values: 55.0 55.0 53.0 54.0 57.0 58.0 59.0 59.0 60.0 57.0 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:72.00 stddev:3.44 (4.8%) stddev-sans-first:3.04 09:42:11 INFO - PROCESS | 1653 | Values: 66.0 74.0 78.0 74.0 73.0 70.0 68.0 70.0 70.0 71.0 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:87.00 stddev:7.25 (8.3%) stddev-sans-first:7.34 09:42:11 INFO - PROCESS | 1653 | Values: 84.0 84.0 88.0 94.0 102.0 83.0 86.0 90.0 103.0 88.0 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:158.60 Median:152.00 stddev:25.89 (17.0%) stddev-sans-first:27.06 09:42:11 INFO - PROCESS | 1653 | Values: 146.0 158.0 159.0 146.0 147.0 146.0 149.0 152.0 231.0 152.0 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.00 Median:166.50 stddev:2.54 (1.5%) stddev-sans-first:1.94 09:42:11 INFO - PROCESS | 1653 | Values: 171.0 162.0 167.0 166.0 168.0 163.0 166.0 167.0 165.0 165.0 09:42:11 INFO - PROCESS | 1653 | 09:42:11 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:70.50 stddev:9.67 (13.7%) stddev-sans-first:9.92 09:42:11 INFO - PROCESS | 1653 | Values: 66.0 66.0 70.0 79.0 90.0 89.0 71.0 67.0 66.0 65.0 09:42:11 INFO - PROCESS | 1653 | -------- Summary: end -------- 09:42:11 INFO - PROCESS | 1653 | 09:42:12 INFO - TEST-INFO | 1653: exit 0 09:42:12 INFO - TEST-OK | kraken | took 60374ms 09:42:12 INFO - SUITE-END | took 1179s 09:42:12 INFO - Completed test suite (00:19:39) 09:42:12 INFO - javascript benchmark 09:42:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3280.6883346082127, 3286.326303107863, 3289.2434740009803, 3301.9339613207735, 3346.4980252962055, 4083.9177617931273, 4308.827646894124, 4815.759212039393, 5240.082985477546, 5459.508644222019, 5406.8918621627645, 5451.073317536018, 5465.180222966557, 5503.3120859892515, 5516.172574113885, 5034.748262586874, 5038.4457709652, 5042.672226305279, 5070.923936140961, 5102.948970510303, 5041.193409054552, 5041.899162016757, 5057.671251368649, 5110.310108135392, 5174.353205849269, 5060.39275286966, 5083.389993200821, 5119.692818430887, 5125.256837758523, 5126.871828204287, 4070.6743460523235, 5126.102931986888, 5132.692038477666, 5136.614753893436, 5175.404828444739, 5092.159793634046, 5095.719735414551, 5119.6160287978555, 5125.897482050375, 5152.690838549662, 5396.0, 5398.811041613525, 5430.239766432703, 5431.972840135813, 5452.1276595744885, 5952.880942381172, 5955.43423374783, 5971.193888824996, 5990.221271234712, 6000.819975400745, 6064.848378790522, 6075.0, 6075.969620151871, 6083.087536869461, 6086.726097325587, 5449.264349312832, 5499.340079190522, 5510.421405752419, 5517.751701173497, 5571.749271282802, 6187.288461826857, 6222.564420490522, 6222.782202622887, 6226.470749986302, 6260.968695156495, 5145.536901678867, 5619.606627536115, 5829.5044921181325, 5831.358550559435, 5854.356020837705, 5798.884022319615, 5924.940750592439, 5933.6736479493175, 5988.041913293852, 6009.729562169713, 5837.854053648693, 5911.527077833763, 5925.111233314951, 5929.703514824241, 5929.822105336847, 5955.851103722355, 5956.940430595639, 5960.791372302036, 5970.910436343458, 5972.492338151219, 4638.327442520883, 4784.162771514971, 4787.042591481718, 4798.376211092605, 4821.421429428491, 4249.192653395845, 4258.5954334338185, 4306.633936115465, 4318.913621727611, 4338.674599405057, 5644.943550564524, 5660.235868157746, 5661.745221465044, 5662.0, 5662.8301150965535, 5949.10763385487, 5974.163617093611, 5975.52195824333, 5988.610740301867, 5989.101634754822, 4358.738475691405, 5522.447755224526, 5629.8592535186945, 5678.4605462480995, 5679.574031947621, 5179.119549676564, 5199.558037566773, 5204.947950520522, 5207.010667973073, 5208.453112423141, 6320.988641817287, 6335.0, 6340.4293613575, 6348.904766428507, 6353.714082866374, 5976.641401515749, 6028.547858910511, 6032.8793424132145, 6081.787137450345, 6091.725872335667, 4862.440819305683, 6322.936770632235, 6341.524385671185, 6344.492440604648, 6360.872782544231, 5769.365369809401, 5794.7102644866745, 5836.970815146067, 5844.707764611668, 5938.376470470538, 5934.762609495745, 5964.701764911824, 5976.9103463448955, 5989.131575921554, 6025.698715064317, 5553.0837411828215, 5743.62666426681, 5748.683822389641, 5751.309842818889, 5760.8271751847515, 5517.393086760533, 5583.357913839798, 5738.5696072795545, 5748.798792042258, 5761.625494342854, 4672.135654903854, 5380.704061276668, 5664.0, 5699.8860022800145, 5736.627119237236, 5819.127130930225, 5819.5344372450945, 5838.0, 5839.912401314069, 5843.065109582446, 5680.147977803197, 5683.318001839806, 5687.886242275049, 5699.51554117888, 5734.741936612779], "value": 5456.550645504665, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.36289932054973, 495.30162470916014, 511.69809812210787, 522.5062316111274, 529.3753371022194, 29995.850020749895, 30325.090247292588, 31580.52629210564, 31648.0, 31730.04809855708, 32087.839560802222, 32688.509672354874, 32715.182120447, 32772.508412373776, 40284.59715402845, 9136.497492637902, 9402.012788657195, 9423.623055077824, 9429.858552121723, 9433.669821556212, 9273.258139348836, 9358.017408172145, 9359.0, 9369.29730270229, 9392.577334019985, 9330.440173589606, 9386.859197112015, 9386.906130938705, 9414.340996130273, 9425.38734982224, 8187.0, 9210.723678289662, 9308.394954327947, 9358.766030849216, 9384.624615015426, 9226.53867306632, 9305.302102342352, 9321.673741419017, 9326.44041357514, 9329.766755831091, 38018.619813801786, 38234.235315293816, 38235.0, 38253.61746382556, 38342.80828595867, 3115.5482455044116, 3223.2425380035675, 3334.4498157803932, 3634.9636503635156, 3787.5644300905474, 30244.8487757562, 30246.69753302461, 30573.541396879064, 30673.69326306753, 30913.227169320722, 3120.4851199552045, 3173.1749745066218, 3195.888143914952, 3196.8721251149927, 3247.9675203247903, 498194.0, 498540.0, 499224.0, 500239.0, 500244.99999999633, 33003.83498082494, 57796.0, 58338.41661583415, 58377.8324433509, 58387.416125839045, 33731.662683373346, 33820.0, 34714.30571388609, 34821.30357392838, 34912.476312855324, 14434.278286085653, 14540.927295363666, 14578.489752858599, 14585.343659535347, 14589.854101459061, 12215.267083974812, 12311.322877241659, 12311.56909508163, 12322.137450378388, 12322.630321090382, 7648.470305938835, 7758.456908016497, 7773.86211346598, 7827.765167044998, 7828.530288182727, 2696.6763988321136, 3152.8896488622786, 3155.25851424915, 3187.15540381799, 3202.151429871086, 898.7079199260266, 991.548845275398, 1004.4776716107583, 1010.6664800615779, 1014.1987829614669, 28502.287442814097, 28747.425051498853, 29029.0, 29215.85392073026, 29293.267668308035, 841.8021764885247, 1031.504877658728, 1039.152721542967, 1041.6406339812752, 1154.4804837823012, 14709.485168019068, 14795.334209960578, 14827.481038163613, 14890.106593604418, 14893.859227985959, 586797.0, 704704.0, 705303.9469605341, 705640.0, 706596.0, 55492.0, 55740.0, 55891.0, 55903.440965590635, 55924.0, 1220.0605533738972, 1246.8815462531045, 1336.1782503759923, 1348.4336578636996, 1370.4792178972127, 24152.0, 28516.0, 29306.0, 30187.0, 31723.84138079295, 46179.0, 52337.21494177666, 53387.0, 53579.0, 56139.8772024549, 617386.0, 622679.8866005641, 622941.0, 623488.0, 625109.0, 23965.0, 24744.752552474965, 24791.132310369052, 25368.619470707585, 26454.206373808818, 44043.96346977476, 44278.0, 44575.33137003012, 44867.32699009452, 44876.0, 36762.080947976516, 36894.0, 37278.44082338821, 38427.61572384352, 38578.228435431694, 30735.0, 36962.07594810151, 37092.07269818276, 37209.62790372062, 37331.69339073119, 29385.559216611342, 35556.64443355533, 35767.82116089507, 36173.276534468634, 36708.0, 29036.983705570205, 31142.53286200754, 31272.06183814489, 32566.511502327958, 33110.86781548864, 20961.475963100434, 21111.366659000254, 24683.01267949262, 24873.253802385956, 25123.371915702253], "value": 17948.884626940257, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.6729136280993, 350.95262139611145, 371.85869369639533, 373.1064101476962, 398.98458423312667, 14989.291233719452, 15291.464798732046, 16487.999999999985, 16973.3210671573, 20563.897180514115, 18326.266949322013, 19218.135183916722, 19933.700994485058, 20860.687089693667, 20930.581388372222, 5775.104858746901, 6156.907646385308, 6159.291681456645, 6165.506759459233, 6174.5060395168275, 3967.9206415871663, 5991.940080599204, 6818.454523638122, 6902.965485172567, 6907.861842763142, 5766.365699773021, 6812.727490900358, 6834.453243740513, 6837.863242735142, 6841.726330946756, 6003.999999999978, 6139.938600614026, 6151.784687535959, 6152.753889844401, 6167.414095660905, 5811.099279611667, 5860.677662728548, 5906.143609176688, 5934.881302373971, 5997.7001149942325, 13125.146865453715, 13137.343132843414, 13147.079704420694, 13347.399367028507, 13374.33128343579, 2119.078200982562, 2123.097683484513, 2134.2636790307474, 2162.308061420346, 2276.226083131743, 9917.702468925943, 10343.275970682056, 10349.327293725884, 10513.579456821719, 14720.411183552646, 1816.3370369814922, 1896.7249748786348, 1910.388675623801, 1922.09661459114, 1925.7482636286386, 107639.0, 115423.0, 124009.75990240072, 128966.42067158605, 133026.0, 9287.071292870658, 10755.56977720898, 11002.779944401067, 11453.0, 11586.304821710724, 14275.64310892236, 14295.927805414636, 14309.78535321971, 14319.85680143206, 14330.85669143316, 8282.461639993382, 8554.272886804567, 8605.827883442296, 8689.739307820775, 8712.346574006975, 8521.0, 8536.359773016924, 8542.572871356408, 8714.607842647096, 8722.433041852259, 3704.2961837250837, 4386.956130438719, 4470.865874023784, 4483.417155769795, 4591.311303304531, 1590.6421055262474, 1838.3014454507202, 1892.5457890106275, 1902.73361729358, 1906.485248982767, 838.2455789789238, 890.643742502991, 996.6910257820099, 998.4408731110456, 1024.6618615856748, 20402.89798550998, 21235.893820531106, 21469.355919322446, 21486.247981320892, 21529.67705484419, 830.3564737328497, 910.1671970147241, 918.894327152386, 923.9769005774775, 973.4597298499259, 5753.309602847685, 5862.912056319158, 5904.261967254093, 5914.615549989237, 6047.667378294236, 529424.0, 601070.0, 625029.0, 646934.0, 656322.0, 114965.0, 123872.0, 124715.37642311912, 134464.32767836098, 136070.0, 428.5157771717939, 449.7526360501711, 450.86023332766945, 451.4199253958647, 452.6650278793735, 11140.442977850913, 11343.319400835977, 11470.885291146982, 11617.709557261136, 11687.94808467242, 48204.86668366545, 50043.99560044112, 50510.73723156803, 51284.48715512797, 53277.0, 60259.397406027136, 72450.63774681093, 72717.6364118176, 73878.63060684662, 75908.0, 7582.886256706043, 7693.422993275415, 7785.182555831556, 7972.561509117054, 8059.153788852086, 9924.0, 11361.602343917923, 12098.455569499218, 12141.332226727262, 12376.319302438453, 10041.799164016826, 10092.646757363456, 10130.898691012995, 10225.130863876653, 10481.685549433529, 9204.815903682023, 9552.713418597175, 9821.754456138653, 9837.967013463769, 10297.124744396511, 8411.663533458594, 10484.842727358942, 10529.526171322155, 10566.735831604272, 10623.628173013907, 7635.312821846061, 7714.729984450517, 8025.0, 8025.5987200638565, 8106.39202059836, 9138.451692898447, 9744.366616169926, 10085.991400859855, 10251.538680759533, 10365.0], "value": 8745.013246903649, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1289.1427200911394, 1473.6463248820282, 1485.7919891215229, 1648.67026594681, 1815.0561707911877, 6593.340665933404, 7853.371730261572, 7862.764117076483, 7862.76411707649, 7867.449278550504, 7977.20227977202, 8284.461510001845, 8329.125441828612, 8359.247667709906, 8364.079951205378, 2296.7243930728337, 2329.406001469623, 2330.755270696578, 2335.2760644200266, 2335.2994101769486, 1962.479942815155, 2301.309607117866, 2343.4727186383097, 2344.6248600223967, 2348.3189874936247, 2272.4432514034083, 2288.5079707862806, 2301.2060839010514, 2306.9538609227807, 2307.8730669813153, 2254.7407048189502, 2260.8756518391483, 2284.394635421598, 2288.290629904718, 2288.668143119247, 2291.633338665822, 2316.5830150572892, 2317.0, 2318.4783423729527, 2318.9652155217686, 8271.007479102545, 8316.126806685272, 8374.49753014821, 8393.874091888627, 8395.496270223744, 5040.6471546991725, 5050.090983622983, 5208.869778255536, 5213.426523082458, 5218.217267409919, 3247.6427592964756, 3327.73378129749, 3438.3123375325035, 3445.4487282035007, 3450.0339904826687, 1053.615430367918, 1064.446487826326, 1076.2842709598108, 1084.5607528950788, 1086.5219303506433, 10533.999270069407, 10685.626003089932, 10850.620228292051, 10859.239853210278, 10874.45627718611, 4546.318052292117, 4613.792379342937, 4659.697119687209, 4722.527747225318, 4727.338172655831, 9521.761955951157, 9530.046995300414, 9558.522073896278, 9560.665376711782, 9563.330566860253, 5243.030039442715, 5249.107651699221, 5250.947490525123, 5257.4742525747115, 5272.578193744491, 3959.9604003960167, 4205.474315710536, 4323.978380108079, 4326.766871441624, 4708.081924024851, 3379.8648054078053, 3830.482884810571, 3865.7100717446306, 3880.952142921452, 3939.980300098481, 1228.65597632663, 1237.1710953661068, 1246.6634008817568, 1264.051961029228, 1275.4260582737804, 776.5030380556449, 860.5095095795461, 902.4991129922956, 907.650554536495, 919.8896132464014, 5626.324841019104, 5753.913691294634, 5757.827265182052, 5854.4730974212525, 5971.701414929236, 905.9547022648841, 908.8000639859221, 918.995405022984, 922.718570835888, 985.6303886042734, 3736.6076561961145, 3766.0, 3873.6320049595242, 3879.4180872869297, 3974.1058261890844, 9775.90224097764, 10270.435126068138, 10273.845892311621, 10275.691729248134, 10289.588416463408, 10243.334183278212, 10258.794824103476, 10263.640772572924, 10278.280520363503, 10283.023112804272, 6447.774327898501, 7787.493812902257, 7802.570858602831, 7806.6096695165015, 7815.02312210985, 5553.389127196086, 5715.8571035724435, 5741.196232527533, 5743.052396354722, 5750.568707346882, 9127.178553930178, 9135.0, 9157.679481218125, 9194.218491428304, 9409.29430292717, 7472.775816725507, 9478.38390504615, 9487.0, 9606.231501480004, 9634.807303854024, 7035.296470352924, 7167.641617919187, 7182.0, 7196.244394338519, 7202.639868006683, 7503.287187717158, 7668.118166410711, 7759.92240077592, 7764.107127680163, 7768.145503994443, 7005.684744186422, 7008.123984501937, 7043.647817609201, 7187.281271872771, 7203.063601731951, 6887.311268873073, 6981.476389270927, 7041.647917604202, 7060.188078371053, 7076.398506126831, 6578.572392794453, 6582.210134783857, 6616.636085015371, 6635.369639884203, 6751.898721519279, 4758.095961767253, 4767.833125840579, 4830.500907765107, 4859.416869975626, 4947.208446648519, 4831.782569784298, 4868.9756551217015, 4943.406791185081, 4955.72743499111, 4984.277279794482], "value": 4398.8099931541965, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.187193027071, 301.80835169667256, 306.14585306993484, 310.5279974438853, 313.4326868368252, 5097.464766199551, 5103.311053007843, 5126.307948426966, 5184.974075129629, 5187.455317191688, 4030.4281330281215, 4039.5255731657953, 4412.955870441295, 4518.277075667894, 5190.455002224769, 3523.4010218262956, 3747.4566187902738, 3937.507811523545, 3969.384745364473, 4005.6194661507184, 3938.2911076006308, 3995.5804640512765, 4011.4183443400834, 4029.5970402959615, 4066.6543343815665, 3154.1326135312856, 3296.3901678189613, 3443.7933723976644, 3722.2133375979547, 3979.8408063677416, 3299.785513941586, 3486.3375958568045, 3579.910502237439, 3746.531683539571, 3754.342989976743, 3032.605761251023, 3366.6633336666137, 3416.5479671139665, 3439.7076248518906, 3471.94792078119, 3771.6039815819495, 4582.977085114587, 4864.854054378375, 4941.629377796679, 4981.352424184833, 3556.039869235318, 3685.981570092159, 4118.073433477473, 4272.4445822042935, 4416.558344165558, 4854.417469903634, 5139.820106296299, 5261.000409922102, 5333.599980001515, 5369.959415422154, 3174.5238214267815, 3623.6738693517445, 3681.4293784463453, 3709.3694072007825, 3753.680937120348, 4249.383839343278, 4977.0792403405485, 5375.43557926416, 5448.673079615196, 5546.168074788733, 3152.763542734304, 3164.9525257121163, 3733.815717805114, 3777.320082385197, 3795.7532760370486, 3451.0854623524788, 3818.816166988187, 3852.24881148179, 3855.4216867470104, 3857.28640201558, 4207.389928460418, 4370.192787933248, 4379.28484339705, 4392.626626736763, 4427.712198707074, 5242.9213561796605, 5304.522592966651, 5359.330083739533, 5411.998780225592, 5439.6672815159745, 1022.8721409823772, 1139.5384869128015, 1214.36245970866, 1214.975700486003, 1234.703671118925, 672.1430176524872, 689.4828878341244, 802.2739420824164, 836.8367968523756, 860.475110182788, 4357.694961352675, 4809.282755243346, 5009.169819052224, 5044.142495775727, 5174.404943431478, 5246.895062098737, 5499.450054994469, 5517.062099443105, 5518.475744804237, 5536.446355364512, 3989.1223930735773, 4583.495815460296, 4597.563231493003, 4778.737169455644, 5143.922841157386, 2321.129576408847, 2405.7233418157134, 2473.4646986838634, 2585.5216784894856, 2672.154155824041, 5147.5881929446305, 5480.355893232094, 5493.945060549423, 5519.240379810095, 5539.2522009529, 4438.622717069021, 4547.47704014036, 4553.724957012042, 4558.475775285885, 4570.611498022638, 5492.835214943558, 5499.50504454601, 5508.421615730371, 5520.779168833202, 5565.053940830068, 4729.618322899144, 4743.5019322971975, 5188.766505507186, 5207.71357575337, 5211.55701765339, 4700.905981880412, 4794.9280760787915, 4983.601311895112, 5100.413452452867, 5120.950205207998, 4766.5471780182215, 4917.606591472745, 4927.679700819435, 5046.268291097844, 5388.973055134699, 3512.227309991798, 3516.0, 3520.2783429397423, 3522.718182545339, 3551.911202219965, 4766.833160839354, 5128.923066153936, 5145.536901678867, 5179.48205179479, 5217.686938783685, 4412.955870441255, 4475.9328610071525, 5026.597872170145, 5040.647154699136, 5060.84817455477, 4463.821447142078, 5001.924971125509, 5025.798968041384, 5031.6226283029655, 5066.746662666926, 4294.957050429456, 4366.214081465367, 4711.175544279806, 4819.096531809246, 4961.801527938842, 4228.0, 4768.237082066853, 4949.480304567968, 5218.712970786643, 5362.490563396471, 4584.954150458586, 4644.907101858012, 5123.692578445305, 5257.0, 5260.342457192851], "value": 3768.748051033186, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.5435812363838, 722.9783106506807, 731.4660297982474, 746.4066067476356, 754.1252147508891, 14107.0, 14769.335379907916, 14790.186539740336, 14799.260036998134, 14808.037477563965, 12288.508459661603, 12291.385430728473, 12352.505899763999, 12388.0, 12388.752224955519, 648.0861984601712, 671.1576970901511, 674.4536925090667, 675.7263308360122, 814.336315902539, 667.2993356975181, 667.9231888332831, 802.0615879421091, 829.6059371798377, 837.4933165434916, 868.891388576428, 883.8585826267798, 887.6138879587368, 887.6626881784945, 889.6130183370223, 874.4141425245101, 875.5315905990265, 876.0713643537838, 876.1019954546578, 880.9779755506164, 502.8039064764745, 513.7662363624542, 519.1200914450009, 520.9583233341324, 646.2148489585177, 12034.157608967376, 13021.28382938938, 13500.797488037688, 13527.661808454768, 13856.307184640727, 13299.933500332532, 13735.725285494234, 13798.93100534501, 13800.861991380058, 13827.170369777845, 10938.179636527293, 11122.666320010412, 11136.33182009082, 11440.027597654209, 11501.367424791633, 3649.4708267301094, 3668.834902429397, 3709.51776269086, 3726.5900750917376, 3756.887293381203, 82294.58852705758, 87397.0, 87753.56123219407, 88372.0, 88453.0, 10237.539310731036, 10344.482775861328, 10477.37135771856, 10485.951404859605, 10516.68449946503, 19851.503712407306, 19931.60136797256, 20052.39842804718, 20063.197472100954, 20076.297329593395, 7425.740099096506, 7516.098068231847, 7526.5107767994905, 7556.962215188999, 7775.105862825847, 4184.225918205142, 4271.337942618899, 4287.7212981156745, 4323.286657701507, 4421.712588681726, 3385.0691059958217, 3483.2336885885065, 3487.8256087195537, 3488.66857648523, 3513.94729079064, 597.2385208858653, 644.8710257948429, 645.6126324205533, 649.9317571654908, 651.5439192565224, 520.7865672981943, 530.6444682063027, 608.6013661052018, 646.1793522226745, 657.263864471785, 8306.335493160532, 8629.870551941725, 8762.95618521916, 8799.91200087991, 8828.249598784047, 590.167863312727, 610.6397225636896, 632.4466092169351, 637.8405398650337, 660.5013215022627, 3420.7776494527775, 3642.863900493454, 4361.520232774392, 4398.582134697199, 4491.191585514639, 75587.86618200732, 98855.50572247093, 102282.0, 112050.31924521139, 121762.78237217515, 86530.56734716412, 87690.0, 91984.0, 95956.52021739987, 98187.52718709224, 78754.42491150145, 80015.59992200119, 80557.59721201591, 82017.0, 84808.0, 9238.584263708284, 9567.186789123003, 9679.032096790264, 9681.9031809681, 9686.0, 52613.0, 53819.73090134524, 54362.728186358814, 54814.0, 56995.0, 77527.83708244268, 77608.22391776236, 78945.0, 82739.69041238283, 84164.57917710372, 11395.202335836411, 11675.824862627238, 11806.940965295118, 12037.157398981988, 12113.75772484563, 13274.668133296746, 14098.718025639635, 14244.786328204878, 14933.477328293458, 14974.326155322819, 12161.695957600778, 12303.56937507183, 12463.688407789878, 12609.810852837032, 12757.48970041225, 11894.107941904218, 12023.398830058637, 12198.0, 12322.137450378388, 12456.75086498283, 12490.125691201893, 12665.683357916127, 12690.555830546256, 12715.237085774883, 12883.935580322039, 10551.670706027464, 10587.841182382117, 10662.360258384522, 10729.141668666643, 11290.0, 10964.342139471657, 11187.272827266203, 11191.832122518006, 11278.718032049264, 11310.321380717183], "value": 7113.255482633224, "unit": "score"}], "name": "dromaeo_css", "value": 6824.338766635248, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 170.0, 142.0, 113.0, 112.0, 112.0, 114.0, 112.0, 112.0, 112.0], "value": 122.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 117.0, 112.0, 148.0, 115.0, 115.0, 107.0, 113.0, 144.0, 113.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 186.0, 184.0, 182.0, 183.0, 182.0, 182.0, 185.0, 184.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 57.0, 87.0, 60.0, 58.0, 59.0, 57.0, 85.0, 50.0], "value": 65.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 95.0, 70.0, 66.0, 69.0, 91.0, 68.0, 69.0, 91.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 85.0, 87.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 92.0, 93.0, 93.0, 92.0, 93.0, 94.0, 92.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 78.0, 81.0, 82.0, 81.0, 83.0, 85.0, 81.0, 86.0], "value": 81.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 53.0, 54.0, 57.0, 58.0, 59.0, 59.0, 60.0, 57.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 78.0, 74.0, 73.0, 70.0, 68.0, 70.0, 70.0, 71.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 88.0, 94.0, 102.0, 83.0, 86.0, 90.0, 103.0, 88.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 158.0, 159.0, 146.0, 147.0, 146.0, 149.0, 152.0, 231.0, 152.0], "value": 158.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 162.0, 167.0, 166.0, 168.0, 163.0, 166.0, 167.0, 165.0, 165.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 70.0, 79.0, 90.0, 89.0, 71.0, 67.0, 66.0, 65.0], "value": 72.9, "unit": "score"}], "name": "kraken", "value": 1448.2, "alertThreshold": 2.0}]} 09:42:12 INFO - Return code: 0 09:42:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:42:12 INFO - # TBPL SUCCESS # 09:42:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:42:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:42:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:42:12 INFO - 2016-11-28 09:42:12.805 screenresolution[1657:10034] starting screenresolution argv=screenresolution get 09:42:12 INFO - 2016-11-28 09:42:12.808 screenresolution[1657:10034] Display 0: 1600x1200x32@60 09:42:12 INFO - 2016-11-28 09:42:12.814 screenresolution[1658:10037] starting screenresolution argv=screenresolution list 09:42:12 INFO - Available Modes on Display 0 09:42:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:42:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:42:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:42:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:42:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:42:13 INFO - Intel Iris: 09:42:13 INFO - Chipset Model: Intel Iris 09:42:13 INFO - Type: GPU 09:42:13 INFO - Bus: Built-In 09:42:13 INFO - VRAM (Dynamic, Max): 1536 MB 09:42:13 INFO - Vendor: Intel (0x8086) 09:42:13 INFO - Device ID: 0x0a2e 09:42:13 INFO - Revision ID: 0x0009 09:42:13 INFO - Displays: 09:42:13 INFO - Display: 09:42:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:42:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:42:13 INFO - Display Serial Number: 1600x1200 60 09:42:13 INFO - Main Display: Yes 09:42:13 INFO - Mirror: Off 09:42:13 INFO - Online: Yes 09:42:13 INFO - Rotation: Supported 09:42:13 INFO - Return code: 0 09:42:13 INFO - Running post-action listener: _resource_record_post_action 09:42:13 INFO - [mozharness: 2016-11-28 17:42:13.062736Z] Finished run-tests step (success) 09:42:13 INFO - Running post-run listener: _resource_record_post_run 09:42:13 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37291008; Write bytes: 656688128; Read time: 238; Write time: 1898 09:42:13 INFO - TinderboxPrint: CPU usage
53.5% 09:42:13 INFO - TinderboxPrint: I/O read bytes / time
37,291,008 / 238 09:42:13 INFO - TinderboxPrint: I/O write bytes / time
656,688,128 / 1,898 09:42:13 INFO - TinderboxPrint: CPU idle
2,254.2 (46.5%) 09:42:13 INFO - TinderboxPrint: CPU system
972.1 (20.1%) 09:42:13 INFO - TinderboxPrint: CPU user
1,619.3 (33.4%) 09:42:13 INFO - TinderboxPrint: Swap in / out
385,835,008 / 0 09:42:13 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345575936; Write bytes: 344200704; Read time: 26814; Write time: 735 09:42:13 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 35226624; Write bytes: 305303040; Read time: 219; Write time: 1135 09:42:13 INFO - Running post-run listener: _upload_blobber_files 09:42:13 INFO - Blob upload gear active. 09:42:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:42:13 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 09:42:13 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'] 09:42:13 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 09:42:13 INFO - (blobuploader) - INFO - Open directory for files ... 09:42:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:42:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:42:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:14 INFO - (blobuploader) - INFO - Done attempting. 09:42:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:42:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:42:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:15 INFO - (blobuploader) - INFO - Done attempting. 09:42:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:42:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:42:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:42:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:42:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:42:19 INFO - (blobuploader) - INFO - Done attempting. 09:42:19 INFO - (blobuploader) - INFO - Iteration through files over. 09:42:19 INFO - Return code: 0 09:42:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:42:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:42:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82261d253f2dcb9158b82ba92d116bfc7a52ee600c453e00a1f461e1eec43b4432ba3c5a2ffc612a4c824d40471f9f88c060c148ae4031a72b7691007ac5c104", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1326697bc5f6831b40fd1ea993627095564e63ac3d841cb827717d78f1fb350fad388f0d51961c4b30e5c0e1e46ae60e7ca2c5cb5341f5f35258135a3ce507ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c227213bf6c74033c4beb79951d4c5d4257b31f3246fb0e037aec6e5e841734238a3946a095c4023a339376e02952010649bab9cec863fd1d6ae83f506e18018"} 09:42:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:42:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:42:19 INFO - Contents: 09:42:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82261d253f2dcb9158b82ba92d116bfc7a52ee600c453e00a1f461e1eec43b4432ba3c5a2ffc612a4c824d40471f9f88c060c148ae4031a72b7691007ac5c104", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1326697bc5f6831b40fd1ea993627095564e63ac3d841cb827717d78f1fb350fad388f0d51961c4b30e5c0e1e46ae60e7ca2c5cb5341f5f35258135a3ce507ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c227213bf6c74033c4beb79951d4c5d4257b31f3246fb0e037aec6e5e841734238a3946a095c4023a339376e02952010649bab9cec863fd1d6ae83f506e18018"} 09:42:19 INFO - Running post-run listener: copy_logs_to_upload_dir 09:42:19 INFO - Copying logs to upload dir... 09:42:19 INFO - mkdir: /builds/slave/test/build/upload/logs 09:42:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.751131 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-28 09:42:19.349693) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-28 09:42:19.353327) ========= 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.fu7cYDdySq/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.LBGXazLp1d/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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/82261d253f2dcb9158b82ba92d116bfc7a52ee600c453e00a1f461e1eec43b4432ba3c5a2ffc612a4c824d40471f9f88c060c148ae4031a72b7691007ac5c104", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1326697bc5f6831b40fd1ea993627095564e63ac3d841cb827717d78f1fb350fad388f0d51961c4b30e5c0e1e46ae60e7ca2c5cb5341f5f35258135a3ce507ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c227213bf6c74033c4beb79951d4c5d4257b31f3246fb0e037aec6e5e841734238a3946a095c4023a339376e02952010649bab9cec863fd1d6ae83f506e18018"} build_url:https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011086 build_url: 'https://queue.taskcluster.net/v1/task/eapdDOK9QFyUKAKC6V3tGA/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/82261d253f2dcb9158b82ba92d116bfc7a52ee600c453e00a1f461e1eec43b4432ba3c5a2ffc612a4c824d40471f9f88c060c148ae4031a72b7691007ac5c104", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1326697bc5f6831b40fd1ea993627095564e63ac3d841cb827717d78f1fb350fad388f0d51961c4b30e5c0e1e46ae60e7ca2c5cb5341f5f35258135a3ce507ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c227213bf6c74033c4beb79951d4c5d4257b31f3246fb0e037aec6e5e841734238a3946a095c4023a339376e02952010649bab9cec863fd1d6ae83f506e18018"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-28 09:42:19.388120) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 09:42:19.388463) ========= 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.fu7cYDdySq/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.LBGXazLp1d/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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.005142 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-28 09:42:19.437869) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-28 09:42:19.438187) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-28 09:42:19.438540) ========= ========= Total master_lag: 0.18 =========