builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0032 starttime: 1483021675.72 results: success (0) buildid: 20161229041337 builduid: f3572429ac8a4049a90aa427f8c96371 revision: bf6b75460a2544ee06f6331cb6ed0a0da32cddf1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:55.721124) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:55.721634) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:55.722355) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:55.749701) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:55.750093) ========= 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.un1Y4AbZvl/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.EyFrwj92LG/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-29 06:27:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.2M=0.001s 2016-12-29 06:27:56 (15.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.501336 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:56.276352) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:56.276709) ========= 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.un1Y4AbZvl/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.EyFrwj92LG/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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.027744 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:56.326039) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:56.326416) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bf6b75460a2544ee06f6331cb6ed0a0da32cddf1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bf6b75460a2544ee06f6331cb6ed0a0da32cddf1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.un1Y4AbZvl/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.EyFrwj92LG/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-29 06:27:56,421 truncating revision to first 12 chars 2016-12-29 06:27:56,421 Setting DEBUG logging. 2016-12-29 06:27:56,421 attempt 1/10 2016-12-29 06:27:56,421 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bf6b75460a25?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 06:27:56,794 unpacking tar archive at: mozilla-aurora-bf6b75460a25/testing/mozharness/ program finished with exit code 0 elapsedTime=0.642739 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:56.993133) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:56.993533) ========= script_repo_revision: bf6b75460a2544ee06f6331cb6ed0a0da32cddf1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:56.994042) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:56.994354) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 06:27:57.015069) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-29 06:27:57.015428) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.un1Y4AbZvl/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.EyFrwj92LG/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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:27:57 INFO - MultiFileLogger online at 20161229 06:27:57 in /builds/slave/test 06:27:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 06:27:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:27:57 INFO - {'append_to_log': False, 06:27:57 INFO - 'base_work_dir': '/builds/slave/test', 06:27:57 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:27:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:27:57 INFO - 'branch': 'Mozilla-Aurora', 06:27:57 INFO - 'buildbot_json_path': 'buildprops.json', 06:27:57 INFO - 'config_files': ('talos/mac_config.py',), 06:27:57 INFO - 'default_actions': ('clobber', 06:27:57 INFO - 'read-buildbot-config', 06:27:57 INFO - 'download-and-extract', 06:27:57 INFO - 'populate-webroot', 06:27:57 INFO - 'create-virtualenv', 06:27:57 INFO - 'install', 06:27:57 INFO - 'run-tests'), 06:27:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:27:57 INFO - 'download_minidump_stackwalk': True, 06:27:57 INFO - 'download_symbols': 'ondemand', 06:27:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:27:57 INFO - 'tooltool.py': '/tools/tooltool.py', 06:27:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:27:57 INFO - '/tools/misc-python/virtualenv.py')}, 06:27:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:27:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:27:57 INFO - 'installer_path': 'installer.exe', 06:27:57 INFO - 'log_level': 'info', 06:27:57 INFO - 'log_name': 'talos', 06:27:57 INFO - 'log_to_console': True, 06:27:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:27:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:27:57 INFO - 'opt_config_files': (), 06:27:57 INFO - 'pip_index': False, 06:27:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:27:57 INFO - 'cmd': ('bash', 06:27:57 INFO - '-c', 06:27:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:27:57 INFO - 'enabled': True, 06:27:57 INFO - 'halt_on_failure': False, 06:27:57 INFO - 'name': 'check_screen_resolution'},), 06:27:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:27:57 INFO - 'cmd': ('bash', 06:27:57 INFO - '-c', 06:27:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:27:57 INFO - 'enabled': True, 06:27:57 INFO - 'halt_on_failure': False, 06:27:57 INFO - 'name': 'check_screen_resolution'},), 06:27:57 INFO - 'run_cmd_checks_enabled': True, 06:27:57 INFO - 'sps_profile': False, 06:27:57 INFO - 'sps_profile_interval': 0, 06:27:57 INFO - 'suite': 'dromaeojs', 06:27:57 INFO - 'system_bits': '32', 06:27:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:27:57 INFO - 'title': 't-yosemite-r7-0032', 06:27:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:27:57 INFO - 'use_talos_json': True, 06:27:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:27:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:27:57 INFO - 'work_dir': 'build'} 06:27:57 INFO - [mozharness: 2016-12-29 14:27:57.229457Z] Running clobber step. 06:27:57 INFO - Running pre-action listener: _resource_record_pre_action 06:27:57 INFO - Running main action method: clobber 06:27:57 INFO - rmtree: /builds/slave/test/build 06:27:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:27:59 INFO - Running post-action listener: _resource_record_post_action 06:27:59 INFO - [mozharness: 2016-12-29 14:27:59.734458Z] Finished clobber step (success) 06:27:59 INFO - [mozharness: 2016-12-29 14:27:59.734586Z] Running read-buildbot-config step. 06:27:59 INFO - Running pre-action listener: _resource_record_pre_action 06:27:59 INFO - Running main action method: read_buildbot_config 06:27:59 INFO - Using buildbot properties: 06:27:59 INFO - { 06:27:59 INFO - "project": "", 06:27:59 INFO - "product": "firefox", 06:27:59 INFO - "script_repo_revision": "production", 06:27:59 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 06:27:59 INFO - "repository": "", 06:27:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 06:27:59 INFO - "buildid": "20161229041337", 06:27:59 INFO - "pgo_build": "False", 06:27:59 INFO - "basedir": "/builds/slave/test", 06:27:59 INFO - "buildnumber": 23, 06:27:59 INFO - "slavename": "t-yosemite-r7-0032", 06:27:59 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 06:27:59 INFO - "platform": "yosemite_r7", 06:27:59 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 06:27:59 INFO - "branch": "mozilla-aurora", 06:27:59 INFO - "repo_path": "releases/mozilla-aurora", 06:27:59 INFO - "revision": "bf6b75460a2544ee06f6331cb6ed0a0da32cddf1", 06:27:59 INFO - "stage_platform": "macosx64", 06:27:59 INFO - "builduid": "f3572429ac8a4049a90aa427f8c96371", 06:27:59 INFO - "slavebuilddir": "test" 06:27:59 INFO - } 06:27:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 06:27:59 INFO - Running post-action listener: _resource_record_post_action 06:27:59 INFO - [mozharness: 2016-12-29 14:27:59.737962Z] Finished read-buildbot-config step (success) 06:27:59 INFO - [mozharness: 2016-12-29 14:27:59.738078Z] Running download-and-extract step. 06:27:59 INFO - Running pre-action listener: _resource_record_pre_action 06:27:59 INFO - Running main action method: download_and_extract 06:27:59 INFO - mkdir: /builds/slave/test/build/tests 06:27:59 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:27:59 INFO - trying https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 06:27:59 INFO - Downloading https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 06:27:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 06:28:00 INFO - Downloaded 1199 bytes. 06:28:00 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 06:28:00 INFO - Using the following test package requirements: 06:28:00 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 06:28:00 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:28:00 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 06:28:00 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:28:00 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 06:28:00 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:28:00 INFO - u'jsshell-mac.zip'], 06:28:00 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:28:00 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 06:28:00 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 06:28:00 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:28:00 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 06:28:00 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:28:00 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 06:28:00 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:28:00 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 06:28:00 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 06:28:00 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 06:28:00 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 06:28:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 06:28:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 06:28:00 INFO - Fetch https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 06:28:01 INFO - Http code: 200 06:28:01 INFO - via: 1.1 0576b942ae9f4fc9c0b62b0736e9bfd6.cloudfront.net (CloudFront) 06:28:01 INFO - x-amz-cf-id: 6fa3PJF17VP9S68nkimr_ctNMuTw8g1StJIurWHVywaKcBHauEr9Ow== 06:28:01 INFO - x-amz-version-id: 39oTqBORJmIwa1Ki1Bw6SepkbrPEalW3 06:28:01 INFO - Expected file size: 16268460 06:28:01 INFO - Obtained file size: 16268460 06:28:08 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 06:28:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 06:28:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 06:28:08 INFO - Fetch https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 06:28:09 INFO - Http code: 200 06:28:09 INFO - via: 1.1 990c804118e679d7ea0f4d3ca3cc4534.cloudfront.net (CloudFront) 06:28:09 INFO - x-amz-cf-id: t5iLNzGPfG1osLbA0vTebUHGzbjo2TIhhC8zYKARXcnoAxsePNLm8w== 06:28:09 INFO - x-amz-version-id: 39oTqBORJmIwa1Ki1Bw6SepkbrPEalW3 06:28:09 INFO - Expected file size: 16268460 06:28:09 INFO - Obtained file size: 16268460 06:28:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 06:28:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 06:28:15 INFO - Fetch https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 06:28:16 INFO - Http code: 200 06:28:16 INFO - via: 1.1 30bb04916f91d64c600e15c15000042d.cloudfront.net (CloudFront) 06:28:16 INFO - x-amz-cf-id: oK7DEPSnX6ndR7lSVTZMs8V4NL3KOksdBvwwWaTHQ2xii4z9DeWp3Q== 06:28:16 INFO - x-amz-version-id: G4y6QENueRlfoodpRlA_B22EPdCyhL0O 06:28:16 INFO - Expected file size: 14409038 06:28:16 INFO - Obtained file size: 14409038 06:28:16 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:28:16 INFO - trying https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 06:28:16 INFO - Downloading https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 06:28:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 06:28:19 INFO - Downloaded 109827338 bytes. 06:28:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 06:28:19 INFO - mkdir: /builds/slave/test/properties 06:28:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:28:19 INFO - Writing to file /builds/slave/test/properties/build_url 06:28:19 INFO - Contents: 06:28:19 INFO - build_url:https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 06:28:20 INFO - Running post-action listener: _resource_record_post_action 06:28:20 INFO - Running post-action listener: set_extra_try_arguments 06:28:20 INFO - [mozharness: 2016-12-29 14:28:20.567435Z] Finished download-and-extract step (success) 06:28:20 INFO - [mozharness: 2016-12-29 14:28:20.567560Z] Running populate-webroot step. 06:28:20 INFO - Running pre-action listener: _resource_record_pre_action 06:28:20 INFO - Running main action method: populate_webroot 06:28:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:28:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:28:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:28:20 INFO - '--tppagecycles', 06:28:20 INFO - '7'], 06:28:20 INFO - 'tests': ['tsvgm']}}, 06:28:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:28:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:28:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:28:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:28:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:28:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:28:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:20 INFO - 'talos_options': ['--disable-e10s'], 06:28:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:28:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:28:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:20 INFO - 'talos_options': ['--disable-e10s'], 06:28:20 INFO - 'tests': ['damp', 'tps']}, 06:28:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:20 INFO - 'tests': ['damp', 'tps']}, 06:28:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:28:20 INFO - 'tests': ['dromaeo_dom']}, 06:28:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:28:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:28:20 INFO - 'tests': ['basic_compositor_video']}, 06:28:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:28:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:28:20 INFO - 'tests': ['a11yr', 06:28:20 INFO - 'ts_paint', 06:28:20 INFO - 'tpaint', 06:28:20 INFO - 'sessionrestore', 06:28:20 INFO - 'sessionrestore_no_auto_restore', 06:28:20 INFO - 'tabpaint']}, 06:28:20 INFO - 'other-e10s': {'tests': ['a11yr', 06:28:20 INFO - 'ts_paint', 06:28:20 INFO - 'tpaint', 06:28:20 INFO - 'sessionrestore', 06:28:20 INFO - 'sessionrestore_no_auto_restore', 06:28:20 INFO - 'tabpaint']}, 06:28:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:28:20 INFO - 'ts_paint', 06:28:20 INFO - 'tpaint', 06:28:20 INFO - 'sessionrestore', 06:28:20 INFO - 'sessionrestore_no_auto_restore', 06:28:20 INFO - 'tabpaint']}, 06:28:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:28:20 INFO - 'ts_paint', 06:28:20 INFO - 'tpaint', 06:28:20 INFO - 'sessionrestore', 06:28:20 INFO - 'sessionrestore_no_auto_restore', 06:28:20 INFO - 'tabpaint']}, 06:28:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:28:20 INFO - 'tests': ['a11yr', 06:28:20 INFO - 'ts_paint', 06:28:20 INFO - 'tpaint', 06:28:20 INFO - 'sessionrestore', 06:28:20 INFO - 'sessionrestore_no_auto_restore', 06:28:20 INFO - 'tabpaint']}, 06:28:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:28:20 INFO - 'tests': ['a11yr', 06:28:20 INFO - 'ts_paint', 06:28:20 INFO - 'tpaint', 06:28:20 INFO - 'sessionrestore', 06:28:20 INFO - 'sessionrestore_no_auto_restore', 06:28:20 INFO - 'tabpaint']}, 06:28:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:28:20 INFO - 'tests': ['tsvgx', 06:28:20 INFO - 'tsvgr_opacity', 06:28:20 INFO - 'tart', 06:28:20 INFO - 'tscrollx', 06:28:20 INFO - 'cart']}, 06:28:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:28:20 INFO - 'tsvgr_opacity', 06:28:20 INFO - 'tart', 06:28:20 INFO - 'tscrollx', 06:28:20 INFO - 'cart']}, 06:28:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:20 INFO - 'talos_options': ['--disable-e10s'], 06:28:20 INFO - 'tests': ['tp5o']}, 06:28:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:20 INFO - 'tests': ['tp5o']}, 06:28:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:20 INFO - 'talos_options': ['--disable-e10s', 06:28:20 INFO - '--xperf_path', 06:28:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:28:20 INFO - 'tests': ['tp5n']}, 06:28:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:28:20 INFO - 'talos_options': ['--xperf_path', 06:28:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:28:20 INFO - 'tests': ['tp5n']}}, 06:28:20 INFO - 'talos.zip': {'path': '', 06:28:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:28:20 INFO - Running post-action listener: _resource_record_post_action 06:28:20 INFO - [mozharness: 2016-12-29 14:28:20.587034Z] Finished populate-webroot step (success) 06:28:20 INFO - [mozharness: 2016-12-29 14:28:20.587159Z] Running create-virtualenv step. 06:28:20 INFO - Running pre-action listener: _resource_record_pre_action 06:28:20 INFO - Running main action method: create_virtualenv 06:28:20 INFO - Creating virtualenv /builds/slave/test/build/venv 06:28:20 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:28:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:28:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:28:20 INFO - Using real prefix '/tools/python27' 06:28:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:28:21 INFO - Installing distribute.............................................................................................................................................................................................done. 06:28:24 INFO - Installing pip.................done. 06:28:24 INFO - Return code: 0 06:28:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:28:24 INFO - Reading from file tmpfile_stdout 06:28:24 INFO - Output received: 06:28:24 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:28:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:28:24 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:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:24 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:28:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:24 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 0x10a63b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc143ca8d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44fd30>, '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 0x10a2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a447b20>, '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.un1Y4AbZvl/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.EyFrwj92LG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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:28:24 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:28:24 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:28:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.un1Y4AbZvl/Render', 06:28:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:24 INFO - 'HOME': '/Users/cltbld', 06:28:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:24 INFO - 'LOGNAME': 'cltbld', 06:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:24 INFO - 'MOZ_NO_REMOTE': '1', 06:28:24 INFO - 'NO_EM_RESTART': '1', 06:28:24 INFO - 'PAGER': '/bin/cat', 06:28:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:24 INFO - 'PWD': '/builds/slave/test', 06:28:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:24 INFO - 'SHELL': '/bin/bash', 06:28:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EyFrwj92LG/Listeners', 06:28:24 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 06:28:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:24 INFO - 'USER': 'cltbld', 06:28:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:24 INFO - 'XPC_FLAGS': '0x0', 06:28:24 INFO - 'XPC_SERVICE_NAME': '0', 06:28:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:24 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:28:24 INFO - Cleaning up... 06:28:24 INFO - Return code: 0 06:28:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:28:24 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:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:24 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:28:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:24 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 0x10a63b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc143ca8d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44fd30>, '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 0x10a2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a447b20>, '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.un1Y4AbZvl/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.EyFrwj92LG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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:28:24 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:28:24 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:28:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.un1Y4AbZvl/Render', 06:28:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:24 INFO - 'HOME': '/Users/cltbld', 06:28:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:24 INFO - 'LOGNAME': 'cltbld', 06:28:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:24 INFO - 'MOZ_NO_REMOTE': '1', 06:28:24 INFO - 'NO_EM_RESTART': '1', 06:28:24 INFO - 'PAGER': '/bin/cat', 06:28:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:24 INFO - 'PWD': '/builds/slave/test', 06:28:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:24 INFO - 'SHELL': '/bin/bash', 06:28:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EyFrwj92LG/Listeners', 06:28:24 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 06:28:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:24 INFO - 'USER': 'cltbld', 06:28:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:24 INFO - 'XPC_FLAGS': '0x0', 06:28:24 INFO - 'XPC_SERVICE_NAME': '0', 06:28:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:25 INFO - Downloading/unpacking psutil>=3.1.1 06:28:25 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:28:25 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:28:25 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:28:25 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:28:25 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:28:25 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:28:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:28:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:28:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:28:29 INFO - Installing collected packages: psutil 06:28:29 INFO - Running setup.py install for psutil 06:28:29 INFO - building 'psutil._psutil_osx' extension 06:28:29 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:28:29 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:28:29 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:28:29 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:28:30 INFO - building 'psutil._psutil_posix' extension 06:28:30 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:28:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:28:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:28:30 INFO - ^ 06:28:30 INFO - 1 warning generated. 06:28:30 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:28:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:28:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:28:30 INFO - Successfully installed psutil 06:28:30 INFO - Cleaning up... 06:28:30 INFO - Return code: 0 06:28:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:28:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a63b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc143ca8d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44fd30>, '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 0x10a2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a447b20>, '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.un1Y4AbZvl/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.EyFrwj92LG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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:28:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:28:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:28:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.un1Y4AbZvl/Render', 06:28:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:30 INFO - 'HOME': '/Users/cltbld', 06:28:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:30 INFO - 'LOGNAME': 'cltbld', 06:28:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:30 INFO - 'MOZ_NO_REMOTE': '1', 06:28:30 INFO - 'NO_EM_RESTART': '1', 06:28:30 INFO - 'PAGER': '/bin/cat', 06:28:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:30 INFO - 'PWD': '/builds/slave/test', 06:28:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:30 INFO - 'SHELL': '/bin/bash', 06:28:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EyFrwj92LG/Listeners', 06:28:30 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 06:28:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:30 INFO - 'USER': 'cltbld', 06:28:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:30 INFO - 'XPC_FLAGS': '0x0', 06:28:30 INFO - 'XPC_SERVICE_NAME': '0', 06:28:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:28:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:28:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:28:33 INFO - Installing collected packages: mozsystemmonitor 06:28:33 INFO - Running setup.py install for mozsystemmonitor 06:28:33 INFO - Successfully installed mozsystemmonitor 06:28:33 INFO - Cleaning up... 06:28:33 INFO - Return code: 0 06:28:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:28: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:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28: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:28:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a63b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc143ca8d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44fd30>, '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 0x10a2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a447b20>, '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.un1Y4AbZvl/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.EyFrwj92LG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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:28: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==2.5.1'] in /builds/slave/test/build 06:28: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==2.5.1 06:28:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.un1Y4AbZvl/Render', 06:28:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:33 INFO - 'HOME': '/Users/cltbld', 06:28:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:33 INFO - 'LOGNAME': 'cltbld', 06:28:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:33 INFO - 'MOZ_NO_REMOTE': '1', 06:28:33 INFO - 'NO_EM_RESTART': '1', 06:28:33 INFO - 'PAGER': '/bin/cat', 06:28:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:33 INFO - 'PWD': '/builds/slave/test', 06:28:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:33 INFO - 'SHELL': '/bin/bash', 06:28:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EyFrwj92LG/Listeners', 06:28:33 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 06:28:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:33 INFO - 'USER': 'cltbld', 06:28:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:33 INFO - 'XPC_FLAGS': '0x0', 06:28:33 INFO - 'XPC_SERVICE_NAME': '0', 06:28:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:33 INFO - Downloading/unpacking jsonschema==2.5.1 06:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:28:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:28:36 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:28:36 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:28:36 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:28:36 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:28:36 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:28:36 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:28:36 INFO - Downloading functools32-3.2.3-2.tar.gz 06:28:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:28:36 INFO - warning: no files found matching '*.txt' 06:28:36 INFO - no previously-included directories found matching 'build' 06:28:36 INFO - no previously-included directories found matching 'dist' 06:28:36 INFO - no previously-included directories found matching '.git*' 06:28:36 INFO - Installing collected packages: jsonschema, functools32 06:28:37 INFO - Running setup.py install for functools32 06:28:37 INFO - warning: no files found matching '*.txt' 06:28:37 INFO - no previously-included directories found matching 'build' 06:28:37 INFO - no previously-included directories found matching 'dist' 06:28:37 INFO - no previously-included directories found matching '.git*' 06:28:37 INFO - Successfully installed jsonschema functools32 06:28:37 INFO - Cleaning up... 06:28:37 INFO - Return code: 0 06:28:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:28:37 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:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:37 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:28:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:37 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 0x10a63b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc143ca8d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44fd30>, '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 0x10a2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a447b20>, '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.un1Y4AbZvl/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.EyFrwj92LG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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:28:37 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:28:37 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:28:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.un1Y4AbZvl/Render', 06:28:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:37 INFO - 'HOME': '/Users/cltbld', 06:28:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:37 INFO - 'LOGNAME': 'cltbld', 06:28:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:37 INFO - 'MOZ_NO_REMOTE': '1', 06:28:37 INFO - 'NO_EM_RESTART': '1', 06:28:37 INFO - 'PAGER': '/bin/cat', 06:28:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:37 INFO - 'PWD': '/builds/slave/test', 06:28:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:37 INFO - 'SHELL': '/bin/bash', 06:28:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EyFrwj92LG/Listeners', 06:28:37 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 06:28:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:37 INFO - 'USER': 'cltbld', 06:28:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:37 INFO - 'XPC_FLAGS': '0x0', 06:28:37 INFO - 'XPC_SERVICE_NAME': '0', 06:28:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:28:37 INFO - Cleaning up... 06:28:37 INFO - Return code: 0 06:28:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:28:37 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:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:37 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:28:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:37 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 0x10a63b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc143ca8d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44fd30>, '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 0x10a2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a447b20>, '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.un1Y4AbZvl/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.EyFrwj92LG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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:28:37 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:28:37 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:28:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.un1Y4AbZvl/Render', 06:28:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:37 INFO - 'HOME': '/Users/cltbld', 06:28:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:37 INFO - 'LOGNAME': 'cltbld', 06:28:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:37 INFO - 'MOZ_NO_REMOTE': '1', 06:28:37 INFO - 'NO_EM_RESTART': '1', 06:28:37 INFO - 'PAGER': '/bin/cat', 06:28:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:37 INFO - 'PWD': '/builds/slave/test', 06:28:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:37 INFO - 'SHELL': '/bin/bash', 06:28:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EyFrwj92LG/Listeners', 06:28:37 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 06:28:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:37 INFO - 'USER': 'cltbld', 06:28:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:37 INFO - 'XPC_FLAGS': '0x0', 06:28:37 INFO - 'XPC_SERVICE_NAME': '0', 06:28:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:37 INFO - Downloading/unpacking blobuploader==1.2.4 06:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:40 INFO - Downloading blobuploader-1.2.4.tar.gz 06:28:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:28:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:28:40 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:28:40 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:28:40 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:28:40 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:28:40 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:28:40 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:28:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:28:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:41 INFO - Downloading docopt-0.6.1.tar.gz 06:28:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:28:41 INFO - Installing collected packages: blobuploader, requests, docopt 06:28:41 INFO - Running setup.py install for blobuploader 06:28:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:28:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:28:41 INFO - Running setup.py install for requests 06:28:42 INFO - Running setup.py install for docopt 06:28:42 INFO - Successfully installed blobuploader requests docopt 06:28:42 INFO - Cleaning up... 06:28:42 INFO - Return code: 0 06:28:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:28:42 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:28:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:42 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:28:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:42 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 0x10a63b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc143ca8d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44fd30>, '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 0x10a2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a447b20>, '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.un1Y4AbZvl/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.EyFrwj92LG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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:28:42 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:28:42 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:28:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.un1Y4AbZvl/Render', 06:28:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:42 INFO - 'HOME': '/Users/cltbld', 06:28:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:42 INFO - 'LOGNAME': 'cltbld', 06:28:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:42 INFO - 'MOZ_NO_REMOTE': '1', 06:28:42 INFO - 'NO_EM_RESTART': '1', 06:28:42 INFO - 'PAGER': '/bin/cat', 06:28:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:42 INFO - 'PWD': '/builds/slave/test', 06:28:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:42 INFO - 'SHELL': '/bin/bash', 06:28:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EyFrwj92LG/Listeners', 06:28:42 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 06:28:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:42 INFO - 'USER': 'cltbld', 06:28:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:42 INFO - 'XPC_FLAGS': '0x0', 06:28:42 INFO - 'XPC_SERVICE_NAME': '0', 06:28:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:28:42 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-ZilNsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:28:42 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-yq0c6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:28:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:28:42 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-Cs8_e4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:28:43 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-xd6vgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:28:43 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-ve1oyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:28:43 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-VhDVe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:28:43 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-JA9XfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:28:43 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-xpvtwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:28:43 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-CMwjOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:28:43 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-asMuMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:28:43 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-Oo6cSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:28:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:28:43 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-1n4Dwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:28:44 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-xNP4Hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:28:44 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-RhiuQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:28:44 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-wIrSSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:28:44 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-QVNZRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:28:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:28:44 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-gBtvSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:28:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:28:44 INFO - Running setup.py install for manifestparser 06:28:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:28:44 INFO - Running setup.py install for mozcrash 06:28:44 INFO - Running setup.py install for mozdebug 06:28:44 INFO - Running setup.py install for mozdevice 06:28:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:28:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:28:45 INFO - Running setup.py install for mozfile 06:28:45 INFO - Running setup.py install for mozhttpd 06:28:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:28:45 INFO - Running setup.py install for mozinfo 06:28:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:28:45 INFO - Running setup.py install for mozInstall 06:28:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:28:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:28:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:28:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:28:45 INFO - Running setup.py install for mozleak 06:28:45 INFO - Running setup.py install for mozlog 06:28:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:28:46 INFO - Running setup.py install for moznetwork 06:28:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:28:46 INFO - Running setup.py install for mozprocess 06:28:46 INFO - Running setup.py install for mozprofile 06:28:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:28:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:28:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:28:46 INFO - Running setup.py install for mozrunner 06:28:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:28:46 INFO - Running setup.py install for mozscreenshot 06:28:46 INFO - Running setup.py install for moztest 06:28:46 INFO - Running setup.py install for mozversion 06:28:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:28:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:28:47 INFO - Cleaning up... 06:28:47 INFO - Return code: 0 06:28:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:47 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 0x10a63b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc143ca8d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44fd30>, '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 0x10a2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a447b20>, '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.un1Y4AbZvl/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.EyFrwj92LG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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:28:47 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:28:47 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:28:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.un1Y4AbZvl/Render', 06:28:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:47 INFO - 'HOME': '/Users/cltbld', 06:28:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:47 INFO - 'LOGNAME': 'cltbld', 06:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:47 INFO - 'MOZ_NO_REMOTE': '1', 06:28:47 INFO - 'NO_EM_RESTART': '1', 06:28:47 INFO - 'PAGER': '/bin/cat', 06:28:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:47 INFO - 'PWD': '/builds/slave/test', 06:28:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:47 INFO - 'SHELL': '/bin/bash', 06:28:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EyFrwj92LG/Listeners', 06:28:47 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 06:28:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:47 INFO - 'USER': 'cltbld', 06:28:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:47 INFO - 'XPC_FLAGS': '0x0', 06:28:47 INFO - 'XPC_SERVICE_NAME': '0', 06:28:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:28:47 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-wM7zX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:28:47 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:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:28:47 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-2XcuEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:28:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:28:47 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-36AWKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:28:47 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:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:28:47 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-m2z85e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:28:47 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:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:28:47 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-utibPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:28:47 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:28:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:28:47 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-mnqH0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:28:48 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:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:28:48 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-WLNhjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:28:48 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:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:28:48 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-_taJgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:28:48 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:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:28:48 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-QIrfJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:28:48 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:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:28:48 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-WLYyEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:28:48 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:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:28:48 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-TBwUz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:28:48 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:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:28:48 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-BI0us0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:28:48 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:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:28:48 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-n1bV9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:28:48 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:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:28:48 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-kWLra7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:28:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:28:48 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-Q1igmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:28:48 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:28:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:28:48 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-NLunV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:28:49 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:28:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:28:49 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-GzKzZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:28:49 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:28:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:28:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:28:49 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:28:49 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:28:49 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:28:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:28:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:28:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:28:51 INFO - Downloading blessings-1.6.tar.gz 06:28:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:28:51 INFO - Installing collected packages: blessings 06:28:51 INFO - Running setup.py install for blessings 06:28:52 INFO - Successfully installed blessings 06:28:52 INFO - Cleaning up... 06:28:52 INFO - Return code: 0 06:28:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:28:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:28:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:28:52 INFO - Reading from file tmpfile_stdout 06:28:52 INFO - Current package versions: 06:28:52 INFO - blessings == 1.6 06:28:52 INFO - blobuploader == 1.2.4 06:28:52 INFO - docopt == 0.6.1 06:28:52 INFO - functools32 == 3.2.3-2 06:28:52 INFO - jsonschema == 2.5.1 06:28:52 INFO - manifestparser == 1.1 06:28:52 INFO - mozInstall == 1.12 06:28:52 INFO - mozcrash == 1.0 06:28:52 INFO - mozdebug == 0.1 06:28:52 INFO - mozdevice == 0.48 06:28:52 INFO - mozfile == 1.2 06:28:52 INFO - mozhttpd == 0.7 06:28:52 INFO - mozinfo == 0.9 06:28:52 INFO - mozleak == 0.1 06:28:52 INFO - mozlog == 3.4 06:28:52 INFO - moznetwork == 0.27 06:28:52 INFO - mozprocess == 0.23 06:28:52 INFO - mozprofile == 0.28 06:28:52 INFO - mozrunner == 6.13 06:28:52 INFO - mozscreenshot == 0.1 06:28:52 INFO - mozsystemmonitor == 0.3 06:28:52 INFO - moztest == 0.7 06:28:52 INFO - mozversion == 1.4 06:28:52 INFO - psutil == 3.1.1 06:28:52 INFO - requests == 1.2.3 06:28:52 INFO - wsgiref == 0.1.2 06:28:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:52 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 0x10a63b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc143ca8d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44fd30>, '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 0x10a2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a447b20>, '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.un1Y4AbZvl/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.EyFrwj92LG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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:28:52 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:28:52 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:28:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.un1Y4AbZvl/Render', 06:28:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:52 INFO - 'HOME': '/Users/cltbld', 06:28:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:52 INFO - 'LOGNAME': 'cltbld', 06:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:52 INFO - 'MOZ_NO_REMOTE': '1', 06:28:52 INFO - 'NO_EM_RESTART': '1', 06:28:52 INFO - 'PAGER': '/bin/cat', 06:28:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:52 INFO - 'PWD': '/builds/slave/test', 06:28:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:52 INFO - 'SHELL': '/bin/bash', 06:28:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EyFrwj92LG/Listeners', 06:28:52 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 06:28:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:52 INFO - 'USER': 'cltbld', 06:28:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:52 INFO - 'XPC_FLAGS': '0x0', 06:28:52 INFO - 'XPC_SERVICE_NAME': '0', 06:28:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:52 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:28:52 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:28:52 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:28:52 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:28:52 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:28:52 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:28:52 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:28:52 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:28:52 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:28:52 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:28:52 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:28:52 INFO - Cleaning up... 06:28:52 INFO - Return code: 0 06:28:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:28:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:28:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:28:52 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 0x10a63b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc143ca8d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a44fd30>, '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 0x10a2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a447b20>, '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.un1Y4AbZvl/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.EyFrwj92LG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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:28:52 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:28:52 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:28:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.un1Y4AbZvl/Render', 06:28:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:28:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:28:52 INFO - 'HOME': '/Users/cltbld', 06:28:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:28:52 INFO - 'LOGNAME': 'cltbld', 06:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:28:52 INFO - 'MOZ_NO_REMOTE': '1', 06:28:52 INFO - 'NO_EM_RESTART': '1', 06:28:52 INFO - 'PAGER': '/bin/cat', 06:28:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:28:52 INFO - 'PWD': '/builds/slave/test', 06:28:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:28:52 INFO - 'SHELL': '/bin/bash', 06:28:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EyFrwj92LG/Listeners', 06:28:52 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 06:28:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:28:52 INFO - 'USER': 'cltbld', 06:28:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:28:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:28:52 INFO - 'XPC_FLAGS': '0x0', 06:28:52 INFO - 'XPC_SERVICE_NAME': '0', 06:28:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:28:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:28:52 INFO - Cleaning up... 06:28:53 INFO - Return code: 0 06:28:53 INFO - Running post-action listener: _resource_record_post_action 06:28:53 INFO - Running post-action listener: _start_resource_monitoring 06:28:53 INFO - Starting resource monitoring. 06:28:53 INFO - [mozharness: 2016-12-29 14:28:53.027615Z] Finished create-virtualenv step (success) 06:28:53 INFO - [mozharness: 2016-12-29 14:28:53.028546Z] Running install step. 06:28:53 INFO - Running pre-action listener: _resource_record_pre_action 06:28:53 INFO - Running main action method: install 06:28:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:28:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:28:53 INFO - Reading from file tmpfile_stdout 06:28:53 INFO - Detecting whether we're running mozinstall >=1.0... 06:28:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:28:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:28:53 INFO - Reading from file tmpfile_stdout 06:28:53 INFO - Output received: 06:28:53 INFO - Usage: mozinstall [options] installer 06:28:53 INFO - Options: 06:28:53 INFO - -h, --help show this help message and exit 06:28:53 INFO - -d DEST, --destination=DEST 06:28:53 INFO - Directory to install application into. [default: 06:28:53 INFO - "/builds/slave/test"] 06:28:53 INFO - --app=APP Application being installed. [default: firefox] 06:28:53 INFO - mkdir: /builds/slave/test/build/application 06:28:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:28:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 06:29:24 INFO - Reading from file tmpfile_stdout 06:29:24 INFO - Output received: 06:29:24 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 06:29:24 INFO - Running post-action listener: _resource_record_post_action 06:29:24 INFO - [mozharness: 2016-12-29 14:29:24.420653Z] Finished install step (success) 06:29:24 INFO - [mozharness: 2016-12-29 14:29:24.420826Z] Running run-tests step. 06:29:24 INFO - Running pre-action listener: _resource_record_pre_action 06:29:24 INFO - Running main action method: run_tests 06:29:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:29:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:29:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:29:24 INFO - 2016-12-29 06:29:24.435 screenresolution[1636:9116] starting screenresolution argv=screenresolution get 06:29:24 INFO - 2016-12-29 06:29:24.437 screenresolution[1636:9116] Display 0: 1600x1200x32@60 06:29:24 INFO - 2016-12-29 06:29:24.443 screenresolution[1637:9119] starting screenresolution argv=screenresolution list 06:29:24 INFO - Available Modes on Display 0 06:29:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:29:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:29:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:29:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:29:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:29:24 INFO - Intel Iris: 06:29:24 INFO - Chipset Model: Intel Iris 06:29:24 INFO - Type: GPU 06:29:24 INFO - Bus: Built-In 06:29:24 INFO - VRAM (Dynamic, Max): 1536 MB 06:29:24 INFO - Vendor: Intel (0x8086) 06:29:24 INFO - Device ID: 0x0a2e 06:29:24 INFO - Revision ID: 0x0009 06:29:24 INFO - Displays: 06:29:24 INFO - Display: 06:29:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:29:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:29:24 INFO - Display Serial Number: 1600x1200 60 06:29:24 INFO - Main Display: Yes 06:29:24 INFO - Mirror: Off 06:29:24 INFO - Online: Yes 06:29:24 INFO - Rotation: Supported 06:29:24 INFO - Return code: 0 06:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:29:24 INFO - Python 2.7.3 06:29:24 INFO - Return code: 0 06:29:24 INFO - grabbing minidump binary from tooltool 06:29:24 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:29:24 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 0x10a44fd30>, '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 0x10a2b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a447b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:29:24 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:29:24 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:29:24 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:29:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:29:24 INFO - Return code: 0 06:29:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:29:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:29:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:29:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:29:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:29:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0032', '--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:29:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0032 --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:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.un1Y4AbZvl/Render', 06:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:29:24 INFO - 'HOME': '/Users/cltbld', 06:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:29:24 INFO - 'LOGNAME': 'cltbld', 06:29:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:29:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:29:24 INFO - 'MOZ_NO_REMOTE': '1', 06:29:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:29:24 INFO - 'NO_EM_RESTART': '1', 06:29:24 INFO - 'PAGER': '/bin/cat', 06:29:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:29:24 INFO - 'PWD': '/builds/slave/test', 06:29:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:29:24 INFO - 'SHELL': '/bin/bash', 06:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EyFrwj92LG/Listeners', 06:29:24 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 06:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:29:24 INFO - 'USER': 'cltbld', 06:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:29:24 INFO - 'XPC_FLAGS': '0x0', 06:29:24 INFO - 'XPC_SERVICE_NAME': '0', 06:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:29:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0032', '--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:29:25 INFO - mozversion application_buildid: 20161229041337 06:29:25 INFO - mozversion application_changeset: bf6b75460a2544ee06f6331cb6ed0a0da32cddf1 06:29:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:29:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:29:25 INFO - mozversion application_name: Firefox 06:29:25 INFO - mozversion application_remotingname: firefox-dev 06:29:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:29:25 INFO - mozversion application_vendor: Mozilla 06:29:25 INFO - mozversion application_version: 52.0a2 06:29:25 INFO - mozversion platform_buildid: 20161229041337 06:29:25 INFO - mozversion platform_changeset: bf6b75460a2544ee06f6331cb6ed0a0da32cddf1 06:29:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:29:25 INFO - mozversion platform_version: 52.0a2 06:29:25 INFO - using testdate: 1483021765 06:29:25 INFO - actual date: 1483021765 06:29:25 INFO - starting webserver on 'localhost:49238' 06:29:25 INFO - SUITE-START | Running 2 tests 06:29:25 INFO - TEST-START | dromaeo_css 06:29:25 INFO - operating with platform_type : mac_ 06:29:25 INFO - Initialising browser for dromaeo_css test... 06:29:25 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmprQq2hu/profile http://localhost:49238/getInfo.html) 06:29:28 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 06:29:28 INFO - PROCESS | 1647 | colorDepth:24 06:29:28 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 06:29:28 INFO - PROCESS | 1647 | __metrics 06:29:28 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:29:28 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:29:29 INFO - TEST-INFO | 1647: exit 0 06:29:29 INFO - Browser initialized. 06:29:29 INFO - Running cycle 1/1 for dromaeo_css test... 06:29:29 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmprQq2hu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:29:41 INFO - PROCESS | 1650 | RSS: Main: 222531584 06:29:41 INFO - PROCESS | 1650 | 06:30:32 INFO - PROCESS | 1650 | 1483021832690 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:32:44 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49238/tests/dromaeo/cssquery-ext.html) 06:32:47 INFO - PROCESS | 1650 | RSS: Main: 457416704 06:32:47 INFO - PROCESS | 1650 | 06:35:50 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-ext.html (next: http://localhost:49238/tests/dromaeo/cssquery-jquery.html) 06:35:51 INFO - PROCESS | 1650 | RSS: Main: 348995584 06:35:51 INFO - PROCESS | 1650 | 06:38:53 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49238/tests/dromaeo/cssquery-mootools.html) 06:38:54 INFO - PROCESS | 1650 | RSS: Main: 321003520 06:38:54 INFO - PROCESS | 1650 | 06:41:56 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49238/tests/dromaeo/cssquery-prototype.html) 06:41:57 INFO - PROCESS | 1650 | RSS: Main: 326189056 06:41:57 INFO - PROCESS | 1650 | 06:45:00 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49238/tests/dromaeo/cssquery-yui.html) 06:45:01 INFO - PROCESS | 1650 | RSS: Main: 312860672 06:45:01 INFO - PROCESS | 1650 | 06:48:04 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-yui.html (next: http://localhost:49238/tests/dromaeo/cssquery-dojo.html) 06:48:04 INFO - PROCESS | 1650 | RSS: Main: 474288128 06:48:04 INFO - PROCESS | 1650 | 06:48:04 INFO - PROCESS | 1650 | __start_tp_report 06:48:04 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 06:48:04 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 06:48:04 INFO - PROCESS | 1650 | |i|pagename|runs| 06:48:04 INFO - PROCESS | 1650 | |0;dojo.html;2646.206138158552;3271.623763267224;3285.638579756228;3290.3583801158784;3329.8834540791077;5518.724063796809;5540.889182216353;5574.331080270368;5597.832065038056;5604.075327570951;5502.862428439291;5567.721613919308;5595.664260144395;5601.439856014396;5628.549716022719;5031.27046578246;5049;5065.037642847848;5155.381354237496;5222.112240919034;5027.4469808321055;5039.949600503985;5057.03916255912;5103;5187.740612969355;5080.923786143192;5117.948820511784;5140.7172605506685;5159.406668233164;5164.509371609691;5072.518110779489;5155.432902380735;5165.276861239429;5175;5192.1173400522;5003.374578177728;5103.336566246401;5106.540411362995;5114.539691427753;5172.27588137661;4185.623293903533;5441.5102640762525;5475.151351540518;5475.561955043587;5502.422245664187;5977.671228082454;5996.880062398771;5997.1304160896425;6015.639061656271;6024.548158888101;6152.015677491625;6184.319724830265;6206.34833342497;6208.2239720034995;6214;5548.223248745179;5573.526250268773;5582.665040097526;5634.943650563442;5653.57598180134;6291.433770960635;6316;6326.2092238470195;6336.8415789604705;6342;5987.491063259672;6001.609895356875;6002.849928751816;6040.697965101727;6059.121427393004;5845.88308233833;5911.142884281755;6131.476938540911;6159.568830181934;6175.567710260329;5837.241158649434;5986.49114825236;6032.668203248776;6066.241719785027;6116.214037636738;6116.510679145657;6119.685352982159;6128.60597900623;6132.581522417141;6144.8771024579255;4546.499885012646;4602.194615942263;4789.137955168034;4833.009233107275;4836.492168322346;4300.483941926988;4319.049809042006;4330.39374487572;4346.543612920661;4348.086901750605;5620.91568626471;5622.297212848394;5626.381098079208;5654.321481422256;5685.431456854281;6161.260648722182;6214.4407003369915;6216.378362163748;6218.098375735583;6227.1282020517165;5480.972595136999;5489.451054894558;5546;5570.303712035996;5632.408597097327;5297.629165958423;5308.840734777963;5321.308229930161;5349.5720342372515;5355.277037599952;6224.844378890473;6290.182276304235;6310.242770867525;6333.050042493479;6337.17616709834;6024.909626355696;6046.244219472566;6206.348333424925;6207.286162091417;6209.161763162096;6122.032243765608;6233.501319894488;6262.185915831095;6272.2159730033745;6315.368463153648;6038.184845045863;6041.395860413923;6059.182010428712;6063.235154796902;6093;6092.512598991982;6158.168647232746;6162.044883043135;6170.136180934583;6188.535859810442;5811.709414529172;5846.093855452412;5875.177475153397;5889.322727886177;5916.112583112395;5884.7940322088525;5902.5277977762535;5906.232189815297;5908.881822363614;5911.704414779329;5245.763940622604;5296.894062118659;5789.623674461147;5793.362730099601;5800.24596802413;5834.15404766315;5929.822105336847;5940.405959404198;5943.791967281124;5944.881102377842;5768.192453056491;5777.7977770777825;5780.190773291826;5785.652860828363;5786.826395208151 06:48:04 INFO - PROCESS | 1650 | |1;ext.html;475.5577313098818;490.458043861533;514.7966553211481;517.0279873837186;522.3705434950883;30026.399472010573;31424.842875785645;31449.528257076134;31492.212694682617;38629;39397;41688.791556042255;41911;42018.7899060505;42121.57878421215;8071.838563228732;9250.491222982733;9412.247020238365;9437.575309111073;9446.952765236165;8335.66657333709;8533.615987280587;9336.206422454099;9451.33840631156;9463.195628371597;11117.166212534052;11319.037881780017;11387.975082242596;11499.482523286471;11505.654830355103;11099;11217.99038086568;11221.719457013558;11246.268992515461;11256.268342557749;7149.49953503255;8523.53120578368;10888.891111088868;11129.833052504218;11220.943895280552;30088.548671769942;34321.828390858136;35505.82247088774;35814.82092589547;35920.46119308213;3199.40810949975;3239.983800081008;3348.2131699050706;3780.4707340972286;3789.7347185697013;28691.71308286911;28720;29628.8518557408;30169.094927152222;30979.380412391627;3065.2336915771057;3152.8226685966883;3168.4138434389483;3193.664665210166;3195.536647186168;480773;484758;485595;486107;487296;60827.39172608306;61147.08279375724;61235.69382153061;61257.69371153116;61342.38657613456;61495.69252153711;61510.69244653748;61657;61669.691651541456;61805.07292390618;15939;16029.438969636007;16413.58966025859;16427.753583696252;16467.094309812837;12020.0383969284;12523.874761252453;12567.685807854878;12584.11911166228;12592.181508201938;15945.44190953311;16377.0173789573;16401.917990409973;18105.808865804538;18481.1683474244;6954.721811127498;7263.3514550687605;7619.390448764086;7626.656800443993;7643.121041080347;695.1553862057624;915.6428992692855;963.21016766251;1031.2471895516314;1090.983635245472;28892.844286228737;29286.41427171487;29579.408411831642;30073.69926300753;30247.395052099277;852.8720691896265;962.0764066496102;1011.8988101189822;1035.1201478743008;1035.233926894108;14794.482193123189;15380.30788614515;15438.845611543966;15447.382104715685;15782.526524204291;577587;654141;657329;658261;660172;56758.71620641871;57096.71451642715;57169;57202.7139864298;57315.42684573184;1169.561414469574;1213.6298428979069;1219.0917766264367;1286.215408600734;1328.7342531493546;28259.576106358832;29941.850290749277;30273.24316892007;30702.539461908535;30892.845535773078;52232;55342;55406;55459;55805.44194558002;585024;588882;590111;592080;594613;26525.469490610467;26941.86529067342;27317.590236146076;27906.860465698355;39575.80212098921;45219.547804521535;45441.77279113583;45764;45791.77104114458;45874.77062614666;37473.06317342088;37663.43504847484;38255.80872095733;39523.40714889336;39704.20591588094;37004.81497592495;37507.87476375713;38427.039324017125;38742;38858.80570597129;29342.70657293485;36566.817165914;37064.07339816526;37616.43575346427;37770.62229377671;29125;30582.694173058873;33371;33393.38923151603;33777.49333760045;24060.39849003789;25076.48929149957;25358.74641253564;25571.872140639178;25581.872090639426 06:48:04 INFO - PROCESS | 1650 | |2;jquery.html;354.0917546493246;371.2167326940158;374.4439507331612;384.9037740564859;400.57739085265035;14446.71106577869;14792.482263120794;15107.75712858655;19777;19989.300374486895;18282.177302021406;18740.344087956924;18985;19150.712739308885;19182.80817191831;5609.411011843759;5930.822075337725;5937.910931336033;5943.3462319144855;5948.910766338508;5330.440303768107;5540.529055030327;5835.708214589254;5903.055511118222;5906.497947674453;5696.231008813798;5851;5855.765769369242;5875.17747515346;5888.793892213774;5730.971345143268;5767.971160144214;5793.073108302693;5819.67991760453;5849.7660093596205;4900.215965445512;4968.807486203321;5093.949060509384;5101.770420331094;5102.693838369672;24026.279211623503;24159.516809663884;24295.392615184763;24361;24446.26661200167;2190.233418303597;2230.7434645015705;2275.385645875621;2520.5463016656995;2624.4357463145516;14444.133351998913;14825.48110816127;14970.47603333878;15658.373665053385;15814.288357023961;1755.3505203074817;1788.284686125547;1807.1054827860162;1836.1461920207169;1836.7061270196705;87481;105603.47198264036;105650;108529;114815.7693870874;9669.081437263449;9679.274054445945;9758.804823903482;9976.600935962626;10785.220438627695;13419.396127174301;13463.596092117252;13466.663333416744;13471.663208419868;13484.39320230592;8266.958665206756;8400.815787895192;8436.156384361515;8543.743687689379;8557;8408.117147699526;8426.747197584082;8439.620217090245;8573.914260857311;8605.31157507398;5430.647007944471;6349.111124442582;6467.482601391877;6476.121924641801;6493.285738568754;1803.1615298886081;1828.1499102917207;1832.221305944968;1838.6874231380698;1875.4186202277338;894.552723638181;896.6547879066476;919.7424721078108;945.163530275711;1033.6020632056711;20327.186912523335;20926.581468370852;20946;20975.580488390147;21116.366509004754;790.6402586823096;855.1576696953496;885.685581618529;928.967486137982;932.7434955387187;10588.059105431403;10933.617323393803;10949.959753823374;11008.339499630049;11019.118470522339;574773;599480;603402;616589;618859.4342169742;101561.25039118166;104271;105611.4719426398;107007.46496267624;109823.45088274508;970.6408628807386;980.632262901412;1009.0111690543162;1009.2985375164191;1015.3653966271081;18093.004884731465;18378.356757513422;18427.53931151732;18510.81489185091;18617.255309787997;48040.519594803605;48636.78408039686;48910;49334.75332623314;49402.75298623484;51639.74180129075;64151.35848641454;67042.66478667576;67062.66468667626;67909.64180716457;6833.658317084027;7162.391196748126;7358.300961408658;7444.73943411978;8026.879596805936;12051.57819476314;12481.375931203585;12608.74782504363;12857.871421285667;13099.738005240033;10921.126309895031;10997.395143266876;11315.773684526428;11329.320240785579;11548.884511155116;10694;10865.837012444978;11041.227114102257;11120;11302.152338574475;11160.386178760245;11651.242669226473;11675.88324116782;11704.180707350404;11781.998530125036;7261.201267860425;7309.671064801991;7403.335165841944;7617.5048621839405;7620;9466.763330916781;10810.405427701551;11066.833997490205;11258.7748245033;11454.370009649549 06:48:04 INFO - PROCESS | 1650 | |3;mootools.html;1266.411118829744;1423.5373503611327;1450.4778279819266;1587.8491543303383;1814.8003719590843;7303.45224108192;7598.278163574459;8079.959600201998;8353.791155221123;8373.995120585538;8115.837683246332;8214.712485063024;8224.547649879254;8224.712135075273;8242.670293188265;2252.5044490212126;2296.5062511560013;2303.1363238785457;2305.619572770491;2307.1694190091553;2276.590213761522;2315.536892621474;2333.031791806407;2334.9299521014395;2337.532493501298;2271.3299576624936;2284.189112864934;2309.8498597591188;2315.016118149788;2315.270689732729;1863.7577114975102;2220.766819483947;2223.644216925284;2227.9554408911813;2236;2242.730872295335;2267.784560466753;2273.3407311879537;2275.397019789757;2279.943001424978;8038.638261278198;8226.753197404028;8285.585720714;8286.502809831369;8305.584720763998;4967.652264341497;5091.083604951107;5150.098732721796;5150.124478838607;5165.070287348276;3268.346330733863;3394.2193295542165;3407.5399821024093;3456.1705190754037;3458.2219000724886;999.715081201854;1004.7488127968008;1025.661531694539;1037.9896201037968;1040.0223789637716;10624.150067994542;10653.360798352123;10725.981031801966;10746.301490403177;10755.3009054412;4142.751434913915;4286.485621725413;4348.978255108705;4373.55672628032;4375.6280716138845;8605.13948605147;8619.569021548898;8630.611622477003;8637.913620863837;8657.350698697497;4980.302757613937;4999.0501804657;4999.10016197088;5001.174806156943;5013.097642424399;3975.5030621172355;4202.243596152722;4328.415663885398;4352.325389564622;4607.631389488833;3514.525539052195;3537.02731748766;3591.3894637911617;3617.2946275476556;3625.093726568358;1076.1498416251075;1080.864891888514;1082.226208261097;1091.6070214722693;1091.683411810582;752.8757754970369;832.8500869843486;874.6676263020012;886.233408102;886.5035580075179;4584.724916505021;4699.671023028423;5308.389535203501;5476.617671312992;5565.888682226333;410.8582539024032;432.44214962698396;438.48259054315577;440.42964361152383;446.01875873079354;3334.949975750366;3785.848566057327;3810.3141434541494;3822.48396466479;3876.8061596920043;9704.660336888317;9987.900120998842;10097.899021009696;10121.746956326151;10125.493725313705;8196.877046844302;9823.950880245553;9836.163926066365;10068;10069.597216111273;7697.961510192413;7793.727219547289;7812.68749250035;7826.295633393022;7830.4518683691595;5192.065428222805;5383.434739352311;5516.117421212587;5566.610337276352;5601.327840658984;8450.281726053354;9282.071792820663;9292.95353523228;9412;9414.811703766023;9206.355555111079;9417.01121382245;9430.669926552539;9548.18840398574;9560.426374417557;6602.108715323562;6686.398224159654;6687.598744075371;6690.264070952094;6945.236024037251;7869.527828330319;7872.881906771288;7887.763367098996;7941.682332706627;7966.681332746625;6946.340097690711;7032.191298000591;7074.858502830018;7094.11323584552;7180.210176880643;6912.170539535288;6968.4076853465995;6996.545224560387;7004.089468369079;7053.858922821618;6510.641914694548;6571;6587.440067594308;6591.802245932629;6650.833729156616;4805.9038819224115;4815.686980346266;4850.6362022847725;4889.975550122226;4942.654014218971;4635.907281854276;4807.903841923072;4822.590079843252;4953.876153096057;5039.823606173766 06:48:04 INFO - PROCESS | 1650 | |4;prototype.html;300.82552119770537;305.2749719416386;308.76996637505056;313.1373067199864;314.18782447373536;5018.322526458927;5072.543471087602;5154.634021984177;5159.961815083045;5184.507471790183;4210.3894935234375;4918.96701692645;5078.289039534468;5083.389993200812;5133.666311689738;3485.2158264390564;3776.2440465183686;3953.940690889639;3970.3250447423934;3987.8604248851298;3279.999600121962;3683.5027271318295;3945.881623551298;3982.9004274893214;3992.7205095643317;3876.6898648108227;3881.6506514413695;3892.3188442022674;3903.921921561567;3925.3326934421075;3681.2821499807546;3877.4571559981623;3884.7280690351686;3897.7661340319673;3902.5316961964463;3383.1034775784437;3396.677315655009;3403.766421899206;3426.4860270959307;3455.568053993251;4692.36653051837;4913.189323761575;4976.377952755905;5050.646454748169;5088.338515992934;3908.335582950905;4284.057507348379;4431.202383570988;4436.268015777425;4452.376667266585;5307.867303317409;5342.385625653061;5342.866428339284;5375.516203541661;5382;3124.8125112493094;3742.2141350316465;3753.9436908446396;3759.774413535197;3762.435634654796;4946.629002824802;5616.567775217375;5622.578306626979;5642.266505354278;5693.77224911003;3184.7133757961897;3433.4163192257374;3544.113971507123;3752.724073814916;3775.905602359963;3386.779859309137;3819.7135214858995;3820.6436714966344;3844.1355942367927;3857.7106716996336;3746.463949780577;4395.604395604411;4395.846145384896;4426.933595996063;4437.378766972626;5374.312843578211;5380.704061276668;5415.6479828811;5429.448159365092;5484.698341591251;1021.7854250607307;1092.9125520107543;1169.3101070368523;1172.407933993345;1189.8155785853207;781.5154604145466;843.4939036578004;844.6822956188366;846.957652117404;847.5423271433357;4085.9182816343505;4732.982408782128;4782.450018247877;4785.617150627942;4787.593054590398;3980.980095099506;4870.464248932614;5220.008198442284;5239;5243.134882744304;4721.9055618888115;4757.3577567028005;4764.976175119103;4791.520847915181;4805.37530121089;2393.3059412770103;2422.7819496245424;2480.6651102101346;2578.793696504275;2723.5233834079354;4828.61371090319;4931.534232883559;4972.975135124301;5144.897102058013;5370.570354371641;4531.932021019687;5222.321098257202;5271.841844744664;5277.393099793496;5281.841544753664;5138.409082955508;5337;5348.443285215366;5377.193420986883;5395.05586522357;4293.720908140961;4450.577195166454;4902.259697807227;5128.333291680145;5131;4043.4230650047093;4383.824647014211;5071.594272458268;5081.923771143362;5103.974480127576;4967.224909069206;5321.946780532146;5342.9198562022375;5363.410024897336;5406.540444062299;2891.739743423018;3071.677473865212;3523.0311664292526;3536.4872093546805;3542.6280240575397;4901.754912254301;5100.770465329143;5136.152534831711;5184.988927159318;5195.960807838372;4464.665150113689;4804.399450068741;4998.4501704813165;4999.750012499288;5025.045241404122;4290.849820256276;4638.397008388888;4988.981666092107;5002.424721157114;5029.5221953914315;4587.77061146948;4899.412070551414;5016.172331565251;5195.92206116901;5218.739063046908;5012.749362531786;5286.682799032071;5418.945810541844;5432.918506222489;5474.3704473986;5175.560077393313;5261.131913234273;5266.868328291824;5268.078086334799;5272.550048736628 06:48:04 INFO - PROCESS | 1650 | |5;yui.html;661.5270081891448;679.8177849104376;717.8420747435565;768.5196752029981;779.8713212319967;13744.725105497897;14230.288485575706;14235.288235588205;14286.214258215794;15039.323230454629;13134.343282835867;13466.192028478274;13466.797998030037;13468.528601498949;13479.191248525096;638.3042483692774;823.8722997935315;833.2167762303416;837.6146972392706;838.4843321357382;659.3834764495189;666.9499787515956;797.5932274539969;824.2664029014182;828.8508068547659;874.54960695242;878.4465786554462;879.9516026618534;880.8766772651802;881.6032785246632;871.8387098386787;873.2402809555664;873.7204094689638;876.0757400942003;877.8814859993882;499.08351928594374;513.8843760153973;627.5293529852611;646.9842347514405;651;14006.71986560263;14144;14158.433662653584;14160.937929655214;14161.433542658282;12751.681207969783;12884.677883052906;12972.610821675366;12985.662580735463;13104.737905241842;11601.883981160248;11909.097712475332;11939.880601193963;11958;11962.324323788895;4283.571642835723;4297.269464191095;4325.4593175853015;4331.220380331539;4472.016156445577;84256.57871710663;85077;85287;86228.56885715594;87285.56357218236;9868.21054315653;9906.702798916043;10093.747656308504;10395.532201050819;10452.843207351896;17619.91190044067;18211.725179237324;19123.521911952033;19207.8079219206;19338.613227735368;7278.344948954619;7318.853622927511;7499.137599176055;7510.248975102446;7577.659005344773;4095.97952010244;4127.979360103241;4175.707700460999;4181.519125300569;4350.1734670412525;3257.674232576771;3347.497875318673;3359.798412095282;3360.24394511233;3360.31113621707;624.7376102037065;640.0431355124026;642.4795915308615;649.6297110646981;656.3469347998677;532.8880935003615;590.391896346755;608.9360617135226;613.6716856481761;621.8227805075622;8194.959025204837;8337.458065225741;8544.231019208177;8629.525376104375;8848.46909185451;578.5718568259458;582.9912551311734;585.7305639405836;603.276068717532;609.4788955443121;3991.221711766236;4053.756774593534;4059.573744756817;4082.3264161413035;4196.748195108304;103677;104960.47519762353;109752.4512377449;111830.44084779687;114213.4289328548;62197.689011554656;62394.632375733265;81053.59473202596;82213;93669.5316523413;77880;83394.16605833985;84928.57535712281;85682.14317856742;86371;9189.356745027784;9415.95292023563;9425.481598512148;9632.518374081128;9698;51179;54988;55964.6008849782;55999;57383.56541086506;70888;71148.28851711417;75278.24721752931;77752;79360.01599960047;11713.941430292794;11859.762804743685;12140.514379425078;12297.938510307391;12362.38188090574;13250.602481925558;13590;14015.475066560817;14767.92616036913;14771.113733176044;13018.414171362121;13055.804162937753;13121.803172952605;13555.728885422433;13584.79622805639;12500.24998500093;12612.117151799286;12807.61577152687;12904.612861614167;13330.803453286;11838.822417663914;12379;12852.228866268053;12860.164089334163;12866.67833304175;8838.381313308008;10579.36523808574;10864;11044.668659940215;11374.601888933848;10177.236707246839;10979.004552928558;11190.272632278877;11226.66320010401;11238 06:48:04 INFO - PROCESS | 1650 | __end_tp_report 06:48:04 INFO - PROCESS | 1650 | __start_cc_report 06:48:04 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,3086 06:48:04 INFO - PROCESS | 1650 | __end_cc_report 06:48:04 INFO - PROCESS | 1650 | __startTimestamp1483022884471__endTimestamp 06:48:04 INFO - PROCESS | 1650 | 06:48:04 INFO - PROCESS | 1650 | ------- Summary: start ------- 06:48:04 INFO - PROCESS | 1650 | Number of tests: 6 06:48:04 INFO - PROCESS | 1650 | 06:48:04 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5610.05 Median:5789.62 stddev:663.49 (11.5%) stddev-sans-first:623.46 06:48:04 INFO - PROCESS | 1650 | Values: 2646.2 3271.6 3285.6 3290.4 3329.9 5518.7 5540.9 5574.3 5597.8 5604.1 5502.9 5567.7 5595.7 5601.4 5628.5 5031.3 5049.0 5065.0 5155.4 5222.1 5027.4 5039.9 5057.0 5103.0 5187.7 5080.9 5117.9 5140.7 5159.4 5164.5 5072.5 5155.4 5165.3 5175.0 5192.1 5003.4 5103.3 5106.5 5114.5 5172.3 4185.6 5441.5 5475.2 5475.6 5502.4 5977.7 5996.9 5997.1 6015.6 6024.5 6152.0 6184.3 6206.3 6208.2 6214.0 5548.2 5573.5 5582.7 5634.9 5653.6 6291.4 6316.0 6326.2 6336.8 6342.0 5987.5 6001.6 6002.8 6040.7 6059.1 5845.9 5911.1 6131.5 6159.6 6175.6 5837.2 5986.5 6032.7 6066.2 6116.2 6116.5 6119.7 6128.6 6132.6 6144.9 4546.5 4602.2 4789.1 4833.0 4836.5 4300.5 4319.0 4330.4 4346.5 4348.1 5620.9 5622.3 5626.4 5654.3 5685.4 6161.3 6214.4 6216.4 6218.1 6227.1 5481.0 5489.5 5546.0 5570.3 5632.4 5297.6 5308.8 5321.3 5349.6 5355.3 6224.8 6290.2 6310.2 6333.1 6337.2 6024.9 6046.2 6206.3 6207.3 6209.2 6122.0 6233.5 6262.2 6272.2 6315.4 6038.2 6041.4 6059.2 6063.2 6093.0 6092.5 6158.2 6162.0 6170.1 6188.5 5811.7 5846.1 5875.2 5889.3 5916.1 5884.8 5902.5 5906.2 5908.9 5911.7 5245.8 5296.9 5789.6 5793.4 5800.2 5834.2 5929.8 5940.4 5943.8 5944.9 5768.2 5777.8 5780.2 5785.7 5786.8 06:48:04 INFO - PROCESS | 1650 | 06:48:04 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:70084.48 Median:36785.82 stddev:154086.99 (418.9%) stddev-sans-first:154430.61 06:48:04 INFO - PROCESS | 1650 | Values: 475.6 490.5 514.8 517.0 522.4 30026.4 31424.8 31449.5 31492.2 38629.0 39397.0 41688.8 41911.0 42018.8 42121.6 8071.8 9250.5 9412.2 9437.6 9447.0 8335.7 8533.6 9336.2 9451.3 9463.2 11117.2 11319.0 11388.0 11499.5 11505.7 11099.0 11218.0 11221.7 11246.3 11256.3 7149.5 8523.5 10888.9 11129.8 11220.9 30088.5 34321.8 35505.8 35814.8 35920.5 3199.4 3240.0 3348.2 3780.5 3789.7 28691.7 28720.0 29628.9 30169.1 30979.4 3065.2 3152.8 3168.4 3193.7 3195.5 480773.0 484758.0 485595.0 486107.0 487296.0 60827.4 61147.1 61235.7 61257.7 61342.4 61495.7 61510.7 61657.0 61669.7 61805.1 15939.0 16029.4 16413.6 16427.8 16467.1 12020.0 12523.9 12567.7 12584.1 12592.2 15945.4 16377.0 16401.9 18105.8 18481.2 6954.7 7263.4 7619.4 7626.7 7643.1 695.2 915.6 963.2 1031.2 1091.0 28892.8 29286.4 29579.4 30073.7 30247.4 852.9 962.1 1011.9 1035.1 1035.2 14794.5 15380.3 15438.8 15447.4 15782.5 577587.0 654141.0 657329.0 658261.0 660172.0 56758.7 57096.7 57169.0 57202.7 57315.4 1169.6 1213.6 1219.1 1286.2 1328.7 28259.6 29941.9 30273.2 30702.5 30892.8 52232.0 55342.0 55406.0 55459.0 55805.4 585024.0 588882.0 590111.0 592080.0 594613.0 26525.5 26941.9 27317.6 27906.9 39575.8 45219.5 45441.8 45764.0 45791.8 45874.8 37473.1 37663.4 38255.8 39523.4 39704.2 37004.8 37507.9 38427.0 38742.0 38858.8 29342.7 36566.8 37064.1 37616.4 37770.6 29125.0 30582.7 33371.0 33393.4 33777.5 24060.4 25076.5 25358.7 25571.9 25581.9 06:48:04 INFO - PROCESS | 1650 | 06:48:04 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:33513.29 Median:13483.41 stddev:99629.46 (738.9%) stddev-sans-first:99877.83 06:48:04 INFO - PROCESS | 1650 | Values: 354.1 371.2 374.4 384.9 400.6 14446.7 14792.5 15107.8 19777.0 19989.3 18282.2 18740.3 18985.0 19150.7 19182.8 5609.4 5930.8 5937.9 5943.3 5948.9 5330.4 5540.5 5835.7 5903.1 5906.5 5696.2 5851.0 5855.8 5875.2 5888.8 5731.0 5768.0 5793.1 5819.7 5849.8 4900.2 4968.8 5093.9 5101.8 5102.7 24026.3 24159.5 24295.4 24361.0 24446.3 2190.2 2230.7 2275.4 2520.5 2624.4 14444.1 14825.5 14970.5 15658.4 15814.3 1755.4 1788.3 1807.1 1836.1 1836.7 87481.0 105603.5 105650.0 108529.0 114815.8 9669.1 9679.3 9758.8 9976.6 10785.2 13419.4 13463.6 13466.7 13471.7 13484.4 8267.0 8400.8 8436.2 8543.7 8557.0 8408.1 8426.7 8439.6 8573.9 8605.3 5430.6 6349.1 6467.5 6476.1 6493.3 1803.2 1828.1 1832.2 1838.7 1875.4 894.6 896.7 919.7 945.2 1033.6 20327.2 20926.6 20946.0 20975.6 21116.4 790.6 855.2 885.7 929.0 932.7 10588.1 10933.6 10950.0 11008.3 11019.1 574773.0 599480.0 603402.0 616589.0 618859.4 101561.3 104271.0 105611.5 107007.5 109823.5 970.6 980.6 1009.0 1009.3 1015.4 18093.0 18378.4 18427.5 18510.8 18617.3 48040.5 48636.8 48910.0 49334.8 49402.8 51639.7 64151.4 67042.7 67062.7 67909.6 6833.7 7162.4 7358.3 7444.7 8026.9 12051.6 12481.4 12608.7 12857.9 13099.7 10921.1 10997.4 11315.8 11329.3 11548.9 10694.0 10865.8 11041.2 11120.0 11302.2 11160.4 11651.2 11675.9 11704.2 11782.0 7261.2 7309.7 7403.3 7617.5 7620.0 9466.8 10810.4 11066.8 11258.8 11454.4 06:48:04 INFO - PROCESS | 1650 | 06:48:04 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5302.66 Median:4973.98 stddev:2954.46 (59.4%) stddev-sans-first:2947.17 06:48:04 INFO - PROCESS | 1650 | Values: 1266.4 1423.5 1450.5 1587.8 1814.8 7303.5 7598.3 8080.0 8353.8 8374.0 8115.8 8214.7 8224.5 8224.7 8242.7 2252.5 2296.5 2303.1 2305.6 2307.2 2276.6 2315.5 2333.0 2334.9 2337.5 2271.3 2284.2 2309.8 2315.0 2315.3 1863.8 2220.8 2223.6 2228.0 2236.0 2242.7 2267.8 2273.3 2275.4 2279.9 8038.6 8226.8 8285.6 8286.5 8305.6 4967.7 5091.1 5150.1 5150.1 5165.1 3268.3 3394.2 3407.5 3456.2 3458.2 999.7 1004.7 1025.7 1038.0 1040.0 10624.2 10653.4 10726.0 10746.3 10755.3 4142.8 4286.5 4349.0 4373.6 4375.6 8605.1 8619.6 8630.6 8637.9 8657.4 4980.3 4999.1 4999.1 5001.2 5013.1 3975.5 4202.2 4328.4 4352.3 4607.6 3514.5 3537.0 3591.4 3617.3 3625.1 1076.1 1080.9 1082.2 1091.6 1091.7 752.9 832.9 874.7 886.2 886.5 4584.7 4699.7 5308.4 5476.6 5565.9 410.9 432.4 438.5 440.4 446.0 3334.9 3785.8 3810.3 3822.5 3876.8 9704.7 9987.9 10097.9 10121.7 10125.5 8196.9 9824.0 9836.2 10068.0 10069.6 7698.0 7793.7 7812.7 7826.3 7830.5 5192.1 5383.4 5516.1 5566.6 5601.3 8450.3 9282.1 9293.0 9412.0 9414.8 9206.4 9417.0 9430.7 9548.2 9560.4 6602.1 6686.4 6687.6 6690.3 6945.2 7869.5 7872.9 7887.8 7941.7 7966.7 6946.3 7032.2 7074.9 7094.1 7180.2 6912.2 6968.4 6996.5 7004.1 7053.9 6510.6 6571.0 6587.4 6591.8 6650.8 4805.9 4815.7 4850.6 4890.0 4942.7 4635.9 4807.9 4822.6 4953.9 5039.8 06:48:04 INFO - PROCESS | 1650 | 06:48:04 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4235.99 Median:4797.96 stddev:1293.37 (27.0%) stddev-sans-first:1262.83 06:48:04 INFO - PROCESS | 1650 | Values: 300.8 305.3 308.8 313.1 314.2 5018.3 5072.5 5154.6 5160.0 5184.5 4210.4 4919.0 5078.3 5083.4 5133.7 3485.2 3776.2 3953.9 3970.3 3987.9 3280.0 3683.5 3945.9 3982.9 3992.7 3876.7 3881.7 3892.3 3903.9 3925.3 3681.3 3877.5 3884.7 3897.8 3902.5 3383.1 3396.7 3403.8 3426.5 3455.6 4692.4 4913.2 4976.4 5050.6 5088.3 3908.3 4284.1 4431.2 4436.3 4452.4 5307.9 5342.4 5342.9 5375.5 5382.0 3124.8 3742.2 3753.9 3759.8 3762.4 4946.6 5616.6 5622.6 5642.3 5693.8 3184.7 3433.4 3544.1 3752.7 3775.9 3386.8 3819.7 3820.6 3844.1 3857.7 3746.5 4395.6 4395.8 4426.9 4437.4 5374.3 5380.7 5415.6 5429.4 5484.7 1021.8 1092.9 1169.3 1172.4 1189.8 781.5 843.5 844.7 847.0 847.5 4085.9 4733.0 4782.5 4785.6 4787.6 3981.0 4870.5 5220.0 5239.0 5243.1 4721.9 4757.4 4765.0 4791.5 4805.4 2393.3 2422.8 2480.7 2578.8 2723.5 4828.6 4931.5 4973.0 5144.9 5370.6 4531.9 5222.3 5271.8 5277.4 5281.8 5138.4 5337.0 5348.4 5377.2 5395.1 4293.7 4450.6 4902.3 5128.3 5131.0 4043.4 4383.8 5071.6 5081.9 5104.0 4967.2 5321.9 5342.9 5363.4 5406.5 2891.7 3071.7 3523.0 3536.5 3542.6 4901.8 5100.8 5136.2 5185.0 5196.0 4464.7 4804.4 4998.5 4999.8 5025.0 4290.8 4638.4 4989.0 5002.4 5029.5 4587.8 4899.4 5016.2 5195.9 5218.7 5012.7 5286.7 5418.9 5432.9 5474.4 5175.6 5261.1 5266.9 5268.1 5272.6 06:48:04 INFO - PROCESS | 1650 | 06:48:04 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:19835.28 Median:4411.09 stddev:28651.77 (649.5%) stddev-sans-first:28695.98 06:48:04 INFO - PROCESS | 1650 | Values: 661.5 679.8 717.8 768.5 779.9 13744.7 14230.3 14235.3 14286.2 15039.3 13134.3 13466.2 13466.8 13468.5 13479.2 638.3 823.9 833.2 837.6 838.5 659.4 666.9 797.6 824.3 828.9 874.5 878.4 880.0 880.9 881.6 871.8 873.2 873.7 876.1 877.9 499.1 513.9 627.5 647.0 651.0 14006.7 14144.0 14158.4 14160.9 14161.4 12751.7 12884.7 12972.6 12985.7 13104.7 11601.9 11909.1 11939.9 11958.0 11962.3 4283.6 4297.3 4325.5 4331.2 4472.0 84256.6 85077.0 85287.0 86228.6 87285.6 9868.2 9906.7 10093.7 10395.5 10452.8 17619.9 18211.7 19123.5 19207.8 19338.6 7278.3 7318.9 7499.1 7510.2 7577.7 4096.0 4128.0 4175.7 4181.5 4350.2 3257.7 3347.5 3359.8 3360.2 3360.3 624.7 640.0 642.5 649.6 656.3 532.9 590.4 608.9 613.7 621.8 8195.0 8337.5 8544.2 8629.5 8848.5 578.6 583.0 585.7 603.3 609.5 3991.2 4053.8 4059.6 4082.3 4196.7 103677.0 104960.5 109752.5 111830.4 114213.4 62197.7 62394.6 81053.6 82213.0 93669.5 77880.0 83394.2 84928.6 85682.1 86371.0 9189.4 9416.0 9425.5 9632.5 9698.0 51179.0 54988.0 55964.6 55999.0 57383.6 70888.0 71148.3 75278.2 77752.0 79360.0 11713.9 11859.8 12140.5 12297.9 12362.4 13250.6 13590.0 14015.5 14767.9 14771.1 13018.4 13055.8 13121.8 13555.7 13584.8 12500.2 12612.1 12807.6 12904.6 13330.8 11838.8 12379.0 12852.2 12860.2 12866.7 8838.4 10579.4 10864.0 11044.7 11374.6 10177.2 10979.0 11190.3 11226.7 11238.0 06:48:04 INFO - PROCESS | 1650 | -------- Summary: end -------- 06:48:04 INFO - PROCESS | 1650 | 06:48:05 INFO - TEST-INFO | 1650: exit 0 06:48:05 INFO - TEST-OK | dromaeo_css | took 1120112ms 06:48:05 INFO - TEST-START | kraken 06:48:05 INFO - operating with platform_type : mac_ 06:48:05 INFO - Initialising browser for kraken test... 06:48:05 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpuDeEsN/profile http://localhost:49238/getInfo.html) 06:48:07 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 06:48:07 INFO - PROCESS | 1657 | colorDepth:24 06:48:07 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 06:48:07 INFO - PROCESS | 1657 | __metrics 06:48:08 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:48:08 INFO - PROCESS | 1657 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:48:08 INFO - TEST-INFO | 1657: exit 0 06:48:08 INFO - Browser initialized. 06:48:08 INFO - Running cycle 1/1 for kraken test... 06:48:08 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpuDeEsN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:48:21 INFO - PROCESS | 1658 | RSS: Main: 222953472 06:48:21 INFO - PROCESS | 1658 | 06:48:23 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection) 06:48:24 INFO - PROCESS | 1658 | RSS: Main: 272453632 06:48:24 INFO - PROCESS | 1658 | 06:48:27 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-dft) 06:48:27 INFO - PROCESS | 1658 | RSS: Main: 283062272 06:48:27 INFO - PROCESS | 1658 | 06:48:31 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-fft) 06:48:31 INFO - PROCESS | 1658 | RSS: Main: 381214720 06:48:31 INFO - PROCESS | 1658 | 06:48:33 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator) 06:48:34 INFO - PROCESS | 1658 | RSS: Main: 282726400 06:48:34 INFO - PROCESS | 1658 | 06:48:36 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:48:37 INFO - PROCESS | 1658 | RSS: Main: 284639232 06:48:37 INFO - PROCESS | 1658 | 06:48:39 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom) 06:48:40 INFO - PROCESS | 1658 | RSS: Main: 325353472 06:48:40 INFO - PROCESS | 1658 | 06:48:43 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate) 06:48:44 INFO - PROCESS | 1658 | RSS: Main: 327004160 06:48:44 INFO - PROCESS | 1658 | 06:48:47 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial) 06:48:47 INFO - PROCESS | 1658 | RSS: Main: 323117056 06:48:47 INFO - PROCESS | 1658 | 06:48:49 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:48:49 INFO - PROCESS | 1658 | RSS: Main: 300326912 06:48:49 INFO - PROCESS | 1658 | 06:48:51 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:48:52 INFO - PROCESS | 1658 | RSS: Main: 301637632 06:48:52 INFO - PROCESS | 1658 | 06:48:54 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:48:55 INFO - PROCESS | 1658 | RSS: Main: 302530560 06:48:55 INFO - PROCESS | 1658 | 06:48:58 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:48:59 INFO - PROCESS | 1658 | RSS: Main: 321474560 06:48:59 INFO - PROCESS | 1658 | 06:49:01 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:49:02 INFO - PROCESS | 1658 | RSS: Main: 315039744 06:49:02 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49238/tests/kraken/driver.html?testName=ai-astar) 06:49:04 INFO - PROCESS | 1658 | RSS: Main: 359542784 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | __start_tp_report 06:49:04 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 06:49:04 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 06:49:04 INFO - PROCESS | 1658 | |i|pagename|runs| 06:49:04 INFO - PROCESS | 1658 | |0;ai-astar;116;114;114;112;115;114;114;114;114;121 06:49:04 INFO - PROCESS | 1658 | |1;audio-beat-detection;145;110;108;143;110;105;111;109;139;108 06:49:04 INFO - PROCESS | 1658 | |2;audio-dft;185;184;186;165;250;262;199;181;182;187 06:49:04 INFO - PROCESS | 1658 | |3;audio-fft;82;60;59;88;60;60;75;82;94;56 06:49:04 INFO - PROCESS | 1658 | |4;audio-oscillator;88;70;93;70;67;69;89;68;69;93 06:49:04 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;86;83;86;84;87;85;73;113;134;103 06:49:04 INFO - PROCESS | 1658 | |6;imaging-darkroom;92;93;111;151;120;93;92;93;92;93 06:49:04 INFO - PROCESS | 1658 | |7;imaging-desaturate;84;83;80;83;97;129;137;83;84;84 06:49:04 INFO - PROCESS | 1658 | |8;json-parse-financial;55;54;53;55;56;59;58;57;56;57 06:49:04 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;64;69;68;81;75;72;70;71;69;96 06:49:04 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;83;82;84;87;84;83;85;101;71;93 06:49:04 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;139;143;157;145;144;141;147;149;216;156 06:49:04 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;160;167;166;161;164;167;225;208;165;165 06:49:04 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;69;68;66;67;66;66;66;68;65;66 06:49:04 INFO - PROCESS | 1658 | __end_tp_report 06:49:04 INFO - PROCESS | 1658 | __start_cc_report 06:49:04 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,2113 06:49:04 INFO - PROCESS | 1658 | __end_cc_report 06:49:04 INFO - PROCESS | 1658 | __startTimestamp1483022944543__endTimestamp 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | ------- Summary: start ------- 06:49:04 INFO - PROCESS | 1658 | Number of tests: 14 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:114.80 Median:114.00 stddev:2.39 (2.1%) stddev-sans-first:2.50 06:49:04 INFO - PROCESS | 1658 | Values: 116.0 114.0 114.0 112.0 115.0 114.0 114.0 114.0 114.0 121.0 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:118.80 Median:110.50 stddev:16.38 (14.8%) stddev-sans-first:14.37 06:49:04 INFO - PROCESS | 1658 | Values: 145.0 110.0 108.0 143.0 110.0 105.0 111.0 109.0 139.0 108.0 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:198.10 Median:186.50 stddev:31.74 (17.0%) stddev-sans-first:33.31 06:49:04 INFO - PROCESS | 1658 | Values: 185.0 184.0 186.0 165.0 250.0 262.0 199.0 181.0 182.0 187.0 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:71.60 Median:78.50 stddev:14.16 (18.0%) stddev-sans-first:14.51 06:49:04 INFO - PROCESS | 1658 | Values: 82.0 60.0 59.0 88.0 60.0 60.0 75.0 82.0 94.0 56.0 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:79.00 stddev:11.45 (14.5%) stddev-sans-first:11.51 06:49:04 INFO - PROCESS | 1658 | Values: 88.0 70.0 93.0 70.0 67.0 69.0 89.0 68.0 69.0 93.0 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:93.40 Median:84.50 stddev:18.13 (21.5%) stddev-sans-first:19.03 06:49:04 INFO - PROCESS | 1658 | Values: 86.0 83.0 86.0 84.0 87.0 85.0 73.0 113.0 134.0 103.0 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:103.00 Median:92.50 stddev:19.49 (21.1%) stddev-sans-first:20.27 06:49:04 INFO - PROCESS | 1658 | Values: 92.0 93.0 111.0 151.0 120.0 93.0 92.0 93.0 92.0 93.0 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:83.50 stddev:20.92 (25.1%) stddev-sans-first:21.85 06:49:04 INFO - PROCESS | 1658 | Values: 84.0 83.0 80.0 83.0 97.0 129.0 137.0 83.0 84.0 84.0 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:56.50 stddev:1.83 (3.2%) stddev-sans-first:1.90 06:49:04 INFO - PROCESS | 1658 | Values: 55.0 54.0 53.0 55.0 56.0 59.0 58.0 57.0 56.0 57.0 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:73.50 Median:71.50 stddev:9.11 (12.7%) stddev-sans-first:8.99 06:49:04 INFO - PROCESS | 1658 | Values: 64.0 69.0 68.0 81.0 75.0 72.0 70.0 71.0 69.0 96.0 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:84.00 stddev:7.73 (9.2%) stddev-sans-first:8.16 06:49:04 INFO - PROCESS | 1658 | Values: 83.0 82.0 84.0 87.0 84.0 83.0 85.0 101.0 71.0 93.0 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:153.70 Median:148.00 stddev:22.67 (15.3%) stddev-sans-first:23.41 06:49:04 INFO - PROCESS | 1658 | Values: 139.0 143.0 157.0 145.0 144.0 141.0 147.0 149.0 216.0 156.0 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.80 Median:166.50 stddev:22.46 (13.5%) stddev-sans-first:23.17 06:49:04 INFO - PROCESS | 1658 | Values: 160.0 167.0 166.0 161.0 164.0 167.0 225.0 208.0 165.0 165.0 06:49:04 INFO - PROCESS | 1658 | 06:49:04 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:66.50 stddev:1.25 (1.9%) stddev-sans-first:1.01 06:49:04 INFO - PROCESS | 1658 | Values: 69.0 68.0 66.0 67.0 66.0 66.0 66.0 68.0 65.0 66.0 06:49:04 INFO - PROCESS | 1658 | -------- Summary: end -------- 06:49:04 INFO - PROCESS | 1658 | 06:49:05 INFO - TEST-INFO | 1658: exit 0 06:49:05 INFO - TEST-OK | kraken | took 60466ms 06:49:05 INFO - SUITE-END | took 1180s 06:49:05 INFO - Completed test suite (00:19:40) 06:49:05 INFO - javascript benchmark 06:49:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2646.206138158552, 3271.623763267224, 3285.638579756228, 3290.3583801158784, 3329.8834540791077, 5518.724063796809, 5540.889182216353, 5574.331080270368, 5597.832065038056, 5604.075327570951, 5502.862428439291, 5567.721613919308, 5595.664260144395, 5601.439856014396, 5628.549716022719, 5031.27046578246, 5049.0, 5065.037642847848, 5155.381354237496, 5222.112240919034, 5027.4469808321055, 5039.949600503985, 5057.03916255912, 5103.0, 5187.740612969355, 5080.923786143192, 5117.948820511784, 5140.7172605506685, 5159.406668233164, 5164.509371609691, 5072.518110779489, 5155.432902380735, 5165.276861239429, 5175.0, 5192.1173400522, 5003.374578177728, 5103.336566246401, 5106.540411362995, 5114.539691427753, 5172.27588137661, 4185.623293903533, 5441.5102640762525, 5475.151351540518, 5475.561955043587, 5502.422245664187, 5977.671228082454, 5996.880062398771, 5997.1304160896425, 6015.639061656271, 6024.548158888101, 6152.015677491625, 6184.319724830265, 6206.34833342497, 6208.2239720034995, 6214.0, 5548.223248745179, 5573.526250268773, 5582.665040097526, 5634.943650563442, 5653.57598180134, 6291.433770960635, 6316.0, 6326.2092238470195, 6336.8415789604705, 6342.0, 5987.491063259672, 6001.609895356875, 6002.849928751816, 6040.697965101727, 6059.121427393004, 5845.88308233833, 5911.142884281755, 6131.476938540911, 6159.568830181934, 6175.567710260329, 5837.241158649434, 5986.49114825236, 6032.668203248776, 6066.241719785027, 6116.214037636738, 6116.510679145657, 6119.685352982159, 6128.60597900623, 6132.581522417141, 6144.8771024579255, 4546.499885012646, 4602.194615942263, 4789.137955168034, 4833.009233107275, 4836.492168322346, 4300.483941926988, 4319.049809042006, 4330.39374487572, 4346.543612920661, 4348.086901750605, 5620.91568626471, 5622.297212848394, 5626.381098079208, 5654.321481422256, 5685.431456854281, 6161.260648722182, 6214.4407003369915, 6216.378362163748, 6218.098375735583, 6227.1282020517165, 5480.972595136999, 5489.451054894558, 5546.0, 5570.303712035996, 5632.408597097327, 5297.629165958423, 5308.840734777963, 5321.308229930161, 5349.5720342372515, 5355.277037599952, 6224.844378890473, 6290.182276304235, 6310.242770867525, 6333.050042493479, 6337.17616709834, 6024.909626355696, 6046.244219472566, 6206.348333424925, 6207.286162091417, 6209.161763162096, 6122.032243765608, 6233.501319894488, 6262.185915831095, 6272.2159730033745, 6315.368463153648, 6038.184845045863, 6041.395860413923, 6059.182010428712, 6063.235154796902, 6093.0, 6092.512598991982, 6158.168647232746, 6162.044883043135, 6170.136180934583, 6188.535859810442, 5811.709414529172, 5846.093855452412, 5875.177475153397, 5889.322727886177, 5916.112583112395, 5884.7940322088525, 5902.5277977762535, 5906.232189815297, 5908.881822363614, 5911.704414779329, 5245.763940622604, 5296.894062118659, 5789.623674461147, 5793.362730099601, 5800.24596802413, 5834.15404766315, 5929.822105336847, 5940.405959404198, 5943.791967281124, 5944.881102377842, 5768.192453056491, 5777.7977770777825, 5780.190773291826, 5785.652860828363, 5786.826395208151], "value": 5564.840938852382, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.5577313098818, 490.458043861533, 514.7966553211481, 517.0279873837186, 522.3705434950883, 30026.399472010573, 31424.842875785645, 31449.528257076134, 31492.212694682617, 38629.0, 39397.0, 41688.791556042255, 41911.0, 42018.7899060505, 42121.57878421215, 8071.838563228732, 9250.491222982733, 9412.247020238365, 9437.575309111073, 9446.952765236165, 8335.66657333709, 8533.615987280587, 9336.206422454099, 9451.33840631156, 9463.195628371597, 11117.166212534052, 11319.037881780017, 11387.975082242596, 11499.482523286471, 11505.654830355103, 11099.0, 11217.99038086568, 11221.719457013558, 11246.268992515461, 11256.268342557749, 7149.49953503255, 8523.53120578368, 10888.891111088868, 11129.833052504218, 11220.943895280552, 30088.548671769942, 34321.828390858136, 35505.82247088774, 35814.82092589547, 35920.46119308213, 3199.40810949975, 3239.983800081008, 3348.2131699050706, 3780.4707340972286, 3789.7347185697013, 28691.71308286911, 28720.0, 29628.8518557408, 30169.094927152222, 30979.380412391627, 3065.2336915771057, 3152.8226685966883, 3168.4138434389483, 3193.664665210166, 3195.536647186168, 480773.0, 484758.0, 485595.0, 486107.0, 487296.0, 60827.39172608306, 61147.08279375724, 61235.69382153061, 61257.69371153116, 61342.38657613456, 61495.69252153711, 61510.69244653748, 61657.0, 61669.691651541456, 61805.07292390618, 15939.0, 16029.438969636007, 16413.58966025859, 16427.753583696252, 16467.094309812837, 12020.0383969284, 12523.874761252453, 12567.685807854878, 12584.11911166228, 12592.181508201938, 15945.44190953311, 16377.0173789573, 16401.917990409973, 18105.808865804538, 18481.1683474244, 6954.721811127498, 7263.3514550687605, 7619.390448764086, 7626.656800443993, 7643.121041080347, 695.1553862057624, 915.6428992692855, 963.21016766251, 1031.2471895516314, 1090.983635245472, 28892.844286228737, 29286.41427171487, 29579.408411831642, 30073.69926300753, 30247.395052099277, 852.8720691896265, 962.0764066496102, 1011.8988101189822, 1035.1201478743008, 1035.233926894108, 14794.482193123189, 15380.30788614515, 15438.845611543966, 15447.382104715685, 15782.526524204291, 577587.0, 654141.0, 657329.0, 658261.0, 660172.0, 56758.71620641871, 57096.71451642715, 57169.0, 57202.7139864298, 57315.42684573184, 1169.561414469574, 1213.6298428979069, 1219.0917766264367, 1286.215408600734, 1328.7342531493546, 28259.576106358832, 29941.850290749277, 30273.24316892007, 30702.539461908535, 30892.845535773078, 52232.0, 55342.0, 55406.0, 55459.0, 55805.44194558002, 585024.0, 588882.0, 590111.0, 592080.0, 594613.0, 26525.469490610467, 26941.86529067342, 27317.590236146076, 27906.860465698355, 39575.80212098921, 45219.547804521535, 45441.77279113583, 45764.0, 45791.77104114458, 45874.77062614666, 37473.06317342088, 37663.43504847484, 38255.80872095733, 39523.40714889336, 39704.20591588094, 37004.81497592495, 37507.87476375713, 38427.039324017125, 38742.0, 38858.80570597129, 29342.70657293485, 36566.817165914, 37064.07339816526, 37616.43575346427, 37770.62229377671, 29125.0, 30582.694173058873, 33371.0, 33393.38923151603, 33777.49333760045, 24060.39849003789, 25076.48929149957, 25358.74641253564, 25571.872140639178, 25581.872090639426], "value": 19684.54897616937, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.0917546493246, 371.2167326940158, 374.4439507331612, 384.9037740564859, 400.57739085265035, 14446.71106577869, 14792.482263120794, 15107.75712858655, 19777.0, 19989.300374486895, 18282.177302021406, 18740.344087956924, 18985.0, 19150.712739308885, 19182.80817191831, 5609.411011843759, 5930.822075337725, 5937.910931336033, 5943.3462319144855, 5948.910766338508, 5330.440303768107, 5540.529055030327, 5835.708214589254, 5903.055511118222, 5906.497947674453, 5696.231008813798, 5851.0, 5855.765769369242, 5875.17747515346, 5888.793892213774, 5730.971345143268, 5767.971160144214, 5793.073108302693, 5819.67991760453, 5849.7660093596205, 4900.215965445512, 4968.807486203321, 5093.949060509384, 5101.770420331094, 5102.693838369672, 24026.279211623503, 24159.516809663884, 24295.392615184763, 24361.0, 24446.26661200167, 2190.233418303597, 2230.7434645015705, 2275.385645875621, 2520.5463016656995, 2624.4357463145516, 14444.133351998913, 14825.48110816127, 14970.47603333878, 15658.373665053385, 15814.288357023961, 1755.3505203074817, 1788.284686125547, 1807.1054827860162, 1836.1461920207169, 1836.7061270196705, 87481.0, 105603.47198264036, 105650.0, 108529.0, 114815.7693870874, 9669.081437263449, 9679.274054445945, 9758.804823903482, 9976.600935962626, 10785.220438627695, 13419.396127174301, 13463.596092117252, 13466.663333416744, 13471.663208419868, 13484.39320230592, 8266.958665206756, 8400.815787895192, 8436.156384361515, 8543.743687689379, 8557.0, 8408.117147699526, 8426.747197584082, 8439.620217090245, 8573.914260857311, 8605.31157507398, 5430.647007944471, 6349.111124442582, 6467.482601391877, 6476.121924641801, 6493.285738568754, 1803.1615298886081, 1828.1499102917207, 1832.221305944968, 1838.6874231380698, 1875.4186202277338, 894.552723638181, 896.6547879066476, 919.7424721078108, 945.163530275711, 1033.6020632056711, 20327.186912523335, 20926.581468370852, 20946.0, 20975.580488390147, 21116.366509004754, 790.6402586823096, 855.1576696953496, 885.685581618529, 928.967486137982, 932.7434955387187, 10588.059105431403, 10933.617323393803, 10949.959753823374, 11008.339499630049, 11019.118470522339, 574773.0, 599480.0, 603402.0, 616589.0, 618859.4342169742, 101561.25039118166, 104271.0, 105611.4719426398, 107007.46496267624, 109823.45088274508, 970.6408628807386, 980.632262901412, 1009.0111690543162, 1009.2985375164191, 1015.3653966271081, 18093.004884731465, 18378.356757513422, 18427.53931151732, 18510.81489185091, 18617.255309787997, 48040.519594803605, 48636.78408039686, 48910.0, 49334.75332623314, 49402.75298623484, 51639.74180129075, 64151.35848641454, 67042.66478667576, 67062.66468667626, 67909.64180716457, 6833.658317084027, 7162.391196748126, 7358.300961408658, 7444.73943411978, 8026.879596805936, 12051.57819476314, 12481.375931203585, 12608.74782504363, 12857.871421285667, 13099.738005240033, 10921.126309895031, 10997.395143266876, 11315.773684526428, 11329.320240785579, 11548.884511155116, 10694.0, 10865.837012444978, 11041.227114102257, 11120.0, 11302.152338574475, 11160.386178760245, 11651.242669226473, 11675.88324116782, 11704.180707350404, 11781.998530125036, 7261.201267860425, 7309.671064801991, 7403.335165841944, 7617.5048621839405, 7620.0, 9466.763330916781, 10810.405427701551, 11066.833997490205, 11258.7748245033, 11454.370009649549], "value": 9353.88613350324, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1266.411118829744, 1423.5373503611327, 1450.4778279819266, 1587.8491543303383, 1814.8003719590843, 7303.45224108192, 7598.278163574459, 8079.959600201998, 8353.791155221123, 8373.995120585538, 8115.837683246332, 8214.712485063024, 8224.547649879254, 8224.712135075273, 8242.670293188265, 2252.5044490212126, 2296.5062511560013, 2303.1363238785457, 2305.619572770491, 2307.1694190091553, 2276.590213761522, 2315.536892621474, 2333.031791806407, 2334.9299521014395, 2337.532493501298, 2271.3299576624936, 2284.189112864934, 2309.8498597591188, 2315.016118149788, 2315.270689732729, 1863.7577114975102, 2220.766819483947, 2223.644216925284, 2227.9554408911813, 2236.0, 2242.730872295335, 2267.784560466753, 2273.3407311879537, 2275.397019789757, 2279.943001424978, 8038.638261278198, 8226.753197404028, 8285.585720714, 8286.502809831369, 8305.584720763998, 4967.652264341497, 5091.083604951107, 5150.098732721796, 5150.124478838607, 5165.070287348276, 3268.346330733863, 3394.2193295542165, 3407.5399821024093, 3456.1705190754037, 3458.2219000724886, 999.715081201854, 1004.7488127968008, 1025.661531694539, 1037.9896201037968, 1040.0223789637716, 10624.150067994542, 10653.360798352123, 10725.981031801966, 10746.301490403177, 10755.3009054412, 4142.751434913915, 4286.485621725413, 4348.978255108705, 4373.55672628032, 4375.6280716138845, 8605.13948605147, 8619.569021548898, 8630.611622477003, 8637.913620863837, 8657.350698697497, 4980.302757613937, 4999.0501804657, 4999.10016197088, 5001.174806156943, 5013.097642424399, 3975.5030621172355, 4202.243596152722, 4328.415663885398, 4352.325389564622, 4607.631389488833, 3514.525539052195, 3537.02731748766, 3591.3894637911617, 3617.2946275476556, 3625.093726568358, 1076.1498416251075, 1080.864891888514, 1082.226208261097, 1091.6070214722693, 1091.683411810582, 752.8757754970369, 832.8500869843486, 874.6676263020012, 886.233408102, 886.5035580075179, 4584.724916505021, 4699.671023028423, 5308.389535203501, 5476.617671312992, 5565.888682226333, 410.8582539024032, 432.44214962698396, 438.48259054315577, 440.42964361152383, 446.01875873079354, 3334.949975750366, 3785.848566057327, 3810.3141434541494, 3822.48396466479, 3876.8061596920043, 9704.660336888317, 9987.900120998842, 10097.899021009696, 10121.746956326151, 10125.493725313705, 8196.877046844302, 9823.950880245553, 9836.163926066365, 10068.0, 10069.597216111273, 7697.961510192413, 7793.727219547289, 7812.68749250035, 7826.295633393022, 7830.4518683691595, 5192.065428222805, 5383.434739352311, 5516.117421212587, 5566.610337276352, 5601.327840658984, 8450.281726053354, 9282.071792820663, 9292.95353523228, 9412.0, 9414.811703766023, 9206.355555111079, 9417.01121382245, 9430.669926552539, 9548.18840398574, 9560.426374417557, 6602.108715323562, 6686.398224159654, 6687.598744075371, 6690.264070952094, 6945.236024037251, 7869.527828330319, 7872.881906771288, 7887.763367098996, 7941.682332706627, 7966.681332746625, 6946.340097690711, 7032.191298000591, 7074.858502830018, 7094.11323584552, 7180.210176880643, 6912.170539535288, 6968.4076853465995, 6996.545224560387, 7004.089468369079, 7053.858922821618, 6510.641914694548, 6571.0, 6587.440067594308, 6591.802245932629, 6650.833729156616, 4805.9038819224115, 4815.686980346266, 4850.6362022847725, 4889.975550122226, 4942.654014218971, 4635.907281854276, 4807.903841923072, 4822.590079843252, 4953.876153096057, 5039.823606173766], "value": 4205.6250728536725, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.82552119770537, 305.2749719416386, 308.76996637505056, 313.1373067199864, 314.18782447373536, 5018.322526458927, 5072.543471087602, 5154.634021984177, 5159.961815083045, 5184.507471790183, 4210.3894935234375, 4918.96701692645, 5078.289039534468, 5083.389993200812, 5133.666311689738, 3485.2158264390564, 3776.2440465183686, 3953.940690889639, 3970.3250447423934, 3987.8604248851298, 3279.999600121962, 3683.5027271318295, 3945.881623551298, 3982.9004274893214, 3992.7205095643317, 3876.6898648108227, 3881.6506514413695, 3892.3188442022674, 3903.921921561567, 3925.3326934421075, 3681.2821499807546, 3877.4571559981623, 3884.7280690351686, 3897.7661340319673, 3902.5316961964463, 3383.1034775784437, 3396.677315655009, 3403.766421899206, 3426.4860270959307, 3455.568053993251, 4692.36653051837, 4913.189323761575, 4976.377952755905, 5050.646454748169, 5088.338515992934, 3908.335582950905, 4284.057507348379, 4431.202383570988, 4436.268015777425, 4452.376667266585, 5307.867303317409, 5342.385625653061, 5342.866428339284, 5375.516203541661, 5382.0, 3124.8125112493094, 3742.2141350316465, 3753.9436908446396, 3759.774413535197, 3762.435634654796, 4946.629002824802, 5616.567775217375, 5622.578306626979, 5642.266505354278, 5693.77224911003, 3184.7133757961897, 3433.4163192257374, 3544.113971507123, 3752.724073814916, 3775.905602359963, 3386.779859309137, 3819.7135214858995, 3820.6436714966344, 3844.1355942367927, 3857.7106716996336, 3746.463949780577, 4395.604395604411, 4395.846145384896, 4426.933595996063, 4437.378766972626, 5374.312843578211, 5380.704061276668, 5415.6479828811, 5429.448159365092, 5484.698341591251, 1021.7854250607307, 1092.9125520107543, 1169.3101070368523, 1172.407933993345, 1189.8155785853207, 781.5154604145466, 843.4939036578004, 844.6822956188366, 846.957652117404, 847.5423271433357, 4085.9182816343505, 4732.982408782128, 4782.450018247877, 4785.617150627942, 4787.593054590398, 3980.980095099506, 4870.464248932614, 5220.008198442284, 5239.0, 5243.134882744304, 4721.9055618888115, 4757.3577567028005, 4764.976175119103, 4791.520847915181, 4805.37530121089, 2393.3059412770103, 2422.7819496245424, 2480.6651102101346, 2578.793696504275, 2723.5233834079354, 4828.61371090319, 4931.534232883559, 4972.975135124301, 5144.897102058013, 5370.570354371641, 4531.932021019687, 5222.321098257202, 5271.841844744664, 5277.393099793496, 5281.841544753664, 5138.409082955508, 5337.0, 5348.443285215366, 5377.193420986883, 5395.05586522357, 4293.720908140961, 4450.577195166454, 4902.259697807227, 5128.333291680145, 5131.0, 4043.4230650047093, 4383.824647014211, 5071.594272458268, 5081.923771143362, 5103.974480127576, 4967.224909069206, 5321.946780532146, 5342.9198562022375, 5363.410024897336, 5406.540444062299, 2891.739743423018, 3071.677473865212, 3523.0311664292526, 3536.4872093546805, 3542.6280240575397, 4901.754912254301, 5100.770465329143, 5136.152534831711, 5184.988927159318, 5195.960807838372, 4464.665150113689, 4804.399450068741, 4998.4501704813165, 4999.750012499288, 5025.045241404122, 4290.849820256276, 4638.397008388888, 4988.981666092107, 5002.424721157114, 5029.5221953914315, 4587.77061146948, 4899.412070551414, 5016.172331565251, 5195.92206116901, 5218.739063046908, 5012.749362531786, 5286.682799032071, 5418.945810541844, 5432.918506222489, 5474.3704473986, 5175.560077393313, 5261.131913234273, 5266.868328291824, 5268.078086334799, 5272.550048736628], "value": 3823.299458469153, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.5270081891448, 679.8177849104376, 717.8420747435565, 768.5196752029981, 779.8713212319967, 13744.725105497897, 14230.288485575706, 14235.288235588205, 14286.214258215794, 15039.323230454629, 13134.343282835867, 13466.192028478274, 13466.797998030037, 13468.528601498949, 13479.191248525096, 638.3042483692774, 823.8722997935315, 833.2167762303416, 837.6146972392706, 838.4843321357382, 659.3834764495189, 666.9499787515956, 797.5932274539969, 824.2664029014182, 828.8508068547659, 874.54960695242, 878.4465786554462, 879.9516026618534, 880.8766772651802, 881.6032785246632, 871.8387098386787, 873.2402809555664, 873.7204094689638, 876.0757400942003, 877.8814859993882, 499.08351928594374, 513.8843760153973, 627.5293529852611, 646.9842347514405, 651.0, 14006.71986560263, 14144.0, 14158.433662653584, 14160.937929655214, 14161.433542658282, 12751.681207969783, 12884.677883052906, 12972.610821675366, 12985.662580735463, 13104.737905241842, 11601.883981160248, 11909.097712475332, 11939.880601193963, 11958.0, 11962.324323788895, 4283.571642835723, 4297.269464191095, 4325.4593175853015, 4331.220380331539, 4472.016156445577, 84256.57871710663, 85077.0, 85287.0, 86228.56885715594, 87285.56357218236, 9868.21054315653, 9906.702798916043, 10093.747656308504, 10395.532201050819, 10452.843207351896, 17619.91190044067, 18211.725179237324, 19123.521911952033, 19207.8079219206, 19338.613227735368, 7278.344948954619, 7318.853622927511, 7499.137599176055, 7510.248975102446, 7577.659005344773, 4095.97952010244, 4127.979360103241, 4175.707700460999, 4181.519125300569, 4350.1734670412525, 3257.674232576771, 3347.497875318673, 3359.798412095282, 3360.24394511233, 3360.31113621707, 624.7376102037065, 640.0431355124026, 642.4795915308615, 649.6297110646981, 656.3469347998677, 532.8880935003615, 590.391896346755, 608.9360617135226, 613.6716856481761, 621.8227805075622, 8194.959025204837, 8337.458065225741, 8544.231019208177, 8629.525376104375, 8848.46909185451, 578.5718568259458, 582.9912551311734, 585.7305639405836, 603.276068717532, 609.4788955443121, 3991.221711766236, 4053.756774593534, 4059.573744756817, 4082.3264161413035, 4196.748195108304, 103677.0, 104960.47519762353, 109752.4512377449, 111830.44084779687, 114213.4289328548, 62197.689011554656, 62394.632375733265, 81053.59473202596, 82213.0, 93669.5316523413, 77880.0, 83394.16605833985, 84928.57535712281, 85682.14317856742, 86371.0, 9189.356745027784, 9415.95292023563, 9425.481598512148, 9632.518374081128, 9698.0, 51179.0, 54988.0, 55964.6008849782, 55999.0, 57383.56541086506, 70888.0, 71148.28851711417, 75278.24721752931, 77752.0, 79360.01599960047, 11713.941430292794, 11859.762804743685, 12140.514379425078, 12297.938510307391, 12362.38188090574, 13250.602481925558, 13590.0, 14015.475066560817, 14767.92616036913, 14771.113733176044, 13018.414171362121, 13055.804162937753, 13121.803172952605, 13555.728885422433, 13584.79622805639, 12500.24998500093, 12612.117151799286, 12807.61577152687, 12904.612861614167, 13330.803453286, 11838.822417663914, 12379.0, 12852.228866268053, 12860.164089334163, 12866.67833304175, 8838.381313308008, 10579.36523808574, 10864.0, 11044.668659940215, 11374.601888933848, 10177.236707246839, 10979.004552928558, 11190.272632278877, 11226.66320010401, 11238.0], "value": 7139.211050416966, "unit": "score"}], "name": "dromaeo_css", "value": 6999.874015119185, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 114.0, 112.0, 115.0, 114.0, 114.0, 114.0, 114.0, 121.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 110.0, 108.0, 143.0, 110.0, 105.0, 111.0, 109.0, 139.0, 108.0], "value": 118.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 186.0, 165.0, 250.0, 262.0, 199.0, 181.0, 182.0, 187.0], "value": 198.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 59.0, 88.0, 60.0, 60.0, 75.0, 82.0, 94.0, 56.0], "value": 71.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 93.0, 70.0, 67.0, 69.0, 89.0, 68.0, 69.0, 93.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 84.0, 87.0, 85.0, 73.0, 113.0, 134.0, 103.0], "value": 93.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 111.0, 151.0, 120.0, 93.0, 92.0, 93.0, 92.0, 93.0], "value": 103.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 80.0, 83.0, 97.0, 129.0, 137.0, 83.0, 84.0, 84.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 55.0, 56.0, 59.0, 58.0, 57.0, 56.0, 57.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 68.0, 81.0, 75.0, 72.0, 70.0, 71.0, 69.0, 96.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 84.0, 87.0, 84.0, 83.0, 85.0, 101.0, 71.0, 93.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 143.0, 157.0, 145.0, 144.0, 141.0, 147.0, 149.0, 216.0, 156.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 167.0, 166.0, 161.0, 164.0, 167.0, 225.0, 208.0, 165.0, 165.0], "value": 174.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 66.0, 67.0, 66.0, 66.0, 66.0, 68.0, 65.0, 66.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1481.7, "alertThreshold": 2.0}]} 06:49:05 INFO - Return code: 0 06:49:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:49:05 INFO - # TBPL SUCCESS # 06:49:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:49:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:49:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:49:05 INFO - 2016-12-29 06:49:05.838 screenresolution[1662:11520] starting screenresolution argv=screenresolution get 06:49:05 INFO - 2016-12-29 06:49:05.841 screenresolution[1662:11520] Display 0: 1600x1200x32@60 06:49:05 INFO - 2016-12-29 06:49:05.847 screenresolution[1663:11523] starting screenresolution argv=screenresolution list 06:49:05 INFO - Available Modes on Display 0 06:49:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:49:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:49:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:49:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:49:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:49:06 INFO - Intel Iris: 06:49:06 INFO - Chipset Model: Intel Iris 06:49:06 INFO - Type: GPU 06:49:06 INFO - Bus: Built-In 06:49:06 INFO - VRAM (Dynamic, Max): 1536 MB 06:49:06 INFO - Vendor: Intel (0x8086) 06:49:06 INFO - Device ID: 0x0a2e 06:49:06 INFO - Revision ID: 0x0009 06:49:06 INFO - Displays: 06:49:06 INFO - Display: 06:49:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:49:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:49:06 INFO - Display Serial Number: 1600x1200 60 06:49:06 INFO - Main Display: Yes 06:49:06 INFO - Mirror: Off 06:49:06 INFO - Online: Yes 06:49:06 INFO - Rotation: Supported 06:49:06 INFO - Return code: 0 06:49:06 INFO - Running post-action listener: _resource_record_post_action 06:49:06 INFO - [mozharness: 2016-12-29 14:49:06.097384Z] Finished run-tests step (success) 06:49:06 INFO - Running post-run listener: _resource_record_post_run 06:49:06 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 33614848; Write bytes: 661935616; Read time: 311; Write time: 1949 06:49:06 INFO - TinderboxPrint: CPU usage
53.6% 06:49:06 INFO - TinderboxPrint: I/O read bytes / time
33,614,848 / 311 06:49:06 INFO - TinderboxPrint: I/O write bytes / time
661,935,616 / 1,949 06:49:06 INFO - TinderboxPrint: CPU idle
2,251.0 (46.4%) 06:49:06 INFO - TinderboxPrint: CPU system
972.2 (20.0%) 06:49:06 INFO - TinderboxPrint: CPU user
1,626.6 (33.5%) 06:49:06 INFO - TinderboxPrint: Swap in / out
378,531,840 / 0 06:49:06 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 346259968; Write bytes: 346355200; Read time: 26777; Write time: 706 06:49:06 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 31554560; Write bytes: 308432896; Read time: 289; Write time: 1197 06:49:06 INFO - Running post-run listener: _upload_blobber_files 06:49:06 INFO - Blob upload gear active. 06:49:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:49:06 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:49:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:49:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:49:06 INFO - (blobuploader) - INFO - Open directory for files ... 06:49:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:49:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:49:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:49:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:49:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:49:07 INFO - (blobuploader) - INFO - Done attempting. 06:49:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:49:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:49:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:49:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:49:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:49:08 INFO - (blobuploader) - INFO - Done attempting. 06:49:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:49:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:49:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:49:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:49:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:49:11 INFO - (blobuploader) - INFO - Done attempting. 06:49:11 INFO - (blobuploader) - INFO - Iteration through files over. 06:49:11 INFO - Return code: 0 06:49:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:49:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:49:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8143aec88e5886656343ae2529b5e8660c51942b249dfe4cc539b094e5f5982d561666fed46495a72464812b155c16cf2473631ba5be45ccf00bd454144303a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c130dac9f5366e7dd8411d457de117ee24acc75d0f900c3cf1a964d5e2c7514ec0863ae520a4542b4572a8fa755b691d96a955536b249bf9576825f1742be5a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d89ebc72ba162c0246f6486e1bf36ff287d567cafb4de2f6aacc8edc3b1e459f80291d6338208929a79ba4a78289b3457946685386a01062e1074c806e7e9f9c"} 06:49:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:49:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:49:11 INFO - Contents: 06:49:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8143aec88e5886656343ae2529b5e8660c51942b249dfe4cc539b094e5f5982d561666fed46495a72464812b155c16cf2473631ba5be45ccf00bd454144303a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c130dac9f5366e7dd8411d457de117ee24acc75d0f900c3cf1a964d5e2c7514ec0863ae520a4542b4572a8fa755b691d96a955536b249bf9576825f1742be5a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d89ebc72ba162c0246f6486e1bf36ff287d567cafb4de2f6aacc8edc3b1e459f80291d6338208929a79ba4a78289b3457946685386a01062e1074c806e7e9f9c"} 06:49:11 INFO - Running post-run listener: copy_logs_to_upload_dir 06:49:11 INFO - Copying logs to upload dir... 06:49:11 INFO - mkdir: /builds/slave/test/build/upload/logs 06:49:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.328892 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-29 06:49:11.391295) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 06:49:11.396331) ========= 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.un1Y4AbZvl/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.EyFrwj92LG/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8143aec88e5886656343ae2529b5e8660c51942b249dfe4cc539b094e5f5982d561666fed46495a72464812b155c16cf2473631ba5be45ccf00bd454144303a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c130dac9f5366e7dd8411d457de117ee24acc75d0f900c3cf1a964d5e2c7514ec0863ae520a4542b4572a8fa755b691d96a955536b249bf9576825f1742be5a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d89ebc72ba162c0246f6486e1bf36ff287d567cafb4de2f6aacc8edc3b1e459f80291d6338208929a79ba4a78289b3457946685386a01062e1074c806e7e9f9c"} build_url:https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011149 build_url: 'https://queue.taskcluster.net/v1/task/K3IUs8TDTD2nck6-CnwdPA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8143aec88e5886656343ae2529b5e8660c51942b249dfe4cc539b094e5f5982d561666fed46495a72464812b155c16cf2473631ba5be45ccf00bd454144303a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c130dac9f5366e7dd8411d457de117ee24acc75d0f900c3cf1a964d5e2c7514ec0863ae520a4542b4572a8fa755b691d96a955536b249bf9576825f1742be5a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d89ebc72ba162c0246f6486e1bf36ff287d567cafb4de2f6aacc8edc3b1e459f80291d6338208929a79ba4a78289b3457946685386a01062e1074c806e7e9f9c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 06:49:11.431205) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:49:11.431561) ========= 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.un1Y4AbZvl/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.EyFrwj92LG/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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.005121 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 06:49:11.483371) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 06:49:11.484155) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 06:49:11.484965) ========= ========= Total master_lag: 0.19 =========