builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0320 starttime: 1478613996.09 results: success (0) buildid: 20161108030212 builduid: 5f4e42c5c6fb4ba2822ef60cdb11a2b3 revision: f13e90d496cf1bc6dfc4fd398da33e4afe785bde ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:36.086313) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:36.086752) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:36.087027) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:36.110915) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:36.111525) ========= 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.F6RDy8uBX2/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.fKivZUfXOX/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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-08 06:06:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.1M=0.001s 2016-11-08 06:06:36 (12.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115665 ========= master_lag: 0.44 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:36.668197) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:36.668523) ========= 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.F6RDy8uBX2/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.fKivZUfXOX/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.019967 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:36.708913) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-08 06:06:36.709263) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f13e90d496cf1bc6dfc4fd398da33e4afe785bde --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f13e90d496cf1bc6dfc4fd398da33e4afe785bde --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.F6RDy8uBX2/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.fKivZUfXOX/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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-08 06:06:36,795 truncating revision to first 12 chars 2016-11-08 06:06:36,795 Setting DEBUG logging. 2016-11-08 06:06:36,795 attempt 1/10 2016-11-08 06:06:36,796 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f13e90d496cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-08 06:06:37,905 unpacking tar archive at: mozilla-central-f13e90d496cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.382954 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-08 06:06:38.114321) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:38.117769) ========= script_repo_revision: f13e90d496cf1bc6dfc4fd398da33e4afe785bde ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:38.118179) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:38.118459) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-08 06:06:38.137032) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-08 06:06:38.137447) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.F6RDy8uBX2/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.fKivZUfXOX/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 06:06:38 INFO - MultiFileLogger online at 20161108 06:06:38 in /builds/slave/test 06:06:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:06:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:06:38 INFO - {'append_to_log': False, 06:06:38 INFO - 'base_work_dir': '/builds/slave/test', 06:06:38 INFO - 'blob_upload_branch': 'Firefox', 06:06:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:06:38 INFO - 'branch': 'Firefox', 06:06:38 INFO - 'buildbot_json_path': 'buildprops.json', 06:06:38 INFO - 'config_files': ('talos/mac_config.py',), 06:06:38 INFO - 'default_actions': ('clobber', 06:06:38 INFO - 'read-buildbot-config', 06:06:38 INFO - 'download-and-extract', 06:06:38 INFO - 'populate-webroot', 06:06:38 INFO - 'create-virtualenv', 06:06:38 INFO - 'install', 06:06:38 INFO - 'run-tests'), 06:06:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:06:38 INFO - 'download_minidump_stackwalk': True, 06:06:38 INFO - 'download_symbols': 'ondemand', 06:06:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:06:38 INFO - 'tooltool.py': '/tools/tooltool.py', 06:06:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:06:38 INFO - '/tools/misc-python/virtualenv.py')}, 06:06:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:06:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:06:38 INFO - 'installer_path': 'installer.exe', 06:06:38 INFO - 'log_level': 'info', 06:06:38 INFO - 'log_name': 'talos', 06:06:38 INFO - 'log_to_console': True, 06:06:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:06:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:06:38 INFO - 'opt_config_files': (), 06:06:38 INFO - 'pip_index': False, 06:06:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:06:38 INFO - 'cmd': ('bash', 06:06:38 INFO - '-c', 06:06:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:06:38 INFO - 'enabled': True, 06:06:38 INFO - 'halt_on_failure': False, 06:06:38 INFO - 'name': 'check_screen_resolution'},), 06:06:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:06:38 INFO - 'cmd': ('bash', 06:06:38 INFO - '-c', 06:06:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:06:38 INFO - 'enabled': True, 06:06:38 INFO - 'halt_on_failure': False, 06:06:38 INFO - 'name': 'check_screen_resolution'},), 06:06:38 INFO - 'run_cmd_checks_enabled': True, 06:06:38 INFO - 'sps_profile': False, 06:06:38 INFO - 'sps_profile_interval': 0, 06:06:38 INFO - 'suite': 'dromaeojs', 06:06:38 INFO - 'system_bits': '32', 06:06:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:06:38 INFO - 'title': 't-yosemite-r7-0320', 06:06:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:06:38 INFO - 'use_talos_json': True, 06:06:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:06:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:06:38 INFO - 'work_dir': 'build'} 06:06:38 INFO - [mozharness: 2016-11-08 14:06:38.338180Z] Running clobber step. 06:06:38 INFO - Running pre-action listener: _resource_record_pre_action 06:06:38 INFO - Running main action method: clobber 06:06:38 INFO - rmtree: /builds/slave/test/build 06:06:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:06:40 INFO - Running post-action listener: _resource_record_post_action 06:06:40 INFO - [mozharness: 2016-11-08 14:06:40.596132Z] Finished clobber step (success) 06:06:40 INFO - [mozharness: 2016-11-08 14:06:40.596295Z] Running read-buildbot-config step. 06:06:40 INFO - Running pre-action listener: _resource_record_pre_action 06:06:40 INFO - Running main action method: read_buildbot_config 06:06:40 INFO - Using buildbot properties: 06:06:40 INFO - { 06:06:40 INFO - "project": "", 06:06:40 INFO - "product": "firefox", 06:06:40 INFO - "script_repo_revision": "production", 06:06:40 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 06:06:40 INFO - "repository": "", 06:06:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 06:06:40 INFO - "buildid": "20161108030212", 06:06:40 INFO - "stage_platform": "macosx64", 06:06:40 INFO - "basedir": "/builds/slave/test", 06:06:40 INFO - "nightly_build": "True", 06:06:40 INFO - "buildnumber": 29, 06:06:40 INFO - "slavename": "t-yosemite-r7-0320", 06:06:40 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 06:06:40 INFO - "platform": "yosemite_r7", 06:06:40 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:06:40 INFO - "branch": "mozilla-central", 06:06:40 INFO - "repo_path": "mozilla-central", 06:06:40 INFO - "revision": "f13e90d496cf1bc6dfc4fd398da33e4afe785bde", 06:06:40 INFO - "pgo_build": "False", 06:06:40 INFO - "builduid": "5f4e42c5c6fb4ba2822ef60cdb11a2b3", 06:06:40 INFO - "slavebuilddir": "test" 06:06:40 INFO - } 06:06:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 06:06:40 INFO - Running post-action listener: _resource_record_post_action 06:06:40 INFO - [mozharness: 2016-11-08 14:06:40.599741Z] Finished read-buildbot-config step (success) 06:06:40 INFO - [mozharness: 2016-11-08 14:06:40.599844Z] Running download-and-extract step. 06:06:40 INFO - Running pre-action listener: _resource_record_pre_action 06:06:40 INFO - Running main action method: download_and_extract 06:06:40 INFO - mkdir: /builds/slave/test/build/tests 06:06:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:40 INFO - trying https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 06:06:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 06:06:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 06:06:42 INFO - Downloaded 1199 bytes. 06:06:42 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 06:06:42 INFO - Using the following test package requirements: 06:06:42 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:06:42 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:06:42 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 06:06:42 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:06:42 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 06:06:42 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:06:42 INFO - u'jsshell-mac.zip'], 06:06:42 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:06:42 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 06:06:42 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 06:06:42 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:06:42 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 06:06:42 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:06:42 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 06:06:42 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:06:42 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 06:06:42 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 06:06:42 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 06:06:42 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:06:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:06:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:06:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:06:42 INFO - Http code: 200 06:06:42 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 06:06:42 INFO - x-amz-cf-id: JKGAxUjK1eUZE2Q_75tgk9Cv7iOdD3eogQDQGB8fuOyz2GslTqYsnA== 06:06:42 INFO - x-amz-version-id: JvHEZkLDM1GkblODwVZ4tewZz5Ems8nR 06:06:43 INFO - Expected file size: 16310308 06:06:43 INFO - Obtained file size: 16310308 06:06:49 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:06:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 06:06:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:06:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 06:06:50 INFO - Http code: 200 06:06:50 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 06:06:50 INFO - x-amz-cf-id: ppMArpchmBONuihubtbYPVLOFM0oZOo2X4EZ61hoWYim8yW-uafpLQ== 06:06:50 INFO - x-amz-version-id: JvHEZkLDM1GkblODwVZ4tewZz5Ems8nR 06:06:50 INFO - Expected file size: 16310308 06:06:50 INFO - Obtained file size: 16310308 06:06:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 06:06:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:06:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 06:06:57 INFO - Http code: 200 06:06:57 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 06:06:57 INFO - x-amz-cf-id: nd_xVS6dETMFCp5agzshTHqD_3y7LlWcpj2s066Cxf3dysINX2WcAg== 06:06:57 INFO - x-amz-version-id: DBAksHU9dggF2xpUwS9AF5BUFF8fM2Bz 06:06:57 INFO - Expected file size: 14409038 06:06:57 INFO - Obtained file size: 14409038 06:06:58 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')]} 06:06:58 INFO - trying https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:06:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 06:06:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 06:07:00 INFO - Downloaded 111728953 bytes. 06:07:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:07:00 INFO - mkdir: /builds/slave/test/properties 06:07:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:07:00 INFO - Writing to file /builds/slave/test/properties/build_url 06:07:00 INFO - Contents: 06:07:00 INFO - build_url:https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 06:07:01 INFO - Running post-action listener: _resource_record_post_action 06:07:01 INFO - Running post-action listener: set_extra_try_arguments 06:07:01 INFO - [mozharness: 2016-11-08 14:07:01.637507Z] Finished download-and-extract step (success) 06:07:01 INFO - [mozharness: 2016-11-08 14:07:01.637628Z] Running populate-webroot step. 06:07:01 INFO - Running pre-action listener: _resource_record_pre_action 06:07:01 INFO - Running main action method: populate_webroot 06:07:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:07:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:07:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:07:01 INFO - '--tppagecycles', 06:07:01 INFO - '7'], 06:07:01 INFO - 'tests': ['tsvgm']}}, 06:07:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:07:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:07:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:07:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:07:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:07:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:07:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:01 INFO - 'talos_options': ['--disable-e10s'], 06:07:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:07:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:07:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:01 INFO - 'talos_options': ['--disable-e10s'], 06:07:01 INFO - 'tests': ['damp', 'tps']}, 06:07:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:01 INFO - 'tests': ['damp', 'tps']}, 06:07:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:07:01 INFO - 'tests': ['dromaeo_dom']}, 06:07:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:07:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:07:01 INFO - 'tests': ['basic_compositor_video']}, 06:07:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:07:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:07:01 INFO - 'tests': ['a11yr', 06:07:01 INFO - 'ts_paint', 06:07:01 INFO - 'tpaint', 06:07:01 INFO - 'sessionrestore', 06:07:01 INFO - 'sessionrestore_no_auto_restore', 06:07:01 INFO - 'tabpaint']}, 06:07:01 INFO - 'other-e10s': {'tests': ['a11yr', 06:07:01 INFO - 'ts_paint', 06:07:01 INFO - 'tpaint', 06:07:01 INFO - 'sessionrestore', 06:07:01 INFO - 'sessionrestore_no_auto_restore', 06:07:01 INFO - 'tabpaint']}, 06:07:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:07:01 INFO - 'ts_paint', 06:07:01 INFO - 'tpaint', 06:07:01 INFO - 'sessionrestore', 06:07:01 INFO - 'sessionrestore_no_auto_restore', 06:07:01 INFO - 'tabpaint']}, 06:07:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:07:01 INFO - 'ts_paint', 06:07:01 INFO - 'tpaint', 06:07:01 INFO - 'sessionrestore', 06:07:01 INFO - 'sessionrestore_no_auto_restore', 06:07:01 INFO - 'tabpaint']}, 06:07:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:07:01 INFO - 'tests': ['a11yr', 06:07:01 INFO - 'ts_paint', 06:07:01 INFO - 'tpaint', 06:07:01 INFO - 'sessionrestore', 06:07:01 INFO - 'sessionrestore_no_auto_restore', 06:07:01 INFO - 'tabpaint']}, 06:07:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:07:01 INFO - 'tests': ['a11yr', 06:07:01 INFO - 'ts_paint', 06:07:01 INFO - 'tpaint', 06:07:01 INFO - 'sessionrestore', 06:07:01 INFO - 'sessionrestore_no_auto_restore', 06:07:01 INFO - 'tabpaint']}, 06:07:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:07:01 INFO - 'tests': ['tsvgx', 06:07:01 INFO - 'tsvgr_opacity', 06:07:01 INFO - 'tart', 06:07:01 INFO - 'tscrollx', 06:07:01 INFO - 'cart']}, 06:07:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:07:01 INFO - 'tsvgr_opacity', 06:07:01 INFO - 'tart', 06:07:01 INFO - 'tscrollx', 06:07:01 INFO - 'cart']}, 06:07:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:01 INFO - 'talos_options': ['--disable-e10s'], 06:07:01 INFO - 'tests': ['tp5o']}, 06:07:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:01 INFO - 'tests': ['tp5o']}, 06:07:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:01 INFO - 'talos_options': ['--disable-e10s', 06:07:01 INFO - '--xperf_path', 06:07:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:07:01 INFO - 'tests': ['tp5n']}, 06:07:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:07:01 INFO - 'talos_options': ['--xperf_path', 06:07:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:07:01 INFO - 'tests': ['tp5n']}}, 06:07:01 INFO - 'talos.zip': {'path': '', 06:07:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:07:01 INFO - Running post-action listener: _resource_record_post_action 06:07:01 INFO - [mozharness: 2016-11-08 14:07:01.656490Z] Finished populate-webroot step (success) 06:07:01 INFO - [mozharness: 2016-11-08 14:07:01.656620Z] Running create-virtualenv step. 06:07:01 INFO - Running pre-action listener: _resource_record_pre_action 06:07:01 INFO - Running main action method: create_virtualenv 06:07:01 INFO - Creating virtualenv /builds/slave/test/build/venv 06:07:01 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 06:07:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:07:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:07:01 INFO - Using real prefix '/tools/python27' 06:07:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:07:02 INFO - Installing distribute.............................................................................................................................................................................................done. 06:07:05 INFO - Installing pip.................done. 06:07:05 INFO - Return code: 0 06:07:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:07:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:07:05 INFO - Reading from file tmpfile_stdout 06:07:05 INFO - Output received: 06:07:05 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) 06:07:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103bdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe10bd67270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f1d30>, '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 0x103817db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039e9b20>, '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.F6RDy8uBX2/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.fKivZUfXOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 06:07:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:07:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:07:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F6RDy8uBX2/Render', 06:07:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:05 INFO - 'HOME': '/Users/cltbld', 06:07:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:05 INFO - 'LOGNAME': 'cltbld', 06:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:05 INFO - 'MOZ_NO_REMOTE': '1', 06:07:05 INFO - 'NO_EM_RESTART': '1', 06:07:05 INFO - 'PAGER': '/bin/cat', 06:07:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:05 INFO - 'PWD': '/builds/slave/test', 06:07:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:05 INFO - 'SHELL': '/bin/bash', 06:07:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKivZUfXOX/Listeners', 06:07:05 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 06:07:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:05 INFO - 'USER': 'cltbld', 06:07:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:05 INFO - 'XPC_FLAGS': '0x0', 06:07:05 INFO - 'XPC_SERVICE_NAME': '0', 06:07:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:05 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 06:07:05 INFO - Cleaning up... 06:07:05 INFO - Return code: 0 06:07:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103bdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe10bd67270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f1d30>, '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 0x103817db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039e9b20>, '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.F6RDy8uBX2/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.fKivZUfXOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 06:07:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:07:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:07:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F6RDy8uBX2/Render', 06:07:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:05 INFO - 'HOME': '/Users/cltbld', 06:07:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:05 INFO - 'LOGNAME': 'cltbld', 06:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:05 INFO - 'MOZ_NO_REMOTE': '1', 06:07:05 INFO - 'NO_EM_RESTART': '1', 06:07:05 INFO - 'PAGER': '/bin/cat', 06:07:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:05 INFO - 'PWD': '/builds/slave/test', 06:07:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:05 INFO - 'SHELL': '/bin/bash', 06:07:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKivZUfXOX/Listeners', 06:07:05 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 06:07:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:05 INFO - 'USER': 'cltbld', 06:07:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:05 INFO - 'XPC_FLAGS': '0x0', 06:07:05 INFO - 'XPC_SERVICE_NAME': '0', 06:07:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:06 INFO - Downloading/unpacking psutil>=3.1.1 06:07:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:07:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:07:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:07:09 INFO - Installing collected packages: psutil 06:07:09 INFO - Running setup.py install for psutil 06:07:09 INFO - building 'psutil._psutil_osx' extension 06:07:09 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 06:07:10 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 06:07:10 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 06:07:10 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 06:07:10 INFO - building 'psutil._psutil_posix' extension 06:07:10 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 06:07:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:07:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:07:10 INFO - ^ 06:07:10 INFO - 1 warning generated. 06:07:10 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 06:07:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:07:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:07:10 INFO - Successfully installed psutil 06:07:10 INFO - Cleaning up... 06:07:10 INFO - Return code: 0 06:07:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:10 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 0x103bdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe10bd67270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f1d30>, '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 0x103817db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039e9b20>, '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.F6RDy8uBX2/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.fKivZUfXOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 06:07:10 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 06:07:10 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 06:07:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F6RDy8uBX2/Render', 06:07:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:10 INFO - 'HOME': '/Users/cltbld', 06:07:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:10 INFO - 'LOGNAME': 'cltbld', 06:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:10 INFO - 'MOZ_NO_REMOTE': '1', 06:07:10 INFO - 'NO_EM_RESTART': '1', 06:07:10 INFO - 'PAGER': '/bin/cat', 06:07:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:10 INFO - 'PWD': '/builds/slave/test', 06:07:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:10 INFO - 'SHELL': '/bin/bash', 06:07:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKivZUfXOX/Listeners', 06:07:10 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 06:07:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:10 INFO - 'USER': 'cltbld', 06:07:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:10 INFO - 'XPC_FLAGS': '0x0', 06:07:10 INFO - 'XPC_SERVICE_NAME': '0', 06:07:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:07:10 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 06:07:10 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 06:07:10 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 06:07:10 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 06:07:10 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 06:07:10 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 06:07:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:07:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:07:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:07:13 INFO - Installing collected packages: mozsystemmonitor 06:07:13 INFO - Running setup.py install for mozsystemmonitor 06:07:13 INFO - Successfully installed mozsystemmonitor 06:07:13 INFO - Cleaning up... 06:07:13 INFO - Return code: 0 06:07:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:13 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 0x103bdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe10bd67270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f1d30>, '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 0x103817db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039e9b20>, '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.F6RDy8uBX2/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.fKivZUfXOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 06:07:13 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 06:07:13 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 06:07:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F6RDy8uBX2/Render', 06:07:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:13 INFO - 'HOME': '/Users/cltbld', 06:07:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:13 INFO - 'LOGNAME': 'cltbld', 06:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:13 INFO - 'MOZ_NO_REMOTE': '1', 06:07:13 INFO - 'NO_EM_RESTART': '1', 06:07:13 INFO - 'PAGER': '/bin/cat', 06:07:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:13 INFO - 'PWD': '/builds/slave/test', 06:07:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:13 INFO - 'SHELL': '/bin/bash', 06:07:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKivZUfXOX/Listeners', 06:07:13 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 06:07:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:13 INFO - 'USER': 'cltbld', 06:07:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:13 INFO - 'XPC_FLAGS': '0x0', 06:07:13 INFO - 'XPC_SERVICE_NAME': '0', 06:07:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:13 INFO - Downloading/unpacking jsonschema==2.5.1 06:07:13 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 06:07:13 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 06:07:13 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 06:07:13 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 06:07:13 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 06:07:13 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 06:07:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:07:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:16 INFO - Downloading functools32-3.2.3-2.tar.gz 06:07:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:07:17 INFO - warning: no files found matching '*.txt' 06:07:17 INFO - no previously-included directories found matching 'build' 06:07:17 INFO - no previously-included directories found matching 'dist' 06:07:17 INFO - no previously-included directories found matching '.git*' 06:07:17 INFO - Installing collected packages: jsonschema, functools32 06:07:17 INFO - Running setup.py install for functools32 06:07:17 INFO - warning: no files found matching '*.txt' 06:07:17 INFO - no previously-included directories found matching 'build' 06:07:17 INFO - no previously-included directories found matching 'dist' 06:07:17 INFO - no previously-included directories found matching '.git*' 06:07:17 INFO - Successfully installed jsonschema functools32 06:07:17 INFO - Cleaning up... 06:07:17 INFO - Return code: 0 06:07:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:07:17 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')]} 06:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:17 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')]} 06:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:17 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 0x103bdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe10bd67270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f1d30>, '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 0x103817db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039e9b20>, '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.F6RDy8uBX2/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.fKivZUfXOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 06:07:17 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 06:07:17 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 06:07:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F6RDy8uBX2/Render', 06:07:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:17 INFO - 'HOME': '/Users/cltbld', 06:07:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:17 INFO - 'LOGNAME': 'cltbld', 06:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:17 INFO - 'MOZ_NO_REMOTE': '1', 06:07:17 INFO - 'NO_EM_RESTART': '1', 06:07:17 INFO - 'PAGER': '/bin/cat', 06:07:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:17 INFO - 'PWD': '/builds/slave/test', 06:07:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:17 INFO - 'SHELL': '/bin/bash', 06:07:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKivZUfXOX/Listeners', 06:07:17 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 06:07:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:17 INFO - 'USER': 'cltbld', 06:07:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:17 INFO - 'XPC_FLAGS': '0x0', 06:07:17 INFO - 'XPC_SERVICE_NAME': '0', 06:07:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:07:17 INFO - Cleaning up... 06:07:17 INFO - Return code: 0 06:07:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:07:17 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')]} 06:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:17 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')]} 06:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:17 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 0x103bdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe10bd67270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f1d30>, '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 0x103817db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039e9b20>, '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.F6RDy8uBX2/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.fKivZUfXOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 06:07:17 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 06:07:17 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 06:07:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F6RDy8uBX2/Render', 06:07:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:17 INFO - 'HOME': '/Users/cltbld', 06:07:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:17 INFO - 'LOGNAME': 'cltbld', 06:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:17 INFO - 'MOZ_NO_REMOTE': '1', 06:07:17 INFO - 'NO_EM_RESTART': '1', 06:07:17 INFO - 'PAGER': '/bin/cat', 06:07:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:17 INFO - 'PWD': '/builds/slave/test', 06:07:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:17 INFO - 'SHELL': '/bin/bash', 06:07:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKivZUfXOX/Listeners', 06:07:17 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 06:07:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:17 INFO - 'USER': 'cltbld', 06:07:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:17 INFO - 'XPC_FLAGS': '0x0', 06:07:17 INFO - 'XPC_SERVICE_NAME': '0', 06:07:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:17 INFO - Downloading/unpacking blobuploader==1.2.4 06:07:17 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 06:07:17 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 06:07:17 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 06:07:17 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 06:07:17 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 06:07:17 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 06:07:20 INFO - Downloading blobuploader-1.2.4.tar.gz 06:07:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:07:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:07:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:07:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:07:21 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 06:07:21 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 06:07:21 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 06:07:21 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 06:07:21 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 06:07:21 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 06:07:21 INFO - Downloading docopt-0.6.1.tar.gz 06:07:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:07:21 INFO - Installing collected packages: blobuploader, requests, docopt 06:07:21 INFO - Running setup.py install for blobuploader 06:07:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:07:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:07:21 INFO - Running setup.py install for requests 06:07:22 INFO - Running setup.py install for docopt 06:07:22 INFO - Successfully installed blobuploader requests docopt 06:07:22 INFO - Cleaning up... 06:07:22 INFO - Return code: 0 06:07:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:22 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 0x103bdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe10bd67270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f1d30>, '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 0x103817db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039e9b20>, '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.F6RDy8uBX2/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.fKivZUfXOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 06:07:22 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 06:07:22 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 06:07:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F6RDy8uBX2/Render', 06:07:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:22 INFO - 'HOME': '/Users/cltbld', 06:07:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:22 INFO - 'LOGNAME': 'cltbld', 06:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:22 INFO - 'MOZ_NO_REMOTE': '1', 06:07:22 INFO - 'NO_EM_RESTART': '1', 06:07:22 INFO - 'PAGER': '/bin/cat', 06:07:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:22 INFO - 'PWD': '/builds/slave/test', 06:07:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:22 INFO - 'SHELL': '/bin/bash', 06:07:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKivZUfXOX/Listeners', 06:07:22 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 06:07:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:22 INFO - 'USER': 'cltbld', 06:07:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:22 INFO - 'XPC_FLAGS': '0x0', 06:07:22 INFO - 'XPC_SERVICE_NAME': '0', 06:07:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:07:22 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-JYUPtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:07:23 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-L5AU6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:07:23 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-4mXXAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:07:23 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-77Ig_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:07:23 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Jw1ebY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:07:23 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-2ee4r4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:07:23 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-M_zAex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:07:23 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-CVBm5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:07:23 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-JRq1NO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:07:23 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-YayUVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:07:23 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-w6Zob4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:07:24 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-8zk86I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:07:24 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-P2MYpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:07:24 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-FExl59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:24 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-LvY1KR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:07:24 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-itfSYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:07:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:07:24 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-yPqqPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:07:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:07:24 INFO - Running setup.py install for manifestparser 06:07:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:07:24 INFO - Running setup.py install for mozcrash 06:07:24 INFO - Running setup.py install for mozdebug 06:07:25 INFO - Running setup.py install for mozdevice 06:07:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:07:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:07:25 INFO - Running setup.py install for mozfile 06:07:25 INFO - Running setup.py install for mozhttpd 06:07:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:07:25 INFO - Running setup.py install for mozinfo 06:07:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:07:25 INFO - Running setup.py install for mozInstall 06:07:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:07:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:07:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:07:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:07:25 INFO - Running setup.py install for mozleak 06:07:26 INFO - Running setup.py install for mozlog 06:07:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:07:26 INFO - Running setup.py install for moznetwork 06:07:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:07:26 INFO - Running setup.py install for mozprocess 06:07:26 INFO - Running setup.py install for mozprofile 06:07:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:07:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:07:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:07:26 INFO - Running setup.py install for mozrunner 06:07:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:07:26 INFO - Running setup.py install for mozscreenshot 06:07:26 INFO - Running setup.py install for moztest 06:07:27 INFO - Running setup.py install for mozversion 06:07:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:07:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:07:27 INFO - Cleaning up... 06:07:27 INFO - Return code: 0 06:07:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:27 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 0x103bdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe10bd67270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f1d30>, '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 0x103817db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039e9b20>, '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.F6RDy8uBX2/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.fKivZUfXOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 06:07:27 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 06:07:27 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 06:07:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F6RDy8uBX2/Render', 06:07:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:27 INFO - 'HOME': '/Users/cltbld', 06:07:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:27 INFO - 'LOGNAME': 'cltbld', 06:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:27 INFO - 'MOZ_NO_REMOTE': '1', 06:07:27 INFO - 'NO_EM_RESTART': '1', 06:07:27 INFO - 'PAGER': '/bin/cat', 06:07:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:27 INFO - 'PWD': '/builds/slave/test', 06:07:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:27 INFO - 'SHELL': '/bin/bash', 06:07:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKivZUfXOX/Listeners', 06:07:27 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 06:07:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:27 INFO - 'USER': 'cltbld', 06:07:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:27 INFO - 'XPC_FLAGS': '0x0', 06:07:27 INFO - 'XPC_SERVICE_NAME': '0', 06:07:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:07:27 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-eUI_xQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:07:27 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)) 06:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:07:27 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-12K3kx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:07:27 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-K5UL8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:07:27 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)) 06:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:07:27 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Cr6R7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:07:27 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)) 06:07:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:07:27 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Xvp0ev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:07:28 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)) 06:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:07:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-VWuBxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:07:28 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)) 06:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:07:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-LrZWtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:07:28 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)) 06:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:07:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-E1WumN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:07:28 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)) 06:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:07:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-fmKtjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:07:28 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)) 06:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:07:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-_V_Q5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:07:28 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)) 06:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:07:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-HAZIak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:07:28 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)) 06:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:07:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-wiUABf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:07:28 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)) 06:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:07:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-2ceAij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:07:28 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)) 06:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:07:28 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-5NmGnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:07:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 06:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:29 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-1v19Ok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:07:29 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)) 06:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:07:29 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-90f1gC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:07:29 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)) 06:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:07:29 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-FXjmok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:07:29 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)) 06:07:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:07:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:07:29 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)) 06:07:29 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)) 06:07:29 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)) 06:07:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:07:29 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 06:07:29 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 06:07:29 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 06:07:29 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 06:07:29 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 06:07:29 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 06:07:32 INFO - Downloading blessings-1.6.tar.gz 06:07:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:07:32 INFO - Installing collected packages: blessings 06:07:32 INFO - Running setup.py install for blessings 06:07:32 INFO - Successfully installed blessings 06:07:32 INFO - Cleaning up... 06:07:32 INFO - Return code: 0 06:07:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:07:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:07:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:07:32 INFO - Reading from file tmpfile_stdout 06:07:32 INFO - Current package versions: 06:07:32 INFO - blessings == 1.6 06:07:32 INFO - blobuploader == 1.2.4 06:07:32 INFO - docopt == 0.6.1 06:07:32 INFO - functools32 == 3.2.3-2 06:07:32 INFO - jsonschema == 2.5.1 06:07:32 INFO - manifestparser == 1.1 06:07:32 INFO - mozInstall == 1.12 06:07:32 INFO - mozcrash == 0.17 06:07:32 INFO - mozdebug == 0.1 06:07:32 INFO - mozdevice == 0.48 06:07:32 INFO - mozfile == 1.2 06:07:32 INFO - mozhttpd == 0.7 06:07:32 INFO - mozinfo == 0.9 06:07:32 INFO - mozleak == 0.1 06:07:32 INFO - mozlog == 3.4 06:07:32 INFO - moznetwork == 0.27 06:07:32 INFO - mozprocess == 0.23 06:07:32 INFO - mozprofile == 0.28 06:07:32 INFO - mozrunner == 6.12 06:07:32 INFO - mozscreenshot == 0.1 06:07:32 INFO - mozsystemmonitor == 0.3 06:07:32 INFO - moztest == 0.7 06:07:32 INFO - mozversion == 1.4 06:07:32 INFO - psutil == 3.1.1 06:07:32 INFO - requests == 1.2.3 06:07:32 INFO - wsgiref == 0.1.2 06:07:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:07: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')]} 06:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07: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')]} 06:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07:33 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 0x103bdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe10bd67270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f1d30>, '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 0x103817db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039e9b20>, '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.F6RDy8uBX2/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.fKivZUfXOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 06:07:33 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 06:07:33 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 06:07:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F6RDy8uBX2/Render', 06:07:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:33 INFO - 'HOME': '/Users/cltbld', 06:07:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:33 INFO - 'LOGNAME': 'cltbld', 06:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:33 INFO - 'MOZ_NO_REMOTE': '1', 06:07:33 INFO - 'NO_EM_RESTART': '1', 06:07:33 INFO - 'PAGER': '/bin/cat', 06:07:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:33 INFO - 'PWD': '/builds/slave/test', 06:07:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:33 INFO - 'SHELL': '/bin/bash', 06:07:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKivZUfXOX/Listeners', 06:07:33 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 06:07:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:33 INFO - 'USER': 'cltbld', 06:07:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:33 INFO - 'XPC_FLAGS': '0x0', 06:07:33 INFO - 'XPC_SERVICE_NAME': '0', 06:07:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:33 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)) 06:07:33 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)) 06:07:33 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)) 06:07:33 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)) 06:07:33 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)) 06:07:33 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)) 06:07:33 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)) 06:07:33 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)) 06:07:33 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)) 06:07:33 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)) 06:07:33 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)) 06:07:33 INFO - Cleaning up... 06:07:33 INFO - Return code: 0 06:07:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:07: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')]} 06:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:07:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07: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')]} 06:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:07:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:07: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103bdd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b53570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe10bd67270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039f1d30>, '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 0x103817db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039e9b20>, '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.F6RDy8uBX2/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.fKivZUfXOX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 06:07: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', 'jsonschema'] in /builds/slave/test/build 06:07: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 jsonschema 06:07:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F6RDy8uBX2/Render', 06:07:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:33 INFO - 'HOME': '/Users/cltbld', 06:07:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:33 INFO - 'LOGNAME': 'cltbld', 06:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:33 INFO - 'MOZ_NO_REMOTE': '1', 06:07:33 INFO - 'NO_EM_RESTART': '1', 06:07:33 INFO - 'PAGER': '/bin/cat', 06:07:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:33 INFO - 'PWD': '/builds/slave/test', 06:07:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:33 INFO - 'SHELL': '/bin/bash', 06:07:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKivZUfXOX/Listeners', 06:07:33 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 06:07:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:33 INFO - 'USER': 'cltbld', 06:07:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:33 INFO - 'XPC_FLAGS': '0x0', 06:07:33 INFO - 'XPC_SERVICE_NAME': '0', 06:07:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:07:33 INFO - Cleaning up... 06:07:33 INFO - Return code: 0 06:07:33 INFO - Running post-action listener: _resource_record_post_action 06:07:33 INFO - Running post-action listener: _start_resource_monitoring 06:07:33 INFO - Starting resource monitoring. 06:07:33 INFO - [mozharness: 2016-11-08 14:07:33.927670Z] Finished create-virtualenv step (success) 06:07:33 INFO - [mozharness: 2016-11-08 14:07:33.928755Z] Running install step. 06:07:33 INFO - Running pre-action listener: _resource_record_pre_action 06:07:33 INFO - Running main action method: install 06:07:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:07:34 INFO - Reading from file tmpfile_stdout 06:07:34 INFO - Detecting whether we're running mozinstall >=1.0... 06:07:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:07:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:07:34 INFO - Reading from file tmpfile_stdout 06:07:34 INFO - Output received: 06:07:34 INFO - Usage: mozinstall [options] installer 06:07:34 INFO - Options: 06:07:34 INFO - -h, --help show this help message and exit 06:07:34 INFO - -d DEST, --destination=DEST 06:07:34 INFO - Directory to install application into. [default: 06:07:34 INFO - "/builds/slave/test"] 06:07:34 INFO - --app=APP Application being installed. [default: firefox] 06:07:34 INFO - mkdir: /builds/slave/test/build/application 06:07:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:07:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:08:08 INFO - Reading from file tmpfile_stdout 06:08:08 INFO - Output received: 06:08:08 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 06:08:08 INFO - Running post-action listener: _resource_record_post_action 06:08:08 INFO - [mozharness: 2016-11-08 14:08:08.742672Z] Finished install step (success) 06:08:08 INFO - [mozharness: 2016-11-08 14:08:08.742824Z] Running run-tests step. 06:08:08 INFO - Running pre-action listener: _resource_record_pre_action 06:08:08 INFO - Running main action method: run_tests 06:08:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:08:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:08:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:08:08 INFO - 2016-11-08 06:08:08.757 screenresolution[2336:14016] starting screenresolution argv=screenresolution get 06:08:08 INFO - 2016-11-08 06:08:08.760 screenresolution[2336:14016] Display 0: 1600x1200x32@60 06:08:08 INFO - 2016-11-08 06:08:08.766 screenresolution[2337:14018] starting screenresolution argv=screenresolution list 06:08:08 INFO - Available Modes on Display 0 06:08:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:08:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:08:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:08:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:08:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:08:08 INFO - Intel Iris: 06:08:08 INFO - Chipset Model: Intel Iris 06:08:08 INFO - Type: GPU 06:08:08 INFO - Bus: Built-In 06:08:08 INFO - VRAM (Dynamic, Max): 1536 MB 06:08:08 INFO - Vendor: Intel (0x8086) 06:08:08 INFO - Device ID: 0x0a2e 06:08:08 INFO - Revision ID: 0x0009 06:08:08 INFO - Displays: 06:08:08 INFO - Display: 06:08:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:08:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:08:08 INFO - Display Serial Number: 1600x1200 60 06:08:08 INFO - Main Display: Yes 06:08:08 INFO - Mirror: Off 06:08:08 INFO - Online: Yes 06:08:08 INFO - Rotation: Supported 06:08:08 INFO - Return code: 0 06:08:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:08:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:08:09 INFO - Python 2.7.3 06:08:09 INFO - Return code: 0 06:08:09 INFO - grabbing minidump binary from tooltool 06:08: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')]} 06:08:09 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 0x1039f1d30>, '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 0x103817db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039e9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:08:09 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 06:08:09 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 06:08:09 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 06:08:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:08:09 INFO - Return code: 0 06:08:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:08:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:08:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:08:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:08:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:08:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--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 06:08:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0320 --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 06:08:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F6RDy8uBX2/Render', 06:08:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:09 INFO - 'HOME': '/Users/cltbld', 06:08:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:09 INFO - 'LOGNAME': 'cltbld', 06:08:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:08:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:09 INFO - 'MOZ_NO_REMOTE': '1', 06:08:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:08:09 INFO - 'NO_EM_RESTART': '1', 06:08:09 INFO - 'PAGER': '/bin/cat', 06:08:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:09 INFO - 'PWD': '/builds/slave/test', 06:08:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:08:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:09 INFO - 'SHELL': '/bin/bash', 06:08:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fKivZUfXOX/Listeners', 06:08:09 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 06:08:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:09 INFO - 'USER': 'cltbld', 06:08:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:09 INFO - 'XPC_FLAGS': '0x0', 06:08:09 INFO - 'XPC_SERVICE_NAME': '0', 06:08:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--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 06:08:09 INFO - mozversion application_buildid: 20161108030212 06:08:09 INFO - mozversion application_changeset: f13e90d496cf1bc6dfc4fd398da33e4afe785bde 06:08:09 INFO - mozversion application_display_name: Nightly 06:08:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:08:09 INFO - mozversion application_name: Firefox 06:08:09 INFO - mozversion application_remotingname: firefox 06:08:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:08:09 INFO - mozversion application_vendor: Mozilla 06:08:09 INFO - mozversion application_version: 52.0a1 06:08:09 INFO - mozversion platform_buildid: 20161108030212 06:08:09 INFO - mozversion platform_changeset: f13e90d496cf1bc6dfc4fd398da33e4afe785bde 06:08:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:08:09 INFO - mozversion platform_version: 52.0a1 06:08:09 INFO - using testdate: 1478614089 06:08:09 INFO - actual date: 1478614089 06:08:09 INFO - starting webserver on 'localhost:49536' 06:08:09 INFO - SUITE-START | Running 2 tests 06:08:09 INFO - TEST-START | dromaeo_css 06:08:09 INFO - operating with platform_type : mac_ 06:08:09 INFO - Initialising browser for dromaeo_css test... 06:08:09 INFO - TEST-INFO | started process 2347 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpeF6_2c/profile http://localhost:49536/getInfo.html) 06:08:11 INFO - PROCESS | 2347 | __metrics Screen width/height:1600/1200 06:08:11 INFO - PROCESS | 2347 | colorDepth:24 06:08:11 INFO - PROCESS | 2347 | Browser inner width/height: 1024/689 06:08:11 INFO - PROCESS | 2347 | __metrics 06:08:11 INFO - PROCESS | 2347 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:08:11 INFO - PROCESS | 2347 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:08:12 INFO - TEST-INFO | 2347: exit 0 06:08:12 INFO - Browser initialized. 06:08:12 INFO - Running cycle 1/1 for dromaeo_css test... 06:08:12 INFO - TEST-INFO | started process 2350 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpeF6_2c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:08:24 INFO - PROCESS | 2350 | RSS: Main: 238735360 06:08:24 INFO - PROCESS | 2350 | 06:09:16 INFO - PROCESS | 2350 | 1478614156084 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] 06:11:29 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:49536/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49536/tests/dromaeo/cssquery-ext.html) 06:11:30 INFO - PROCESS | 2350 | RSS: Main: 370790400 06:11:30 INFO - PROCESS | 2350 | 06:14:33 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:49536/tests/dromaeo/cssquery-ext.html (next: http://localhost:49536/tests/dromaeo/cssquery-jquery.html) 06:14:34 INFO - PROCESS | 2350 | RSS: Main: 367886336 06:14:34 INFO - PROCESS | 2350 | 06:17:36 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:49536/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49536/tests/dromaeo/cssquery-mootools.html) 06:17:37 INFO - PROCESS | 2350 | RSS: Main: 357724160 06:17:37 INFO - PROCESS | 2350 | 06:20:39 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:49536/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49536/tests/dromaeo/cssquery-prototype.html) 06:20:40 INFO - PROCESS | 2350 | RSS: Main: 349810688 06:20:40 INFO - PROCESS | 2350 | 06:23:43 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:49536/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49536/tests/dromaeo/cssquery-yui.html) 06:23:44 INFO - PROCESS | 2350 | RSS: Main: 329900032 06:23:44 INFO - PROCESS | 2350 | 06:26:47 INFO - PROCESS | 2350 | Cycle 1(1): loaded http://localhost:49536/tests/dromaeo/cssquery-yui.html (next: http://localhost:49536/tests/dromaeo/cssquery-dojo.html) 06:26:47 INFO - PROCESS | 2350 | RSS: Main: 429441024 06:26:47 INFO - PROCESS | 2350 | 06:26:47 INFO - PROCESS | 2350 | __start_tp_report 06:26:47 INFO - PROCESS | 2350 | _x_x_mozilla_page_load 06:26:47 INFO - PROCESS | 2350 | _x_x_mozilla_page_load_details 06:26:47 INFO - PROCESS | 2350 | |i|pagename|runs| 06:26:47 INFO - PROCESS | 2350 | |0;dojo.html;3276.3119744853566;3283.802971821691;3286.4248756467614;3295.505674148879;3356.865725370984;5549.889002219953;5598.300212473441;5679.062954612489;5687.260656114704;5730.455606717361;5607.411221821701;5681;5712.457316554931;5754.367019627837;5807.82576522705;5084.542391184793;5132.589392848563;5139.794408223685;5159.535641792238;5200.297959775438;5195.376554813428;5197.194434862584;5202.245674377213;5236.659617124875;5245.527902488775;5123.692578445268;5166.044281807877;5213.296205012312;5231.163013917774;5280.630355875091;5183.041137389595;5214.270002199695;5214.478552144793;5215.713135777531;5226.189940559219;5111.539961403491;5167.3282473278605;5181.145111056671;5188.533032027136;5196.116660167742;5670.631408958405;5688.83378907323;5689.004424225744;5700.486956173922;5715.742791574389;6110.749250451246;6113.050656958994;6120.20437343147;6128.601640893327;6143.341297849555;6190.907136392958;6197.5351848611535;6208.844778880519;6272.153259310026;6298.87402251957;5732.168835518826;5760.3951585083805;5769.9711501442225;5771.68255745938;5772.105323674838;6212.875742485125;6227.626342419469;6296.748130074746;6329.2721337045905;6339.302676705559;6033.487153591994;6049.062395328731;6064.090386442069;6077.17958075663;6079.787207447718;6186.540497288958;6188.412100850412;6219.968900155471;6234.781782637586;6239.500839932794;5993.4905533029305;6066.241719785027;6073.058675905153;6168.19813424252;6173.969130154321;6112.442689633878;6173.938260617337;6189.752409903554;6215.160953271251;6236.563440559208;4899;4903.41159060915;4903.583195428358;4904.681195722266;4930.802767889316;4389.473263208435;4449.021215332622;4458.9331160032625;4464.263396539534;4471.776411179428;5597.608167428241;5664.291963504562;5667.291588551431;5745.023346031101;5798.014337562625;6260.780872669435;6305.716242769086;6323.52573556985;6326.49388048955;6330.746770129143;5240.0567897778;5341.465853414627;5782.421757824184;5785.4214578541805;5797.752808988764;5227.555657769056;5326;5333.493318134849;5366.409694933554;5379.354477462652;6239.656818875005;6255.093011513214;6263.248410190807;6297.590656607397;6310.652914089769;6254.343293954069;6258.967270400515;6265.592736472115;6267;6277.1525844010475;6249.031400132987;6285.748570057147;6294.0558916162945;6314.431701146918;6329.208848893888;6026.939730602638;6061.3029501606115;6065.181200537871;6089.908651370144;6111.633302001894;5100.846974590768;6209;6212.502999760099;6214.782482613087;6214.937850621617;4687.648426368104;5942.227510423617;5968.940310596839;5980.5514586405325;6008.459238668541;5900.232969713909;5944.613600115978;5959.135925290895;5984.910226346521;6022.578419510767;5867.765289388377;5923.763049477972;5937.287525496968;5982.58121931478;5986.371430999682;6091.756329746938;6092.268927728701;6105.603135796159;6129.111278864629;6130.141780150693;4868.318435418973;5751.33859606134;5798.59409841307;5882.470577648032;5920.2895652521975 06:26:47 INFO - PROCESS | 2350 | |1;ext.html;477.60836114386217;497.3608912547376;502.11878153840007;508.6744483530541;515.1937218253433;30900.69099309012;31039.534406983887;31418.842905785466;31633;39977;40510.7974460128;40844.38733418994;40943;40976;41096;9013.954930225356;9225.169734723873;9237.445753254826;9242.353035287533;9253.768655783591;9212.263018958467;9270.953645231764;9319.953400233024;9323.440593564374;9332.973372928973;9284.16442520173;9345.158935695787;9352.25181985443;9356.111169438926;9388.1081297277;9269.629214831399;9333.346665733401;9352.158305752448;9354.672586459475;9356.438613683133;9048.954755226248;9114.361994660376;9119.40723852954;9144.725658230263;9254.676086337013;38705.45178192869;38922.22155556873;39028.609713902784;39048.80475597604;39060.82817515479;3284.917877053069;3510.33303672304;3771.509703738524;3797;3825.8469661213517;28779.424411511653;28901.277468063257;29186.85406572975;29354.706452935625;29617.407651846843;3133.7336326412283;3169.8415079245947;3175.42842288388;3221.951670724941;3266.70599646033;367761;369778;370012;370084;370548.14725926466;56598.71700641471;56890.43109568934;56911.71544142336;56963.715181423824;56969.72727045488;32878.83560582182;33419.83290083534;33622.83188584042;34328.82835585806;59022;15053.774193387108;15154.621134471727;15157.469488567847;15171.544853654188;15221.391144354102;11489;11560.190786645022;11565.595204167981;11581.07351411885;11587.942060289814;17990;17994;18088.366907158186;18195.90824550531;18205.362812301504;6286.245650521875;7288.635568221568;7467.141278753013;7539.132999705104;7544.132424771222;810.3557671651051;921.5991043895792;985.5367977050774;1041.994790026045;1044.2585764107569;28428.431431371257;29089.854550727534;29118;29446.411071778446;29451.852740736158;1003.2325271167563;1043.4052590023766;1060.8355704865758;1074.0441007503327;1112.8386383974278;14498.565043048726;14708.632284192981;15054;15282.770758438632;15352.309146088453;637570;642597;643925;644040.779796098;645748;54539.18191227135;55102.21426750134;55166.448335516936;55213;55516.61208469821;1085.630885498934;1193.4927655746274;1236.5486597392066;1331.074902942465;1340.1958824705098;28684.13947581576;29653.703462965095;29785;30357.69642303637;30472.542911855897;38675.41986870256;39996.60011899686;47275.76362118167;47850.28224576704;47957.76021119872;430238;431236;432161;432726;432958;22972.081116755144;23393.649095263245;24971.500569988137;25539.978400863758;25574.744252557237;42900.78549607357;42948.1410371797;43953.901152471444;44065.339019914085;44398.556014440735;36401.35364128561;36511.81744091262;37563.436548452344;37564;40238;38442.42336365013;39844.60155398409;40838.59161408348;40959.59040409677;41143.588564113976;38165.618343816204;38757.6124238754;39048.80475597604;39194.80402597969;39576.40635390529;31876;38706.4194037084;38986;40024.599754003255;40238;24566.631500527863;25687.74312256928;25876;26070.86964565241;26230.737692623592 06:26:47 INFO - PROCESS | 2350 | |2;jquery.html;308.4032397311203;344.2151893682402;364.5552426040232;365.5576752129922;395.48191868652066;14915.701685966274;15371.385144594216;16717.49847504575;19661;19715.408537743853;19277.036148192605;19617.921014344207;20041.699374509393;20069;20098.59802803943;5467.371252305996;5737;5738.713064346787;5742.425757424266;5748.223989761371;4053.9594604054023;4215.409842622035;4323.978380108095;5473.808416705416;6345.3971872672255;6091.512678985692;6239.532035097386;6353.237611486628;6356.555041147121;6357.173567436227;6219.720112594921;6307.747690092391;6311.621302721828;6313.40022697843;6316.62100273985;5254.448282930313;5437.13005919055;5469.206964990054;5474.178873169017;5507.091329930557;24978.750212497824;25016.624750628755;25031;25032.4993500129;25335.493290134094;2170.2078741259484;2185.9890700546553;2199.1533259694975;2218.5673793610254;2412.203972689026;13967.441302347894;15064.698706025927;15102.39590416382;15251.932364734474;15783.763243551355;1736.9565760855953;1766.1787268919886;1791.5162906015303;1860.283790740561;1900.0214889332005;84133.57933210356;102480.46279305816;107115;109857;111232.88767112387;8363.369143017175;10086.899131008742;10462.738431539121;10581.470926453647;10674.573017079385;13175.275359855108;13190.406431710597;13202.801957970638;13205.273709945854;13216.207027578375;8031.839363212703;8174.544873044205;8187.5087494750505;8313.043999939964;8361.498310101413;8080.232377924088;8092.433529652985;8205.548694821722;8237.711680091168;8364.958175209085;5359.249705041298;6329.968350158312;6337.524685648595;6426.3573642635365;6542.640154791532;1742.4337090445656;1778.2264714849082;1782.3138091834737;1783.616522447654;1787.204693911197;878.9935523825185;882.5102068351998;889.7686601483531;930.06993006993;1009.1271050541229;19184.90407547953;21725.891370543046;21758.673619895715;21807;21833.67249491259;903.1555495611624;926.9304802139732;936.4240991790136;949.5584553182803;1026.7176526455285;10661.792173250486;11071.501782419811;11344.659660210207;11561.537538498535;11657.067434605211;573689;573935;586476;588614;589722.5652589148;85284.29431411337;106276;108614.45692771485;111717;113807;937.8780758501351;948.4356807699407;967.3663750243602;973.4840534516718;973.5473005052685;16602.58493537671;19361.612767744286;19661.311854085037;19721.70417443768;19902;49021.50978490312;50630.49369506258;50709;51281;51738.741306293225;54089.72955135199;72864.53515986216;73143.26856731364;74115.25884741083;74713.15511871176;6958.817001109722;7376.299251571092;7430.071241094864;7456.366208872307;7784.805379865548;11334.036606888507;12242.632721018384;12476.313802740937;12783.296918669563;12785.16896401731;8507.95746021266;10715.571377144826;10928.781424371628;10998.285111467729;11001.944990274997;9325.86011209846;9707.951460242653;10677.786444271227;10913.890861091606;11208.775824483302;10774.407407592658;10788.352698838095;10963.945180274048;10973.286736362112;10977.341359518454;6681.830679631143;7078.469114816512;7483.92516074854;7676.193999629959;7827.178146294557;10705.464726763787;10791.97476239756;10983;11427.885721142682;11615.303081815118 06:26:47 INFO - PROCESS | 2350 | |3;mootools.html;1482.5626440200144;1484.88120950324;1660.6346603747172;1789.9821001789994;1834.972475412869;8064.758057258277;8453.830923381529;8458.238758511732;8465.280451161643;8471.237588617016;7401.8149546261375;7638.12161601416;8005.119436861955;8254.463459875104;8261.008678958533;1918.6354592627426;2278.464560828204;2317.281642690763;2318.1886339781026;2319.4781174235745;2295.2425699519117;2327.185485080217;2334.5214231082673;2342.5666251743396;2342.894569744357;2249.392663980724;2263.422827179067;2289.713785776778;2291.8281128915314;2310.191432998454;2262.9434264143365;2289.3246492284648;2291.5302363015544;2292.2435596253194;2292.7248730152323;1896.924123035077;2227.4208705736623;2299.2757281456456;2304.2396009316885;2305.1240528599023;8123.431359804816;8199.672013119469;8210.589470526449;8228.25945664887;8243.175682431769;5013.899722005576;5190.143626301656;5191.117510023305;5195.922061169085;5196.29849970253;3287.2767991042174;3390.762646614738;3489.4068008438626;3583.605803361628;3595.9101022474388;1036.8444733290066;1057.376148072641;1071.2715353559577;1076.978460430795;1079.794838980599;10441.634542790965;10733.892661073367;10767.946160269226;10916.181286403473;10921.290116142425;4259.210217307668;4264.616184543406;4452;4465.977670111629;4466.531014243523;8659.350548708871;8663.263622592025;8665.826683466295;8667.869981950276;8684.348673849487;5095;5100.5409513143995;5107.055194788976;5107.259447380184;5110.310108135429;3681.37416639172;4014.337634290368;4204.07510347723;4396.538363471854;4779;3550.4496802995577;3573.5354403927845;3684.4657524658774;3686.64976827201;3699.2971335446177;1122.887711228887;1141.3551343490901;1144.0847322142254;1145.9885401145882;1149.7125718570358;701.4248316380621;796.4942261663886;806.467731297341;812.7480481050775;822.946508476959;5058.772355244023;5464.316960379952;5533.833984980457;5606.299212598426;5712.0860662293835;415.6508532832435;433.83947939262674;441.56064715608176;442.40938347306064;898.7169041751828;3535.699465545406;3758.699304055669;3775.811209439517;3795.2788970095594;3835.9616403835603;9498.95250523743;9910.10809027191;10082.596696132072;10177.796444071226;10213.693589192337;10156.4921753912;10168.644097456554;10172.745681358025;10178.389296642225;10183.981601839756;7327.963360183165;7751.147373788879;7843.411744119214;7855.960720196363;7859.567723775133;4570.565796249351;5432.7555260012605;5457.61796674229;5634.154876768517;5719.971400142973;9325.673601423918;9435.622575096919;9446.38598491096;9455.479948602893;9456.290778191526;7758.495697779626;8623.706444033445;9660.033996600283;9660.855087173839;9681.903180968382;6263.498920086291;6464.256610489854;6696.698648560924;6844.691988860613;6862.691178896862;7687.730929417444;7843.411744119328;7951;8026;8045.034595848301;7087.228192951762;7190.06529151207;7193.856122877409;7245.31169538914;7379.704811807467;7045.2954704531185;7058.047163632844;7067.434605231672;7084.539504932163;7142.5714457132735;6655.933440665532;6657.667116644052;6755.864882702417;6764.120664313607;6764.8985265222045;4688.859334219979;4739.312799644102;4755.714657120584;4807.567318941312;4863.3434486343895;4594;4602.884927876696;4717.528247175255;4774.880627984328;4852.053849499313 06:26:47 INFO - PROCESS | 2350 | |4;prototype.html;248.36542633571227;298.64908732239627;307.5033820380086;310.28943718883755;314.9370125974806;4072.3891416287497;4584.335271385651;5205.479452054798;5256.710880901549;5342.771162632597;5040.974795126029;5145.614078944085;5155.4586768389345;5210.713410762407;5219.765110570024;3722.423024431204;3917.0599056226515;3922.4704664870164;3962.841486340543;3976.284268831609;3384.289299247161;3864.4273111350435;4013.9598604013877;4022.336314508117;4024.605359810908;3033.453978283908;3519.436890097585;3952.9802350988207;3970.642642162205;3981.3430783920617;3245.3996010738088;3949.2891279569667;3951.6487689824507;3958.44581758554;3984.6015398460067;3261.657525959764;3261.9184520386943;3428.7257019438384;3490;3490.790552566829;4179.456670632799;4577.748223847687;4876.536729010738;5035.84892453227;5054.570361519239;4524.095180963787;4541.523140070278;4550.544945505456;4551.089782043571;4596.770161491912;4457.64338852891;4667.172918207038;5080.695158290514;5258.50044245796;5506.585916731612;3457.3085382923264;3811.1996480739076;3820.8280627371837;3826.349520581508;3843.2313537292653;5467.605760531039;5612.719364031782;5731.8567035824435;5762.827115186551;5775.3935836736955;3139.4819854723714;3732.234891847165;3790.33669107905;3803;3812.741795207575;3127.702868227515;3431.7940923544666;3868.0273060738664;3873.8837834865;3901.551321597996;3886.397608370707;4249.490061192615;4387.3857659927635;4496.460424749051;4559.475660299108;4289.785510724451;4602.631789456836;5239.502247286502;5270.393904701008;5369.033573956725;1129.282905355089;1162.5466068233395;1214.611324376191;1218.5004148299154;1236.9814452783216;727.7452891487936;785.7799816051514;838.3460900497622;847.3644766425181;857.7898414888392;4155.633936511435;4815.566599006036;4847.509792949071;4868.440129385166;4950.950490495121;4342.5874541918465;5253.527182553512;5286.524212820865;5365.785368585291;5402.270693456412;4742.5731684148595;5154.366171848913;5204.851065105557;5241.746049198805;5245.793467502496;2302.5608994378513;2340.4499942513494;2465.556199884038;2555.3134930945484;2821.929451763722;4362.738235705868;4707.646926480527;5381.596380271495;5581.4790020083865;5610.887782244332;4535.705179163344;5113.386393632788;5532.280803495601;5553.139263414178;5581.832545023656;4835.542587050206;4855.732934688484;5577.079781836032;5584.18513982949;5589.357223919302;4538.818447262072;5123.7959079617;5274.707696614354;5330.440303768049;5423.430539793423;4415.889602759957;4465.687401881837;5105.948940510547;5259.475451783242;5399.516222537677;4922.627999820134;4978.47725988766;5569.637973531708;5586.497215250647;5625.46558076982;2972.628421447319;3297.7856439331367;3571.589267234301;3573.6426357365094;3622.6920711738744;4647;5268;5327.387350454593;5341.759620816995;5360.276362691143;4367.1613451256935;5180.896382072413;5201.633256868192;5207.3230480038865;5228.921566176587;5094.617903657167;5106.540411362995;5211.091681239071;5489.972550137383;5574.9442505574425;5154;5282.841514754564;5307.6465501296925;5314.840554783363;5470.53500452466;5521.365043020104;5564.805231816867;5574.888502230014;5586.301712285965;5592.440755924375;5143.459936706592;5423.2407462954425;5442.700651464207;5443.101888188563;5454.127339625641 06:26:47 INFO - PROCESS | 2350 | |5;yui.html;701.3652644356857;742.2948199210751;749.9100107987042;774.7365895595497;823.1151512124468;13880.930595347021;14471.34878930448;14496.347664355102;14528.63678408039;14560.199189044599;9626.518674066303;12751;12862.163959342659;12879.613611591643;13418.46326146953;629.6473974574253;659.1233659233209;767.1254769562703;787.0633945604726;810.400303775208;803.4255507312271;806.2783808491397;806.4193780478047;808.7775861638038;810.0077405178668;856.4133568505563;858.2961971183632;858.5406807358065;859.5702148925537;860.1699360117485;671.781670956941;692.2800287700785;696.9616671083088;698.1935864076949;698.3958875572594;606.8907596632604;620.512897375558;624.8188025472607;627.8587317853492;628.3151365012113;13470.528531501448;13490.595282141552;13494.527691530848;13501.98735094862;13526.797098043537;12510.37448127599;12664.55674051413;12727.93636031814;12757.55348562796;12776.87223127766;10129.797404051951;10827.350358978487;11807.704807379798;11933.105017123751;12041.819372709417;3785.9242815143543;3856.2865869814173;3876.8836934891997;3929.4105884117325;3949.2299001694682;87804.5609771947;90568.54715726445;90935.54532227363;91704;92337.61493577602;10436.2694611378;10627.84058239127;10676.412797296069;10866.456677166268;11117.722056948642;19984.600307993758;19985.30051448192;19985.900070499556;19989.300374486822;19993.400197994084;6333.113364129025;6782.1861376635115;7287.526310789782;7611.238876112345;7903.525788452711;4186;4350.347447882843;4354.281543545343;4463.531329210451;4486.371907932892;3328.5007248912857;3374.6625337466057;3436.7937923724658;3442.1738782691973;3444.483327500895;632.2517890641226;638.47956468205;649.2922714241499;654.1332734127301;662.2516556291456;566.1620801214226;642.9196350456193;660.5079215984026;663.8449098568456;670.5105273150627;8413.789655258544;8594.31245500371;8836.072212417732;8887.081899586548;9114.544272786334;546.7867531662655;597.7011494252873;641.9390157934989;668.9565178263397;680.5644387591847;4336.956630433719;4433.334999750064;4437.822487100544;4462.005108556809;4501.88745281364;88571.37143542815;104009.47995259975;112919.7558329615;121056.39471802584;121567.78432215564;66419.66790166018;74662.25337746662;99729;103270.93458130796;103730.96269037364;81072.37855242862;81482.59258703669;81956.59021704854;82679;83409.58295208485;7517.624118794148;8595.699150529701;8615.612297446756;8768.956155219183;8822.2060014599;41145;43427.78286108549;49540.75229623829;58360.70819645875;58708;80307.59846200731;81892.59053704693;83327;83692;84364;10766.300190487593;11138.883612113672;11184.94407527957;11201.327920324806;11372.993258045708;10125.746856328356;11241.231839165464;11314.547418103513;12074.154809163274;12559.623211303675;10068.44623545712;11446.885531144582;11558.971609020658;11625.47685354178;11800.52797888075;10040.899591004289;11501.252418592765;11548.884511154782;11764.352960586908;12092.93953530262;9951;12536.185147965352;12860.678483037625;13041.804372934224;13304.534341298007;9285.907140928504;9364.765880853032;9471.337006409485;9712.077352651488;10959.342439453658;9284.767880803034;9899.65351212704;10821.350718956888;11084.722881927693;11390.601328953448 06:26:47 INFO - PROCESS | 2350 | __end_tp_report 06:26:47 INFO - PROCESS | 2350 | __start_cc_report 06:26:47 INFO - PROCESS | 2350 | _x_x_mozilla_cycle_collect,1392 06:26:47 INFO - PROCESS | 2350 | __end_cc_report 06:26:47 INFO - PROCESS | 2350 | __startTimestamp1478615207599__endTimestamp 06:26:47 INFO - PROCESS | 2350 | 06:26:47 INFO - PROCESS | 2350 | ------- Summary: start ------- 06:26:47 INFO - PROCESS | 2350 | Number of tests: 6 06:26:47 INFO - PROCESS | 2350 | 06:26:47 INFO - PROCESS | 2350 | [#0] dojo.html Cycles:165 Average:5690.67 Median:5900.23 stddev:644.59 (10.9%) stddev-sans-first:618.11 06:26:47 INFO - PROCESS | 2350 | Values: 3276.3 3283.8 3286.4 3295.5 3356.9 5549.9 5598.3 5679.1 5687.3 5730.5 5607.4 5681.0 5712.5 5754.4 5807.8 5084.5 5132.6 5139.8 5159.5 5200.3 5195.4 5197.2 5202.2 5236.7 5245.5 5123.7 5166.0 5213.3 5231.2 5280.6 5183.0 5214.3 5214.5 5215.7 5226.2 5111.5 5167.3 5181.1 5188.5 5196.1 5670.6 5688.8 5689.0 5700.5 5715.7 6110.7 6113.1 6120.2 6128.6 6143.3 6190.9 6197.5 6208.8 6272.2 6298.9 5732.2 5760.4 5770.0 5771.7 5772.1 6212.9 6227.6 6296.7 6329.3 6339.3 6033.5 6049.1 6064.1 6077.2 6079.8 6186.5 6188.4 6220.0 6234.8 6239.5 5993.5 6066.2 6073.1 6168.2 6174.0 6112.4 6173.9 6189.8 6215.2 6236.6 4899.0 4903.4 4903.6 4904.7 4930.8 4389.5 4449.0 4458.9 4464.3 4471.8 5597.6 5664.3 5667.3 5745.0 5798.0 6260.8 6305.7 6323.5 6326.5 6330.7 5240.1 5341.5 5782.4 5785.4 5797.8 5227.6 5326.0 5333.5 5366.4 5379.4 6239.7 6255.1 6263.2 6297.6 6310.7 6254.3 6259.0 6265.6 6267.0 6277.2 6249.0 6285.7 6294.1 6314.4 6329.2 6026.9 6061.3 6065.2 6089.9 6111.6 5100.8 6209.0 6212.5 6214.8 6214.9 4687.6 5942.2 5968.9 5980.6 6008.5 5900.2 5944.6 5959.1 5984.9 6022.6 5867.8 5923.8 5937.3 5982.6 5986.4 6091.8 6092.3 6105.6 6129.1 6130.1 4868.3 5751.3 5798.6 5882.5 5920.3 06:26:47 INFO - PROCESS | 2350 | 06:26:47 INFO - PROCESS | 2350 | [#1] ext.html Cycles:180 Average:61463.53 Median:38839.92 stddev:132407.50 (340.9%) stddev-sans-first:132699.76 06:26:47 INFO - PROCESS | 2350 | Values: 477.6 497.4 502.1 508.7 515.2 30900.7 31039.5 31418.8 31633.0 39977.0 40510.8 40844.4 40943.0 40976.0 41096.0 9014.0 9225.2 9237.4 9242.4 9253.8 9212.3 9271.0 9320.0 9323.4 9333.0 9284.2 9345.2 9352.3 9356.1 9388.1 9269.6 9333.3 9352.2 9354.7 9356.4 9049.0 9114.4 9119.4 9144.7 9254.7 38705.5 38922.2 39028.6 39048.8 39060.8 3284.9 3510.3 3771.5 3797.0 3825.8 28779.4 28901.3 29186.9 29354.7 29617.4 3133.7 3169.8 3175.4 3222.0 3266.7 367761.0 369778.0 370012.0 370084.0 370548.1 56598.7 56890.4 56911.7 56963.7 56969.7 32878.8 33419.8 33622.8 34328.8 59022.0 15053.8 15154.6 15157.5 15171.5 15221.4 11489.0 11560.2 11565.6 11581.1 11587.9 17990.0 17994.0 18088.4 18195.9 18205.4 6286.2 7288.6 7467.1 7539.1 7544.1 810.4 921.6 985.5 1042.0 1044.3 28428.4 29089.9 29118.0 29446.4 29451.9 1003.2 1043.4 1060.8 1074.0 1112.8 14498.6 14708.6 15054.0 15282.8 15352.3 637570.0 642597.0 643925.0 644040.8 645748.0 54539.2 55102.2 55166.4 55213.0 55516.6 1085.6 1193.5 1236.5 1331.1 1340.2 28684.1 29653.7 29785.0 30357.7 30472.5 38675.4 39996.6 47275.8 47850.3 47957.8 430238.0 431236.0 432161.0 432726.0 432958.0 22972.1 23393.6 24971.5 25540.0 25574.7 42900.8 42948.1 43953.9 44065.3 44398.6 36401.4 36511.8 37563.4 37564.0 40238.0 38442.4 39844.6 40838.6 40959.6 41143.6 38165.6 38757.6 39048.8 39194.8 39576.4 31876.0 38706.4 38986.0 40024.6 40238.0 24566.6 25687.7 25876.0 26070.9 26230.7 06:26:47 INFO - PROCESS | 2350 | 06:26:47 INFO - PROCESS | 2350 | [#2] jquery.html Cycles:180 Average:33142.31 Median:12821.95 stddev:96445.88 (752.2%) stddev-sans-first:96684.92 06:26:47 INFO - PROCESS | 2350 | Values: 308.4 344.2 364.6 365.6 395.5 14915.7 15371.4 16717.5 19661.0 19715.4 19277.0 19617.9 20041.7 20069.0 20098.6 5467.4 5737.0 5738.7 5742.4 5748.2 4054.0 4215.4 4324.0 5473.8 6345.4 6091.5 6239.5 6353.2 6356.6 6357.2 6219.7 6307.7 6311.6 6313.4 6316.6 5254.4 5437.1 5469.2 5474.2 5507.1 24978.8 25016.6 25031.0 25032.5 25335.5 2170.2 2186.0 2199.2 2218.6 2412.2 13967.4 15064.7 15102.4 15251.9 15783.8 1737.0 1766.2 1791.5 1860.3 1900.0 84133.6 102480.5 107115.0 109857.0 111232.9 8363.4 10086.9 10462.7 10581.5 10674.6 13175.3 13190.4 13202.8 13205.3 13216.2 8031.8 8174.5 8187.5 8313.0 8361.5 8080.2 8092.4 8205.5 8237.7 8365.0 5359.2 6330.0 6337.5 6426.4 6542.6 1742.4 1778.2 1782.3 1783.6 1787.2 879.0 882.5 889.8 930.1 1009.1 19184.9 21725.9 21758.7 21807.0 21833.7 903.2 926.9 936.4 949.6 1026.7 10661.8 11071.5 11344.7 11561.5 11657.1 573689.0 573935.0 586476.0 588614.0 589722.6 85284.3 106276.0 108614.5 111717.0 113807.0 937.9 948.4 967.4 973.5 973.5 16602.6 19361.6 19661.3 19721.7 19902.0 49021.5 50630.5 50709.0 51281.0 51738.7 54089.7 72864.5 73143.3 74115.3 74713.2 6958.8 7376.3 7430.1 7456.4 7784.8 11334.0 12242.6 12476.3 12783.3 12785.2 8508.0 10715.6 10928.8 10998.3 11001.9 9325.9 9708.0 10677.8 10913.9 11208.8 10774.4 10788.4 10963.9 10973.3 10977.3 6681.8 7078.5 7483.9 7676.2 7827.2 10705.5 10792.0 10983.0 11427.9 11615.3 06:26:47 INFO - PROCESS | 2350 | 06:26:47 INFO - PROCESS | 2350 | [#3] mootools.html Cycles:180 Average:5343.87 Median:4829.81 stddev:2973.34 (61.6%) stddev-sans-first:2967.53 06:26:47 INFO - PROCESS | 2350 | Values: 1482.6 1484.9 1660.6 1790.0 1835.0 8064.8 8453.8 8458.2 8465.3 8471.2 7401.8 7638.1 8005.1 8254.5 8261.0 1918.6 2278.5 2317.3 2318.2 2319.5 2295.2 2327.2 2334.5 2342.6 2342.9 2249.4 2263.4 2289.7 2291.8 2310.2 2262.9 2289.3 2291.5 2292.2 2292.7 1896.9 2227.4 2299.3 2304.2 2305.1 8123.4 8199.7 8210.6 8228.3 8243.2 5013.9 5190.1 5191.1 5195.9 5196.3 3287.3 3390.8 3489.4 3583.6 3595.9 1036.8 1057.4 1071.3 1077.0 1079.8 10441.6 10733.9 10767.9 10916.2 10921.3 4259.2 4264.6 4452.0 4466.0 4466.5 8659.4 8663.3 8665.8 8667.9 8684.3 5095.0 5100.5 5107.1 5107.3 5110.3 3681.4 4014.3 4204.1 4396.5 4779.0 3550.4 3573.5 3684.5 3686.6 3699.3 1122.9 1141.4 1144.1 1146.0 1149.7 701.4 796.5 806.5 812.7 822.9 5058.8 5464.3 5533.8 5606.3 5712.1 415.7 433.8 441.6 442.4 898.7 3535.7 3758.7 3775.8 3795.3 3836.0 9499.0 9910.1 10082.6 10177.8 10213.7 10156.5 10168.6 10172.7 10178.4 10184.0 7328.0 7751.1 7843.4 7856.0 7859.6 4570.6 5432.8 5457.6 5634.2 5720.0 9325.7 9435.6 9446.4 9455.5 9456.3 7758.5 8623.7 9660.0 9660.9 9681.9 6263.5 6464.3 6696.7 6844.7 6862.7 7687.7 7843.4 7951.0 8026.0 8045.0 7087.2 7190.1 7193.9 7245.3 7379.7 7045.3 7058.0 7067.4 7084.5 7142.6 6655.9 6657.7 6755.9 6764.1 6764.9 4688.9 4739.3 4755.7 4807.6 4863.3 4594.0 4602.9 4717.5 4774.9 4852.1 06:26:47 INFO - PROCESS | 2350 | 06:26:47 INFO - PROCESS | 2350 | [#4] prototype.html Cycles:180 Average:4306.29 Median:4841.53 stddev:1338.90 (27.7%) stddev-sans-first:1307.55 06:26:47 INFO - PROCESS | 2350 | Values: 248.4 298.6 307.5 310.3 314.9 4072.4 4584.3 5205.5 5256.7 5342.8 5041.0 5145.6 5155.5 5210.7 5219.8 3722.4 3917.1 3922.5 3962.8 3976.3 3384.3 3864.4 4014.0 4022.3 4024.6 3033.5 3519.4 3953.0 3970.6 3981.3 3245.4 3949.3 3951.6 3958.4 3984.6 3261.7 3261.9 3428.7 3490.0 3490.8 4179.5 4577.7 4876.5 5035.8 5054.6 4524.1 4541.5 4550.5 4551.1 4596.8 4457.6 4667.2 5080.7 5258.5 5506.6 3457.3 3811.2 3820.8 3826.3 3843.2 5467.6 5612.7 5731.9 5762.8 5775.4 3139.5 3732.2 3790.3 3803.0 3812.7 3127.7 3431.8 3868.0 3873.9 3901.6 3886.4 4249.5 4387.4 4496.5 4559.5 4289.8 4602.6 5239.5 5270.4 5369.0 1129.3 1162.5 1214.6 1218.5 1237.0 727.7 785.8 838.3 847.4 857.8 4155.6 4815.6 4847.5 4868.4 4951.0 4342.6 5253.5 5286.5 5365.8 5402.3 4742.6 5154.4 5204.9 5241.7 5245.8 2302.6 2340.4 2465.6 2555.3 2821.9 4362.7 4707.6 5381.6 5581.5 5610.9 4535.7 5113.4 5532.3 5553.1 5581.8 4835.5 4855.7 5577.1 5584.2 5589.4 4538.8 5123.8 5274.7 5330.4 5423.4 4415.9 4465.7 5105.9 5259.5 5399.5 4922.6 4978.5 5569.6 5586.5 5625.5 2972.6 3297.8 3571.6 3573.6 3622.7 4647.0 5268.0 5327.4 5341.8 5360.3 4367.2 5180.9 5201.6 5207.3 5228.9 5094.6 5106.5 5211.1 5490.0 5574.9 5154.0 5282.8 5307.6 5314.8 5470.5 5521.4 5564.8 5574.9 5586.3 5592.4 5143.5 5423.2 5442.7 5443.1 5454.1 06:26:47 INFO - PROCESS | 2350 | 06:26:47 INFO - PROCESS | 2350 | [#5] yui.html Cycles:180 Average:20129.37 Median:25043.77 stddev:30239.39 (120.7%) stddev-sans-first:30289.03 06:26:47 INFO - PROCESS | 2350 | Values: 701.4 742.3 749.9 774.7 823.1 13880.9 14471.3 14496.3 14528.6 14560.2 9626.5 12751.0 12862.2 12879.6 13418.5 629.6 659.1 767.1 787.1 810.4 803.4 806.3 806.4 808.8 810.0 856.4 858.3 858.5 859.6 860.2 671.8 692.3 697.0 698.2 698.4 606.9 620.5 624.8 627.9 628.3 13470.5 13490.6 13494.5 13502.0 13526.8 12510.4 12664.6 12727.9 12757.6 12776.9 10129.8 10827.4 11807.7 11933.1 12041.8 3785.9 3856.3 3876.9 3929.4 3949.2 87804.6 90568.5 90935.5 91704.0 92337.6 10436.3 10627.8 10676.4 10866.5 11117.7 19984.6 19985.3 19985.9 19989.3 19993.4 6333.1 6782.2 7287.5 7611.2 7903.5 4186.0 4350.3 4354.3 4463.5 4486.4 3328.5 3374.7 3436.8 3442.2 3444.5 632.3 638.5 649.3 654.1 662.3 566.2 642.9 660.5 663.8 670.5 8413.8 8594.3 8836.1 8887.1 9114.5 546.8 597.7 641.9 669.0 680.6 4337.0 4433.3 4437.8 4462.0 4501.9 88571.4 104009.5 112919.8 121056.4 121567.8 66419.7 74662.3 99729.0 103270.9 103731.0 81072.4 81482.6 81956.6 82679.0 83409.6 7517.6 8595.7 8615.6 8769.0 8822.2 41145.0 43427.8 49540.8 58360.7 58708.0 80307.6 81892.6 83327.0 83692.0 84364.0 10766.3 11138.9 11184.9 11201.3 11373.0 10125.7 11241.2 11314.5 12074.2 12559.6 10068.4 11446.9 11559.0 11625.5 11800.5 10040.9 11501.3 11548.9 11764.4 12092.9 9951.0 12536.2 12860.7 13041.8 13304.5 9285.9 9364.8 9471.3 9712.1 10959.3 9284.8 9899.7 10821.4 11084.7 11390.6 06:26:47 INFO - PROCESS | 2350 | -------- Summary: end -------- 06:26:47 INFO - PROCESS | 2350 | 06:26:48 INFO - TEST-INFO | 2350: exit 0 06:26:48 INFO - TEST-OK | dromaeo_css | took 1118898ms 06:26:48 INFO - TEST-START | kraken 06:26:48 INFO - operating with platform_type : mac_ 06:26:48 INFO - Initialising browser for kraken test... 06:26:48 INFO - TEST-INFO | started process 2353 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpz3X7v7/profile http://localhost:49536/getInfo.html) 06:26:50 INFO - PROCESS | 2353 | __metrics Screen width/height:1600/1200 06:26:50 INFO - PROCESS | 2353 | colorDepth:24 06:26:50 INFO - PROCESS | 2353 | Browser inner width/height: 1024/689 06:26:50 INFO - PROCESS | 2353 | __metrics 06:26:50 INFO - PROCESS | 2353 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:26:50 INFO - PROCESS | 2353 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:26:51 INFO - TEST-INFO | 2353: exit 0 06:26:51 INFO - Browser initialized. 06:26:51 INFO - Running cycle 1/1 for kraken test... 06:26:51 INFO - TEST-INFO | started process 2355 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpz3X7v7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:27:03 INFO - PROCESS | 2355 | RSS: Main: 231784448 06:27:03 INFO - PROCESS | 2355 | 06:27:06 INFO - PROCESS | 2355 | Cycle 1(1): loaded http://localhost:49536/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49536/tests/kraken/driver.html?testName=audio-beat-detection) 06:27:07 INFO - PROCESS | 2355 | RSS: Main: 290017280 06:27:07 INFO - PROCESS | 2355 | 06:27:10 INFO - PROCESS | 2355 | Cycle 1(1): loaded http://localhost:49536/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49536/tests/kraken/driver.html?testName=audio-dft) 06:27:10 INFO - PROCESS | 2355 | RSS: Main: 291397632 06:27:10 INFO - PROCESS | 2355 | 06:27:14 INFO - PROCESS | 2355 | Cycle 1(1): loaded http://localhost:49536/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49536/tests/kraken/driver.html?testName=audio-fft) 06:27:14 INFO - PROCESS | 2355 | RSS: Main: 386277376 06:27:14 INFO - PROCESS | 2355 | 06:27:16 INFO - PROCESS | 2355 | Cycle 1(1): loaded http://localhost:49536/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49536/tests/kraken/driver.html?testName=audio-oscillator) 06:27:17 INFO - PROCESS | 2355 | RSS: Main: 285011968 06:27:17 INFO - PROCESS | 2355 | 06:27:19 INFO - PROCESS | 2355 | Cycle 1(1): loaded http://localhost:49536/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49536/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:27:20 INFO - PROCESS | 2355 | RSS: Main: 289902592 06:27:20 INFO - PROCESS | 2355 | 06:27:22 INFO - PROCESS | 2355 | Cycle 1(1): loaded http://localhost:49536/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49536/tests/kraken/driver.html?testName=imaging-darkroom) 06:27:23 INFO - PROCESS | 2355 | RSS: Main: 326549504 06:27:23 INFO - PROCESS | 2355 | 06:27:26 INFO - PROCESS | 2355 | Cycle 1(1): loaded http://localhost:49536/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49536/tests/kraken/driver.html?testName=imaging-desaturate) 06:27:26 INFO - PROCESS | 2355 | RSS: Main: 328306688 06:27:26 INFO - PROCESS | 2355 | 06:27:29 INFO - PROCESS | 2355 | Cycle 1(1): loaded http://localhost:49536/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49536/tests/kraken/driver.html?testName=json-parse-financial) 06:27:30 INFO - PROCESS | 2355 | RSS: Main: 324378624 06:27:30 INFO - PROCESS | 2355 | 06:27:31 INFO - PROCESS | 2355 | Cycle 1(1): loaded http://localhost:49536/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49536/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:27:32 INFO - PROCESS | 2355 | RSS: Main: 302657536 06:27:32 INFO - PROCESS | 2355 | 06:27:34 INFO - PROCESS | 2355 | Cycle 1(1): loaded http://localhost:49536/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49536/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:27:35 INFO - PROCESS | 2355 | RSS: Main: 304189440 06:27:35 INFO - PROCESS | 2355 | 06:27:37 INFO - PROCESS | 2355 | Cycle 1(1): loaded http://localhost:49536/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49536/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:27:37 INFO - PROCESS | 2355 | RSS: Main: 307113984 06:27:37 INFO - PROCESS | 2355 | 06:27:40 INFO - PROCESS | 2355 | Cycle 1(1): loaded http://localhost:49536/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49536/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:27:41 INFO - PROCESS | 2355 | RSS: Main: 315109376 06:27:41 INFO - PROCESS | 2355 | 06:27:45 INFO - PROCESS | 2355 | Cycle 1(1): loaded http://localhost:49536/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49536/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:27:45 INFO - PROCESS | 2355 | RSS: Main: 306999296 06:27:45 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | Cycle 1(1): loaded http://localhost:49536/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49536/tests/kraken/driver.html?testName=ai-astar) 06:27:47 INFO - PROCESS | 2355 | RSS: Main: 352755712 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | __start_tp_report 06:27:47 INFO - PROCESS | 2355 | _x_x_mozilla_page_load 06:27:47 INFO - PROCESS | 2355 | _x_x_mozilla_page_load_details 06:27:47 INFO - PROCESS | 2355 | |i|pagename|runs| 06:27:47 INFO - PROCESS | 2355 | |0;ai-astar;104;113;113;111;118;151;111;113;112;112 06:27:47 INFO - PROCESS | 2355 | |1;audio-beat-detection;150;115;113;152;132;119;113;112;144;114 06:27:47 INFO - PROCESS | 2355 | |2;audio-dft;184;185;185;182;187;182;184;184;179;185 06:27:47 INFO - PROCESS | 2355 | |3;audio-fft;80;59;60;90;62;58;58;58;83;58 06:27:47 INFO - PROCESS | 2355 | |4;audio-oscillator;90;72;94;95;97;102;91;68;69;92 06:27:47 INFO - PROCESS | 2355 | |5;imaging-gaussian-blur;87;91;135;84;87;83;87;84;86;84 06:27:47 INFO - PROCESS | 2355 | |6;imaging-darkroom;93;93;93;94;93;94;94;96;91;93 06:27:47 INFO - PROCESS | 2355 | |7;imaging-desaturate;133;131;82;81;83;80;84;86;86;81 06:27:47 INFO - PROCESS | 2355 | |8;json-parse-financial;57;56;54;55;57;55;57;56;55;56 06:27:47 INFO - PROCESS | 2355 | |9;json-stringify-tinderbox;66;74;73;77;74;69;70;74;72;71 06:27:47 INFO - PROCESS | 2355 | |10;stanford-crypto-aes;88;90;87;86;86;86;84;86;89;87 06:27:47 INFO - PROCESS | 2355 | |11;stanford-crypto-ccm;149;146;148;162;153;153;150;146;186;156 06:27:47 INFO - PROCESS | 2355 | |12;stanford-crypto-pbkdf2;180;233;196;175;219;218;173;183;233;187 06:27:47 INFO - PROCESS | 2355 | |13;stanford-crypto-sha256-iterative;68;68;67;67;68;67;65;67;66;66 06:27:47 INFO - PROCESS | 2355 | __end_tp_report 06:27:47 INFO - PROCESS | 2355 | __start_cc_report 06:27:47 INFO - PROCESS | 2355 | _x_x_mozilla_cycle_collect,2070 06:27:47 INFO - PROCESS | 2355 | __end_cc_report 06:27:47 INFO - PROCESS | 2355 | __startTimestamp1478615267622__endTimestamp 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | ------- Summary: start ------- 06:27:47 INFO - PROCESS | 2355 | Number of tests: 14 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | [#0] ai-astar Cycles:10 Average:115.80 Median:113.00 stddev:12.83 (11.4%) stddev-sans-first:12.88 06:27:47 INFO - PROCESS | 2355 | Values: 104.0 113.0 113.0 111.0 118.0 151.0 111.0 113.0 112.0 112.0 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | [#1] audio-beat-detection Cycles:10 Average:126.40 Median:125.50 stddev:16.53 (13.2%) stddev-sans-first:15.16 06:27:47 INFO - PROCESS | 2355 | Values: 150.0 115.0 113.0 152.0 132.0 119.0 113.0 112.0 144.0 114.0 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.50 stddev:2.21 (1.2%) stddev-sans-first:2.35 06:27:47 INFO - PROCESS | 2355 | Values: 184.0 185.0 185.0 182.0 187.0 182.0 184.0 184.0 179.0 185.0 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | [#3] audio-fft Cycles:10 Average:66.60 Median:61.00 stddev:12.54 (20.6%) stddev-sans-first:12.32 06:27:47 INFO - PROCESS | 2355 | Values: 80.0 59.0 60.0 90.0 62.0 58.0 58.0 58.0 83.0 58.0 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | [#4] audio-oscillator Cycles:10 Average:87.00 Median:91.50 stddev:12.46 (13.6%) stddev-sans-first:13.17 06:27:47 INFO - PROCESS | 2355 | Values: 90.0 72.0 94.0 95.0 97.0 102.0 91.0 68.0 69.0 92.0 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:86.50 stddev:15.70 (18.2%) stddev-sans-first:16.60 06:27:47 INFO - PROCESS | 2355 | Values: 87.0 91.0 135.0 84.0 87.0 83.0 87.0 84.0 86.0 84.0 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.50 stddev:1.26 (1.4%) stddev-sans-first:1.33 06:27:47 INFO - PROCESS | 2355 | Values: 93.0 93.0 93.0 94.0 93.0 94.0 94.0 96.0 91.0 93.0 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | [#7] imaging-desaturate Cycles:10 Average:92.70 Median:82.50 stddev:20.82 (25.2%) stddev-sans-first:16.18 06:27:47 INFO - PROCESS | 2355 | Values: 133.0 131.0 82.0 81.0 83.0 80.0 84.0 86.0 86.0 81.0 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.00 stddev:1.03 (1.8%) stddev-sans-first:1.00 06:27:47 INFO - PROCESS | 2355 | Values: 57.0 56.0 54.0 55.0 57.0 55.0 57.0 56.0 55.0 56.0 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:73.50 stddev:3.13 (4.3%) stddev-sans-first:2.45 06:27:47 INFO - PROCESS | 2355 | Values: 66.0 74.0 73.0 77.0 74.0 69.0 70.0 74.0 72.0 71.0 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:87.00 stddev:1.73 (2.0%) stddev-sans-first:1.79 06:27:47 INFO - PROCESS | 2355 | Values: 88.0 90.0 87.0 86.0 86.0 86.0 84.0 86.0 89.0 87.0 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | [#11] stanford-crypto-ccm Cycles:10 Average:154.90 Median:153.00 stddev:11.98 (7.8%) stddev-sans-first:12.51 06:27:47 INFO - PROCESS | 2355 | Values: 149.0 146.0 148.0 162.0 153.0 153.0 150.0 146.0 186.0 156.0 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.70 Median:207.00 stddev:23.78 (11.5%) stddev-sans-first:24.13 06:27:47 INFO - PROCESS | 2355 | Values: 180.0 233.0 196.0 175.0 219.0 218.0 173.0 183.0 233.0 187.0 06:27:47 INFO - PROCESS | 2355 | 06:27:47 INFO - PROCESS | 2355 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.99 (1.5%) stddev-sans-first:0.97 06:27:47 INFO - PROCESS | 2355 | Values: 68.0 68.0 67.0 67.0 68.0 67.0 65.0 67.0 66.0 66.0 06:27:47 INFO - PROCESS | 2355 | -------- Summary: end -------- 06:27:47 INFO - PROCESS | 2355 | 06:27:48 INFO - TEST-INFO | 2355: exit 0 06:27:48 INFO - TEST-OK | kraken | took 60296ms 06:27:48 INFO - SUITE-END | took 1179s 06:27:48 INFO - Completed test suite (00:19:39) 06:27:48 INFO - javascript benchmark 06:27:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3276.3119744853566, 3283.802971821691, 3286.4248756467614, 3295.505674148879, 3356.865725370984, 5549.889002219953, 5598.300212473441, 5679.062954612489, 5687.260656114704, 5730.455606717361, 5607.411221821701, 5681.0, 5712.457316554931, 5754.367019627837, 5807.82576522705, 5084.542391184793, 5132.589392848563, 5139.794408223685, 5159.535641792238, 5200.297959775438, 5195.376554813428, 5197.194434862584, 5202.245674377213, 5236.659617124875, 5245.527902488775, 5123.692578445268, 5166.044281807877, 5213.296205012312, 5231.163013917774, 5280.630355875091, 5183.041137389595, 5214.270002199695, 5214.478552144793, 5215.713135777531, 5226.189940559219, 5111.539961403491, 5167.3282473278605, 5181.145111056671, 5188.533032027136, 5196.116660167742, 5670.631408958405, 5688.83378907323, 5689.004424225744, 5700.486956173922, 5715.742791574389, 6110.749250451246, 6113.050656958994, 6120.20437343147, 6128.601640893327, 6143.341297849555, 6190.907136392958, 6197.5351848611535, 6208.844778880519, 6272.153259310026, 6298.87402251957, 5732.168835518826, 5760.3951585083805, 5769.9711501442225, 5771.68255745938, 5772.105323674838, 6212.875742485125, 6227.626342419469, 6296.748130074746, 6329.2721337045905, 6339.302676705559, 6033.487153591994, 6049.062395328731, 6064.090386442069, 6077.17958075663, 6079.787207447718, 6186.540497288958, 6188.412100850412, 6219.968900155471, 6234.781782637586, 6239.500839932794, 5993.4905533029305, 6066.241719785027, 6073.058675905153, 6168.19813424252, 6173.969130154321, 6112.442689633878, 6173.938260617337, 6189.752409903554, 6215.160953271251, 6236.563440559208, 4899.0, 4903.41159060915, 4903.583195428358, 4904.681195722266, 4930.802767889316, 4389.473263208435, 4449.021215332622, 4458.9331160032625, 4464.263396539534, 4471.776411179428, 5597.608167428241, 5664.291963504562, 5667.291588551431, 5745.023346031101, 5798.014337562625, 6260.780872669435, 6305.716242769086, 6323.52573556985, 6326.49388048955, 6330.746770129143, 5240.0567897778, 5341.465853414627, 5782.421757824184, 5785.4214578541805, 5797.752808988764, 5227.555657769056, 5326.0, 5333.493318134849, 5366.409694933554, 5379.354477462652, 6239.656818875005, 6255.093011513214, 6263.248410190807, 6297.590656607397, 6310.652914089769, 6254.343293954069, 6258.967270400515, 6265.592736472115, 6267.0, 6277.1525844010475, 6249.031400132987, 6285.748570057147, 6294.0558916162945, 6314.431701146918, 6329.208848893888, 6026.939730602638, 6061.3029501606115, 6065.181200537871, 6089.908651370144, 6111.633302001894, 5100.846974590768, 6209.0, 6212.502999760099, 6214.782482613087, 6214.937850621617, 4687.648426368104, 5942.227510423617, 5968.940310596839, 5980.5514586405325, 6008.459238668541, 5900.232969713909, 5944.613600115978, 5959.135925290895, 5984.910226346521, 6022.578419510767, 5867.765289388377, 5923.763049477972, 5937.287525496968, 5982.58121931478, 5986.371430999682, 6091.756329746938, 6092.268927728701, 6105.603135796159, 6129.111278864629, 6130.141780150693, 4868.318435418973, 5751.33859606134, 5798.59409841307, 5882.470577648032, 5920.2895652521975], "value": 5649.585509918081, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.60836114386217, 497.3608912547376, 502.11878153840007, 508.6744483530541, 515.1937218253433, 30900.69099309012, 31039.534406983887, 31418.842905785466, 31633.0, 39977.0, 40510.7974460128, 40844.38733418994, 40943.0, 40976.0, 41096.0, 9013.954930225356, 9225.169734723873, 9237.445753254826, 9242.353035287533, 9253.768655783591, 9212.263018958467, 9270.953645231764, 9319.953400233024, 9323.440593564374, 9332.973372928973, 9284.16442520173, 9345.158935695787, 9352.25181985443, 9356.111169438926, 9388.1081297277, 9269.629214831399, 9333.346665733401, 9352.158305752448, 9354.672586459475, 9356.438613683133, 9048.954755226248, 9114.361994660376, 9119.40723852954, 9144.725658230263, 9254.676086337013, 38705.45178192869, 38922.22155556873, 39028.609713902784, 39048.80475597604, 39060.82817515479, 3284.917877053069, 3510.33303672304, 3771.509703738524, 3797.0, 3825.8469661213517, 28779.424411511653, 28901.277468063257, 29186.85406572975, 29354.706452935625, 29617.407651846843, 3133.7336326412283, 3169.8415079245947, 3175.42842288388, 3221.951670724941, 3266.70599646033, 367761.0, 369778.0, 370012.0, 370084.0, 370548.14725926466, 56598.71700641471, 56890.43109568934, 56911.71544142336, 56963.715181423824, 56969.72727045488, 32878.83560582182, 33419.83290083534, 33622.83188584042, 34328.82835585806, 59022.0, 15053.774193387108, 15154.621134471727, 15157.469488567847, 15171.544853654188, 15221.391144354102, 11489.0, 11560.190786645022, 11565.595204167981, 11581.07351411885, 11587.942060289814, 17990.0, 17994.0, 18088.366907158186, 18195.90824550531, 18205.362812301504, 6286.245650521875, 7288.635568221568, 7467.141278753013, 7539.132999705104, 7544.132424771222, 810.3557671651051, 921.5991043895792, 985.5367977050774, 1041.994790026045, 1044.2585764107569, 28428.431431371257, 29089.854550727534, 29118.0, 29446.411071778446, 29451.852740736158, 1003.2325271167563, 1043.4052590023766, 1060.8355704865758, 1074.0441007503327, 1112.8386383974278, 14498.565043048726, 14708.632284192981, 15054.0, 15282.770758438632, 15352.309146088453, 637570.0, 642597.0, 643925.0, 644040.779796098, 645748.0, 54539.18191227135, 55102.21426750134, 55166.448335516936, 55213.0, 55516.61208469821, 1085.630885498934, 1193.4927655746274, 1236.5486597392066, 1331.074902942465, 1340.1958824705098, 28684.13947581576, 29653.703462965095, 29785.0, 30357.69642303637, 30472.542911855897, 38675.41986870256, 39996.60011899686, 47275.76362118167, 47850.28224576704, 47957.76021119872, 430238.0, 431236.0, 432161.0, 432726.0, 432958.0, 22972.081116755144, 23393.649095263245, 24971.500569988137, 25539.978400863758, 25574.744252557237, 42900.78549607357, 42948.1410371797, 43953.901152471444, 44065.339019914085, 44398.556014440735, 36401.35364128561, 36511.81744091262, 37563.436548452344, 37564.0, 40238.0, 38442.42336365013, 39844.60155398409, 40838.59161408348, 40959.59040409677, 41143.588564113976, 38165.618343816204, 38757.6124238754, 39048.80475597604, 39194.80402597969, 39576.40635390529, 31876.0, 38706.4194037084, 38986.0, 40024.599754003255, 40238.0, 24566.631500527863, 25687.74312256928, 25876.0, 26070.86964565241, 26230.737692623592], "value": 18822.614469024655, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.4032397311203, 344.2151893682402, 364.5552426040232, 365.5576752129922, 395.48191868652066, 14915.701685966274, 15371.385144594216, 16717.49847504575, 19661.0, 19715.408537743853, 19277.036148192605, 19617.921014344207, 20041.699374509393, 20069.0, 20098.59802803943, 5467.371252305996, 5737.0, 5738.713064346787, 5742.425757424266, 5748.223989761371, 4053.9594604054023, 4215.409842622035, 4323.978380108095, 5473.808416705416, 6345.3971872672255, 6091.512678985692, 6239.532035097386, 6353.237611486628, 6356.555041147121, 6357.173567436227, 6219.720112594921, 6307.747690092391, 6311.621302721828, 6313.40022697843, 6316.62100273985, 5254.448282930313, 5437.13005919055, 5469.206964990054, 5474.178873169017, 5507.091329930557, 24978.750212497824, 25016.624750628755, 25031.0, 25032.4993500129, 25335.493290134094, 2170.2078741259484, 2185.9890700546553, 2199.1533259694975, 2218.5673793610254, 2412.203972689026, 13967.441302347894, 15064.698706025927, 15102.39590416382, 15251.932364734474, 15783.763243551355, 1736.9565760855953, 1766.1787268919886, 1791.5162906015303, 1860.283790740561, 1900.0214889332005, 84133.57933210356, 102480.46279305816, 107115.0, 109857.0, 111232.88767112387, 8363.369143017175, 10086.899131008742, 10462.738431539121, 10581.470926453647, 10674.573017079385, 13175.275359855108, 13190.406431710597, 13202.801957970638, 13205.273709945854, 13216.207027578375, 8031.839363212703, 8174.544873044205, 8187.5087494750505, 8313.043999939964, 8361.498310101413, 8080.232377924088, 8092.433529652985, 8205.548694821722, 8237.711680091168, 8364.958175209085, 5359.249705041298, 6329.968350158312, 6337.524685648595, 6426.3573642635365, 6542.640154791532, 1742.4337090445656, 1778.2264714849082, 1782.3138091834737, 1783.616522447654, 1787.204693911197, 878.9935523825185, 882.5102068351998, 889.7686601483531, 930.06993006993, 1009.1271050541229, 19184.90407547953, 21725.891370543046, 21758.673619895715, 21807.0, 21833.67249491259, 903.1555495611624, 926.9304802139732, 936.4240991790136, 949.5584553182803, 1026.7176526455285, 10661.792173250486, 11071.501782419811, 11344.659660210207, 11561.537538498535, 11657.067434605211, 573689.0, 573935.0, 586476.0, 588614.0, 589722.5652589148, 85284.29431411337, 106276.0, 108614.45692771485, 111717.0, 113807.0, 937.8780758501351, 948.4356807699407, 967.3663750243602, 973.4840534516718, 973.5473005052685, 16602.58493537671, 19361.612767744286, 19661.311854085037, 19721.70417443768, 19902.0, 49021.50978490312, 50630.49369506258, 50709.0, 51281.0, 51738.741306293225, 54089.72955135199, 72864.53515986216, 73143.26856731364, 74115.25884741083, 74713.15511871176, 6958.817001109722, 7376.299251571092, 7430.071241094864, 7456.366208872307, 7784.805379865548, 11334.036606888507, 12242.632721018384, 12476.313802740937, 12783.296918669563, 12785.16896401731, 8507.95746021266, 10715.571377144826, 10928.781424371628, 10998.285111467729, 11001.944990274997, 9325.86011209846, 9707.951460242653, 10677.786444271227, 10913.890861091606, 11208.775824483302, 10774.407407592658, 10788.352698838095, 10963.945180274048, 10973.286736362112, 10977.341359518454, 6681.830679631143, 7078.469114816512, 7483.92516074854, 7676.193999629959, 7827.178146294557, 10705.464726763787, 10791.97476239756, 10983.0, 11427.885721142682, 11615.303081815118], "value": 9327.531273823159, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1482.5626440200144, 1484.88120950324, 1660.6346603747172, 1789.9821001789994, 1834.972475412869, 8064.758057258277, 8453.830923381529, 8458.238758511732, 8465.280451161643, 8471.237588617016, 7401.8149546261375, 7638.12161601416, 8005.119436861955, 8254.463459875104, 8261.008678958533, 1918.6354592627426, 2278.464560828204, 2317.281642690763, 2318.1886339781026, 2319.4781174235745, 2295.2425699519117, 2327.185485080217, 2334.5214231082673, 2342.5666251743396, 2342.894569744357, 2249.392663980724, 2263.422827179067, 2289.713785776778, 2291.8281128915314, 2310.191432998454, 2262.9434264143365, 2289.3246492284648, 2291.5302363015544, 2292.2435596253194, 2292.7248730152323, 1896.924123035077, 2227.4208705736623, 2299.2757281456456, 2304.2396009316885, 2305.1240528599023, 8123.431359804816, 8199.672013119469, 8210.589470526449, 8228.25945664887, 8243.175682431769, 5013.899722005576, 5190.143626301656, 5191.117510023305, 5195.922061169085, 5196.29849970253, 3287.2767991042174, 3390.762646614738, 3489.4068008438626, 3583.605803361628, 3595.9101022474388, 1036.8444733290066, 1057.376148072641, 1071.2715353559577, 1076.978460430795, 1079.794838980599, 10441.634542790965, 10733.892661073367, 10767.946160269226, 10916.181286403473, 10921.290116142425, 4259.210217307668, 4264.616184543406, 4452.0, 4465.977670111629, 4466.531014243523, 8659.350548708871, 8663.263622592025, 8665.826683466295, 8667.869981950276, 8684.348673849487, 5095.0, 5100.5409513143995, 5107.055194788976, 5107.259447380184, 5110.310108135429, 3681.37416639172, 4014.337634290368, 4204.07510347723, 4396.538363471854, 4779.0, 3550.4496802995577, 3573.5354403927845, 3684.4657524658774, 3686.64976827201, 3699.2971335446177, 1122.887711228887, 1141.3551343490901, 1144.0847322142254, 1145.9885401145882, 1149.7125718570358, 701.4248316380621, 796.4942261663886, 806.467731297341, 812.7480481050775, 822.946508476959, 5058.772355244023, 5464.316960379952, 5533.833984980457, 5606.299212598426, 5712.0860662293835, 415.6508532832435, 433.83947939262674, 441.56064715608176, 442.40938347306064, 898.7169041751828, 3535.699465545406, 3758.699304055669, 3775.811209439517, 3795.2788970095594, 3835.9616403835603, 9498.95250523743, 9910.10809027191, 10082.596696132072, 10177.796444071226, 10213.693589192337, 10156.4921753912, 10168.644097456554, 10172.745681358025, 10178.389296642225, 10183.981601839756, 7327.963360183165, 7751.147373788879, 7843.411744119214, 7855.960720196363, 7859.567723775133, 4570.565796249351, 5432.7555260012605, 5457.61796674229, 5634.154876768517, 5719.971400142973, 9325.673601423918, 9435.622575096919, 9446.38598491096, 9455.479948602893, 9456.290778191526, 7758.495697779626, 8623.706444033445, 9660.033996600283, 9660.855087173839, 9681.903180968382, 6263.498920086291, 6464.256610489854, 6696.698648560924, 6844.691988860613, 6862.691178896862, 7687.730929417444, 7843.411744119328, 7951.0, 8026.0, 8045.034595848301, 7087.228192951762, 7190.06529151207, 7193.856122877409, 7245.31169538914, 7379.704811807467, 7045.2954704531185, 7058.047163632844, 7067.434605231672, 7084.539504932163, 7142.5714457132735, 6655.933440665532, 6657.667116644052, 6755.864882702417, 6764.120664313607, 6764.8985265222045, 4688.859334219979, 4739.312799644102, 4755.714657120584, 4807.567318941312, 4863.3434486343895, 4594.0, 4602.884927876696, 4717.528247175255, 4774.880627984328, 4852.053849499313], "value": 4261.71058993824, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [248.36542633571227, 298.64908732239627, 307.5033820380086, 310.28943718883755, 314.9370125974806, 4072.3891416287497, 4584.335271385651, 5205.479452054798, 5256.710880901549, 5342.771162632597, 5040.974795126029, 5145.614078944085, 5155.4586768389345, 5210.713410762407, 5219.765110570024, 3722.423024431204, 3917.0599056226515, 3922.4704664870164, 3962.841486340543, 3976.284268831609, 3384.289299247161, 3864.4273111350435, 4013.9598604013877, 4022.336314508117, 4024.605359810908, 3033.453978283908, 3519.436890097585, 3952.9802350988207, 3970.642642162205, 3981.3430783920617, 3245.3996010738088, 3949.2891279569667, 3951.6487689824507, 3958.44581758554, 3984.6015398460067, 3261.657525959764, 3261.9184520386943, 3428.7257019438384, 3490.0, 3490.790552566829, 4179.456670632799, 4577.748223847687, 4876.536729010738, 5035.84892453227, 5054.570361519239, 4524.095180963787, 4541.523140070278, 4550.544945505456, 4551.089782043571, 4596.770161491912, 4457.64338852891, 4667.172918207038, 5080.695158290514, 5258.50044245796, 5506.585916731612, 3457.3085382923264, 3811.1996480739076, 3820.8280627371837, 3826.349520581508, 3843.2313537292653, 5467.605760531039, 5612.719364031782, 5731.8567035824435, 5762.827115186551, 5775.3935836736955, 3139.4819854723714, 3732.234891847165, 3790.33669107905, 3803.0, 3812.741795207575, 3127.702868227515, 3431.7940923544666, 3868.0273060738664, 3873.8837834865, 3901.551321597996, 3886.397608370707, 4249.490061192615, 4387.3857659927635, 4496.460424749051, 4559.475660299108, 4289.785510724451, 4602.631789456836, 5239.502247286502, 5270.393904701008, 5369.033573956725, 1129.282905355089, 1162.5466068233395, 1214.611324376191, 1218.5004148299154, 1236.9814452783216, 727.7452891487936, 785.7799816051514, 838.3460900497622, 847.3644766425181, 857.7898414888392, 4155.633936511435, 4815.566599006036, 4847.509792949071, 4868.440129385166, 4950.950490495121, 4342.5874541918465, 5253.527182553512, 5286.524212820865, 5365.785368585291, 5402.270693456412, 4742.5731684148595, 5154.366171848913, 5204.851065105557, 5241.746049198805, 5245.793467502496, 2302.5608994378513, 2340.4499942513494, 2465.556199884038, 2555.3134930945484, 2821.929451763722, 4362.738235705868, 4707.646926480527, 5381.596380271495, 5581.4790020083865, 5610.887782244332, 4535.705179163344, 5113.386393632788, 5532.280803495601, 5553.139263414178, 5581.832545023656, 4835.542587050206, 4855.732934688484, 5577.079781836032, 5584.18513982949, 5589.357223919302, 4538.818447262072, 5123.7959079617, 5274.707696614354, 5330.440303768049, 5423.430539793423, 4415.889602759957, 4465.687401881837, 5105.948940510547, 5259.475451783242, 5399.516222537677, 4922.627999820134, 4978.47725988766, 5569.637973531708, 5586.497215250647, 5625.46558076982, 2972.628421447319, 3297.7856439331367, 3571.589267234301, 3573.6426357365094, 3622.6920711738744, 4647.0, 5268.0, 5327.387350454593, 5341.759620816995, 5360.276362691143, 4367.1613451256935, 5180.896382072413, 5201.633256868192, 5207.3230480038865, 5228.921566176587, 5094.617903657167, 5106.540411362995, 5211.091681239071, 5489.972550137383, 5574.9442505574425, 5154.0, 5282.841514754564, 5307.6465501296925, 5314.840554783363, 5470.53500452466, 5521.365043020104, 5564.805231816867, 5574.888502230014, 5586.301712285965, 5592.440755924375, 5143.459936706592, 5423.2407462954425, 5442.700651464207, 5443.101888188563, 5454.127339625641], "value": 3876.417415758357, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.3652644356857, 742.2948199210751, 749.9100107987042, 774.7365895595497, 823.1151512124468, 13880.930595347021, 14471.34878930448, 14496.347664355102, 14528.63678408039, 14560.199189044599, 9626.518674066303, 12751.0, 12862.163959342659, 12879.613611591643, 13418.46326146953, 629.6473974574253, 659.1233659233209, 767.1254769562703, 787.0633945604726, 810.400303775208, 803.4255507312271, 806.2783808491397, 806.4193780478047, 808.7775861638038, 810.0077405178668, 856.4133568505563, 858.2961971183632, 858.5406807358065, 859.5702148925537, 860.1699360117485, 671.781670956941, 692.2800287700785, 696.9616671083088, 698.1935864076949, 698.3958875572594, 606.8907596632604, 620.512897375558, 624.8188025472607, 627.8587317853492, 628.3151365012113, 13470.528531501448, 13490.595282141552, 13494.527691530848, 13501.98735094862, 13526.797098043537, 12510.37448127599, 12664.55674051413, 12727.93636031814, 12757.55348562796, 12776.87223127766, 10129.797404051951, 10827.350358978487, 11807.704807379798, 11933.105017123751, 12041.819372709417, 3785.9242815143543, 3856.2865869814173, 3876.8836934891997, 3929.4105884117325, 3949.2299001694682, 87804.5609771947, 90568.54715726445, 90935.54532227363, 91704.0, 92337.61493577602, 10436.2694611378, 10627.84058239127, 10676.412797296069, 10866.456677166268, 11117.722056948642, 19984.600307993758, 19985.30051448192, 19985.900070499556, 19989.300374486822, 19993.400197994084, 6333.113364129025, 6782.1861376635115, 7287.526310789782, 7611.238876112345, 7903.525788452711, 4186.0, 4350.347447882843, 4354.281543545343, 4463.531329210451, 4486.371907932892, 3328.5007248912857, 3374.6625337466057, 3436.7937923724658, 3442.1738782691973, 3444.483327500895, 632.2517890641226, 638.47956468205, 649.2922714241499, 654.1332734127301, 662.2516556291456, 566.1620801214226, 642.9196350456193, 660.5079215984026, 663.8449098568456, 670.5105273150627, 8413.789655258544, 8594.31245500371, 8836.072212417732, 8887.081899586548, 9114.544272786334, 546.7867531662655, 597.7011494252873, 641.9390157934989, 668.9565178263397, 680.5644387591847, 4336.956630433719, 4433.334999750064, 4437.822487100544, 4462.005108556809, 4501.88745281364, 88571.37143542815, 104009.47995259975, 112919.7558329615, 121056.39471802584, 121567.78432215564, 66419.66790166018, 74662.25337746662, 99729.0, 103270.93458130796, 103730.96269037364, 81072.37855242862, 81482.59258703669, 81956.59021704854, 82679.0, 83409.58295208485, 7517.624118794148, 8595.699150529701, 8615.612297446756, 8768.956155219183, 8822.2060014599, 41145.0, 43427.78286108549, 49540.75229623829, 58360.70819645875, 58708.0, 80307.59846200731, 81892.59053704693, 83327.0, 83692.0, 84364.0, 10766.300190487593, 11138.883612113672, 11184.94407527957, 11201.327920324806, 11372.993258045708, 10125.746856328356, 11241.231839165464, 11314.547418103513, 12074.154809163274, 12559.623211303675, 10068.44623545712, 11446.885531144582, 11558.971609020658, 11625.47685354178, 11800.52797888075, 10040.899591004289, 11501.252418592765, 11548.884511154782, 11764.352960586908, 12092.93953530262, 9951.0, 12536.185147965352, 12860.678483037625, 13041.804372934224, 13304.534341298007, 9285.907140928504, 9364.765880853032, 9471.337006409485, 9712.077352651488, 10959.342439453658, 9284.767880803034, 9899.65351212704, 10821.350718956888, 11084.722881927693, 11390.601328953448], "value": 7007.65524641339, "unit": "score"}], "name": "dromaeo_css", "value": 6971.8791900581955, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 113.0, 111.0, 118.0, 151.0, 111.0, 113.0, 112.0, 112.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 115.0, 113.0, 152.0, 132.0, 119.0, 113.0, 112.0, 144.0, 114.0], "value": 126.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 185.0, 182.0, 187.0, 182.0, 184.0, 184.0, 179.0, 185.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 60.0, 90.0, 62.0, 58.0, 58.0, 58.0, 83.0, 58.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 94.0, 95.0, 97.0, 102.0, 91.0, 68.0, 69.0, 92.0], "value": 87.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 91.0, 135.0, 84.0, 87.0, 83.0, 87.0, 84.0, 86.0, 84.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 93.0, 94.0, 94.0, 96.0, 91.0, 93.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 131.0, 82.0, 81.0, 83.0, 80.0, 84.0, 86.0, 86.0, 81.0], "value": 92.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 54.0, 55.0, 57.0, 55.0, 57.0, 56.0, 55.0, 56.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 73.0, 77.0, 74.0, 69.0, 70.0, 74.0, 72.0, 71.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 87.0, 86.0, 86.0, 86.0, 84.0, 86.0, 89.0, 87.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 146.0, 148.0, 162.0, 153.0, 153.0, 150.0, 146.0, 186.0, 156.0], "value": 154.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 233.0, 196.0, 175.0, 219.0, 218.0, 173.0, 183.0, 233.0, 187.0], "value": 199.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 68.0, 67.0, 65.0, 67.0, 66.0, 66.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1492.6000000000001, "alertThreshold": 2.0}]} 06:27:48 INFO - Return code: 0 06:27:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:27:48 INFO - # TBPL SUCCESS # 06:27:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:27:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:27:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:27:48 INFO - 2016-11-08 06:27:48.965 screenresolution[2359:16097] starting screenresolution argv=screenresolution get 06:27:48 INFO - 2016-11-08 06:27:48.969 screenresolution[2359:16097] Display 0: 1600x1200x32@60 06:27:48 INFO - 2016-11-08 06:27:48.975 screenresolution[2360:16100] starting screenresolution argv=screenresolution list 06:27:48 INFO - Available Modes on Display 0 06:27:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:27:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:27:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:27:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:27:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:27:49 INFO - Intel Iris: 06:27:49 INFO - Chipset Model: Intel Iris 06:27:49 INFO - Type: GPU 06:27:49 INFO - Bus: Built-In 06:27:49 INFO - VRAM (Dynamic, Max): 1536 MB 06:27:49 INFO - Vendor: Intel (0x8086) 06:27:49 INFO - Device ID: 0x0a2e 06:27:49 INFO - Revision ID: 0x0009 06:27:49 INFO - Displays: 06:27:49 INFO - Display: 06:27:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:27:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:27:49 INFO - Display Serial Number: 1600x1200 60 06:27:49 INFO - Main Display: Yes 06:27:49 INFO - Mirror: Off 06:27:49 INFO - Online: Yes 06:27:49 INFO - Rotation: Supported 06:27:49 INFO - Return code: 0 06:27:49 INFO - Running post-action listener: _resource_record_post_action 06:27:49 INFO - [mozharness: 2016-11-08 14:27:49.213402Z] Finished run-tests step (success) 06:27:49 INFO - Running post-run listener: _resource_record_post_run 06:27:49 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 286720; Write bytes: 704228864; Read time: 6; Write time: 1984 06:27:49 INFO - TinderboxPrint: CPU usage
53.5% 06:27:49 INFO - TinderboxPrint: I/O read bytes / time
286,720 / 6 06:27:49 INFO - TinderboxPrint: I/O write bytes / time
704,228,864 / 1,984 06:27:49 INFO - TinderboxPrint: CPU idle
2,257.4 (46.5%) 06:27:49 INFO - TinderboxPrint: CPU system
974.7 (20.1%) 06:27:49 INFO - TinderboxPrint: CPU user
1,625.5 (33.5%) 06:27:49 INFO - TinderboxPrint: Swap in / out
403,693,568 / 0 06:27:49 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 386056704; Write bytes: 379946496; Read time: 29488; Write time: 792 06:27:49 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 286720; Write bytes: 298702848; Read time: 6; Write time: 1128 06:27:49 INFO - Running post-run listener: _upload_blobber_files 06:27:49 INFO - Blob upload gear active. 06:27:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:27:49 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 06:27:49 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:27:49 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:27:50 INFO - (blobuploader) - INFO - Open directory for files ... 06:27:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:27:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:27:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:27:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:27:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:27:51 INFO - (blobuploader) - INFO - Done attempting. 06:27:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:27:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:27:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:27:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:27:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:27:52 INFO - (blobuploader) - INFO - Done attempting. 06:27:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:27:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:27:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:27:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:27:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:27:54 INFO - (blobuploader) - INFO - Done attempting. 06:27:54 INFO - (blobuploader) - INFO - Iteration through files over. 06:27:54 INFO - Return code: 0 06:27:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:27:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:27:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78efeea7830db19751b112f1ec475186176bbe63bdd0c1884cf11d2a8f844f07f24d632d4d375c2bad07734e148c63c6092498886bfb31f0662eb8fcc0d0b41a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e161f4bddc76a986e7184079a5b95df0f71f5885eeabdd0b436fb8864f6d59dc32e52f1bd986d2c717eba61433665bafca44495afc1496eb9df17fa29386e77", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae9425e34853ba82955b7101447cf527ac7f507b7e4e180feb641ad0fcfcc1d1b0f60083c7ef82a1f0f50f0cd69eb65aca537aba46d3b9edcf1645312450206e"} 06:27:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:27:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:27:54 INFO - Contents: 06:27:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78efeea7830db19751b112f1ec475186176bbe63bdd0c1884cf11d2a8f844f07f24d632d4d375c2bad07734e148c63c6092498886bfb31f0662eb8fcc0d0b41a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e161f4bddc76a986e7184079a5b95df0f71f5885eeabdd0b436fb8864f6d59dc32e52f1bd986d2c717eba61433665bafca44495afc1496eb9df17fa29386e77", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae9425e34853ba82955b7101447cf527ac7f507b7e4e180feb641ad0fcfcc1d1b0f60083c7ef82a1f0f50f0cd69eb65aca537aba46d3b9edcf1645312450206e"} 06:27:54 INFO - Running post-run listener: copy_logs_to_upload_dir 06:27:54 INFO - Copying logs to upload dir... 06:27:54 INFO - mkdir: /builds/slave/test/build/upload/logs 06:27:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.519133 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-08 06:27:54.693412) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-08 06:27:54.696853) ========= 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.F6RDy8uBX2/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.fKivZUfXOX/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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/Firefox/sha512/78efeea7830db19751b112f1ec475186176bbe63bdd0c1884cf11d2a8f844f07f24d632d4d375c2bad07734e148c63c6092498886bfb31f0662eb8fcc0d0b41a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e161f4bddc76a986e7184079a5b95df0f71f5885eeabdd0b436fb8864f6d59dc32e52f1bd986d2c717eba61433665bafca44495afc1496eb9df17fa29386e77", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae9425e34853ba82955b7101447cf527ac7f507b7e4e180feb641ad0fcfcc1d1b0f60083c7ef82a1f0f50f0cd69eb65aca537aba46d3b9edcf1645312450206e"} build_url:https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011467 build_url: 'https://queue.taskcluster.net/v1/task/Pkxz4_0RQpeUphHiJLkZhw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78efeea7830db19751b112f1ec475186176bbe63bdd0c1884cf11d2a8f844f07f24d632d4d375c2bad07734e148c63c6092498886bfb31f0662eb8fcc0d0b41a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e161f4bddc76a986e7184079a5b95df0f71f5885eeabdd0b436fb8864f6d59dc32e52f1bd986d2c717eba61433665bafca44495afc1496eb9df17fa29386e77", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae9425e34853ba82955b7101447cf527ac7f507b7e4e180feb641ad0fcfcc1d1b0f60083c7ef82a1f0f50f0cd69eb65aca537aba46d3b9edcf1645312450206e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-08 06:27:54.730495) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 06:27:54.730817) ========= 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.F6RDy8uBX2/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.fKivZUfXOX/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.005350 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 06:27:54.779181) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-08 06:27:54.779501) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-08 06:27:54.779811) ========= ========= Total master_lag: 0.59 =========