builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0330 starttime: 1476951963.17 results: success (0) buildid: 20161020002550 builduid: daa0991a81fb43c0bde5182983ed0a33 revision: 99a239e1866a57f987b08dad796528e4ea30e622 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 01:26:03.173052) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 01:26:03.173468) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 01:26:03.173758) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 01:26:03.198358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 01:26:03.198768) ========= 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.GN1SrflfaC/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.JM7NFXgV8a/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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-10-20 01:26:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.0M=0.001s 2016-10-20 01:26:03 (19.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.146768 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 01:26:03.367805) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 01:26:03.368118) ========= 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.GN1SrflfaC/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.JM7NFXgV8a/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.024798 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 01:26:03.416423) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 01:26:03.416798) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 99a239e1866a57f987b08dad796528e4ea30e622 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 99a239e1866a57f987b08dad796528e4ea30e622 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GN1SrflfaC/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.JM7NFXgV8a/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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-10-20 01:26:03,498 truncating revision to first 12 chars 2016-10-20 01:26:03,498 Setting DEBUG logging. 2016-10-20 01:26:03,499 attempt 1/10 2016-10-20 01:26:03,499 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/99a239e1866a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-20 01:26:04,775 unpacking tar archive at: mozilla-central-99a239e1866a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.533880 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 01:26:04.974240) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 01:26:04.974819) ========= script_repo_revision: 99a239e1866a57f987b08dad796528e4ea30e622 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 01:26:04.975455) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 01:26:04.975943) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 01:26:04.995120) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-20 01:26:04.995529) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GN1SrflfaC/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.JM7NFXgV8a/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 01:26:05 INFO - MultiFileLogger online at 20161020 01:26:05 in /builds/slave/test 01:26:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 01:26:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:26:05 INFO - {'append_to_log': False, 01:26:05 INFO - 'base_work_dir': '/builds/slave/test', 01:26:05 INFO - 'blob_upload_branch': 'Firefox', 01:26:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:26:05 INFO - 'branch': 'Firefox', 01:26:05 INFO - 'buildbot_json_path': 'buildprops.json', 01:26:05 INFO - 'config_files': ('talos/mac_config.py',), 01:26:05 INFO - 'default_actions': ('clobber', 01:26:05 INFO - 'read-buildbot-config', 01:26:05 INFO - 'download-and-extract', 01:26:05 INFO - 'populate-webroot', 01:26:05 INFO - 'create-virtualenv', 01:26:05 INFO - 'install', 01:26:05 INFO - 'run-tests'), 01:26:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:26:05 INFO - 'download_minidump_stackwalk': True, 01:26:05 INFO - 'download_symbols': 'ondemand', 01:26:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:26:05 INFO - 'tooltool.py': '/tools/tooltool.py', 01:26:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:26:05 INFO - '/tools/misc-python/virtualenv.py')}, 01:26:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:26:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:26:05 INFO - 'installer_path': 'installer.exe', 01:26:05 INFO - 'log_level': 'info', 01:26:05 INFO - 'log_name': 'talos', 01:26:05 INFO - 'log_to_console': True, 01:26:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:26:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:26:05 INFO - 'opt_config_files': (), 01:26:05 INFO - 'pip_index': False, 01:26:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:26:05 INFO - 'cmd': ('bash', 01:26:05 INFO - '-c', 01:26:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:26:05 INFO - 'enabled': True, 01:26:05 INFO - 'halt_on_failure': False, 01:26:05 INFO - 'name': 'check_screen_resolution'},), 01:26:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:26:05 INFO - 'cmd': ('bash', 01:26:05 INFO - '-c', 01:26:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:26:05 INFO - 'enabled': True, 01:26:05 INFO - 'halt_on_failure': False, 01:26:05 INFO - 'name': 'check_screen_resolution'},), 01:26:05 INFO - 'run_cmd_checks_enabled': True, 01:26:05 INFO - 'sps_profile': False, 01:26:05 INFO - 'sps_profile_interval': 0, 01:26:05 INFO - 'suite': 'dromaeojs', 01:26:05 INFO - 'system_bits': '32', 01:26:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:26:05 INFO - 'title': 't-yosemite-r7-0330', 01:26:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:26:05 INFO - 'use_talos_json': True, 01:26:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:26:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:26:05 INFO - 'work_dir': 'build'} 01:26:05 INFO - [mozharness: 2016-10-20 08:26:05.203466Z] Running clobber step. 01:26:05 INFO - Running pre-action listener: _resource_record_pre_action 01:26:05 INFO - Running main action method: clobber 01:26:05 INFO - rmtree: /builds/slave/test/build 01:26:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:26:07 INFO - Running post-action listener: _resource_record_post_action 01:26:07 INFO - [mozharness: 2016-10-20 08:26:07.332758Z] Finished clobber step (success) 01:26:07 INFO - [mozharness: 2016-10-20 08:26:07.333034Z] Running read-buildbot-config step. 01:26:07 INFO - Running pre-action listener: _resource_record_pre_action 01:26:07 INFO - Running main action method: read_buildbot_config 01:26:07 INFO - Using buildbot properties: 01:26:07 INFO - { 01:26:07 INFO - "project": "", 01:26:07 INFO - "product": "firefox", 01:26:07 INFO - "script_repo_revision": "production", 01:26:07 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 01:26:07 INFO - "repository": "", 01:26:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 01:26:07 INFO - "buildid": "20161020002550", 01:26:07 INFO - "pgo_build": "False", 01:26:07 INFO - "basedir": "/builds/slave/test", 01:26:07 INFO - "buildnumber": 9, 01:26:07 INFO - "slavename": "t-yosemite-r7-0330", 01:26:07 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 01:26:07 INFO - "platform": "yosemite_r7", 01:26:07 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 01:26:07 INFO - "branch": "mozilla-central", 01:26:07 INFO - "repo_path": "mozilla-central", 01:26:07 INFO - "revision": "99a239e1866a57f987b08dad796528e4ea30e622", 01:26:07 INFO - "stage_platform": "macosx64", 01:26:07 INFO - "builduid": "daa0991a81fb43c0bde5182983ed0a33", 01:26:07 INFO - "slavebuilddir": "test" 01:26:07 INFO - } 01:26:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 01:26:07 INFO - Running post-action listener: _resource_record_post_action 01:26:07 INFO - [mozharness: 2016-10-20 08:26:07.338708Z] Finished read-buildbot-config step (success) 01:26:07 INFO - [mozharness: 2016-10-20 08:26:07.338907Z] Running download-and-extract step. 01:26:07 INFO - Running pre-action listener: _resource_record_pre_action 01:26:07 INFO - Running main action method: download_and_extract 01:26:07 INFO - mkdir: /builds/slave/test/build/tests 01:26:07 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')]} 01:26:07 INFO - trying https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 01:26:07 INFO - Downloading https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 01:26:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 01:26:08 INFO - Downloaded 1199 bytes. 01:26:08 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 01:26:08 INFO - Using the following test package requirements: 01:26:08 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 01:26:08 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:08 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 01:26:08 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:08 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 01:26:08 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:08 INFO - u'jsshell-mac.zip'], 01:26:08 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:08 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 01:26:08 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 01:26:08 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:08 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 01:26:08 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:08 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 01:26:08 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:08 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 01:26:08 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 01:26:08 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 01:26:08 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:26:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 01:26:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:26:08 INFO - Fetch https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 01:26:08 INFO - Http code: 200 01:26:08 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 01:26:08 INFO - x-amz-cf-id: cJJMbnaoJihkL4CjYgTntKQDL1xdMkZP2fw17e6pif0gOgG3nuYxpQ== 01:26:08 INFO - x-amz-version-id: f9IZwpVxrb_vc3LoXnoacg4MToLO_8O1 01:26:09 INFO - Expected file size: 16504663 01:26:09 INFO - Obtained file size: 16504663 01:26:15 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 01:26:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 01:26:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:26:15 INFO - Fetch https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 01:26:16 INFO - Http code: 200 01:26:16 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 01:26:16 INFO - x-amz-cf-id: xY-2k4H1Buk_0nD52p-ABJ99v13Djt2EJjcYoWULE99JmUt6ASEsXw== 01:26:16 INFO - x-amz-version-id: f9IZwpVxrb_vc3LoXnoacg4MToLO_8O1 01:26:16 INFO - Expected file size: 16504663 01:26:16 INFO - Obtained file size: 16504663 01:26:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 01:26:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 01:26:23 INFO - Fetch https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 01:26:24 INFO - Http code: 200 01:26:24 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 01:26:24 INFO - x-amz-cf-id: p3XGi43KrUhO1rJ7cQuUgnMWycf9QjbaMaY8G7iaejo7-1ChMMLCYA== 01:26:24 INFO - x-amz-version-id: 5l2PjiESf9nxtzNriKS_iWYyW3KaZc18 01:26:24 INFO - Expected file size: 14409028 01:26:24 INFO - Obtained file size: 14409028 01:26: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')]} 01:26:24 INFO - trying https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:26:24 INFO - Downloading https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 01:26:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 01:26:27 INFO - Downloaded 111636314 bytes. 01:26:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:26:27 INFO - mkdir: /builds/slave/test/properties 01:26:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:26:27 INFO - Writing to file /builds/slave/test/properties/build_url 01:26:27 INFO - Contents: 01:26:27 INFO - build_url:https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 01:26:28 INFO - Running post-action listener: _resource_record_post_action 01:26:28 INFO - Running post-action listener: set_extra_try_arguments 01:26:28 INFO - [mozharness: 2016-10-20 08:26:28.336475Z] Finished download-and-extract step (success) 01:26:28 INFO - [mozharness: 2016-10-20 08:26:28.336604Z] Running populate-webroot step. 01:26:28 INFO - Running pre-action listener: _resource_record_pre_action 01:26:28 INFO - Running main action method: populate_webroot 01:26:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:26:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:26:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:26:28 INFO - '--tppagecycles', 01:26:28 INFO - '7'], 01:26:28 INFO - 'tests': ['tsvgm']}}, 01:26:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:26:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:26:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:26:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:26:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:26:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:26:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:28 INFO - 'talos_options': ['--disable-e10s'], 01:26:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:26:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:26:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:28 INFO - 'talos_options': ['--disable-e10s'], 01:26:28 INFO - 'tests': ['damp', 'tps']}, 01:26:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:28 INFO - 'tests': ['damp', 'tps']}, 01:26:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:26:28 INFO - 'tests': ['dromaeo_dom']}, 01:26:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:26:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:26:28 INFO - 'tests': ['basic_compositor_video']}, 01:26:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:26:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:26:28 INFO - 'tests': ['a11yr', 01:26:28 INFO - 'ts_paint', 01:26:28 INFO - 'tpaint', 01:26:28 INFO - 'sessionrestore', 01:26:28 INFO - 'sessionrestore_no_auto_restore', 01:26:28 INFO - 'tabpaint']}, 01:26:28 INFO - 'other-e10s': {'tests': ['a11yr', 01:26:28 INFO - 'ts_paint', 01:26:28 INFO - 'tpaint', 01:26:28 INFO - 'sessionrestore', 01:26:28 INFO - 'sessionrestore_no_auto_restore', 01:26:28 INFO - 'tabpaint']}, 01:26:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:26:28 INFO - 'ts_paint', 01:26:28 INFO - 'tpaint', 01:26:28 INFO - 'sessionrestore', 01:26:28 INFO - 'sessionrestore_no_auto_restore', 01:26:28 INFO - 'tabpaint']}, 01:26:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:26:28 INFO - 'ts_paint', 01:26:28 INFO - 'tpaint', 01:26:28 INFO - 'sessionrestore', 01:26:28 INFO - 'sessionrestore_no_auto_restore', 01:26:28 INFO - 'tabpaint']}, 01:26:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:26:28 INFO - 'tests': ['a11yr', 01:26:28 INFO - 'ts_paint', 01:26:28 INFO - 'tpaint', 01:26:28 INFO - 'sessionrestore', 01:26:28 INFO - 'sessionrestore_no_auto_restore', 01:26:28 INFO - 'tabpaint']}, 01:26:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:26:28 INFO - 'tests': ['a11yr', 01:26:28 INFO - 'ts_paint', 01:26:28 INFO - 'tpaint', 01:26:28 INFO - 'sessionrestore', 01:26:28 INFO - 'sessionrestore_no_auto_restore', 01:26:28 INFO - 'tabpaint']}, 01:26:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:26:28 INFO - 'tests': ['tsvgx', 01:26:28 INFO - 'tsvgr_opacity', 01:26:28 INFO - 'tart', 01:26:28 INFO - 'tscrollx', 01:26:28 INFO - 'cart']}, 01:26:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:26:28 INFO - 'tsvgr_opacity', 01:26:28 INFO - 'tart', 01:26:28 INFO - 'tscrollx', 01:26:28 INFO - 'cart']}, 01:26:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:28 INFO - 'talos_options': ['--disable-e10s'], 01:26:28 INFO - 'tests': ['tp5o']}, 01:26:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:28 INFO - 'tests': ['tp5o']}, 01:26:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:28 INFO - 'talos_options': ['--disable-e10s', 01:26:28 INFO - '--xperf_path', 01:26:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:28 INFO - 'tests': ['tp5n']}, 01:26:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:28 INFO - 'talos_options': ['--xperf_path', 01:26:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:28 INFO - 'tests': ['tp5n']}}, 01:26:28 INFO - 'talos.zip': {'path': '', 01:26:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:26:28 INFO - Running post-action listener: _resource_record_post_action 01:26:28 INFO - [mozharness: 2016-10-20 08:26:28.355459Z] Finished populate-webroot step (success) 01:26:28 INFO - [mozharness: 2016-10-20 08:26:28.355555Z] Running create-virtualenv step. 01:26:28 INFO - Running pre-action listener: _resource_record_pre_action 01:26:28 INFO - Running main action method: create_virtualenv 01:26:28 INFO - Creating virtualenv /builds/slave/test/build/venv 01:26:28 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 01:26:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:26:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:26:28 INFO - Using real prefix '/tools/python27' 01:26:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:26:29 INFO - Installing distribute.............................................................................................................................................................................................done. 01:26:32 INFO - Installing pip.................done. 01:26:32 INFO - Return code: 0 01:26:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:26:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:26:32 INFO - Reading from file tmpfile_stdout 01:26:32 INFO - Output received: 01:26:32 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) 01:26:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10299c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a05cb570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10283ad30>, '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 0x102660db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102832b20>, '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.GN1SrflfaC/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.JM7NFXgV8a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 01:26:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:26:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:26:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GN1SrflfaC/Render', 01:26:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:32 INFO - 'HOME': '/Users/cltbld', 01:26:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:32 INFO - 'LOGNAME': 'cltbld', 01:26:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:32 INFO - 'MOZ_NO_REMOTE': '1', 01:26:32 INFO - 'NO_EM_RESTART': '1', 01:26:32 INFO - 'PAGER': '/bin/cat', 01:26:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:32 INFO - 'PWD': '/builds/slave/test', 01:26:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:32 INFO - 'SHELL': '/bin/bash', 01:26:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JM7NFXgV8a/Listeners', 01:26:32 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 01:26:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:32 INFO - 'USER': 'cltbld', 01:26:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:32 INFO - 'XPC_FLAGS': '0x0', 01:26:32 INFO - 'XPC_SERVICE_NAME': '0', 01:26:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:32 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 01:26:32 INFO - Cleaning up... 01:26:32 INFO - Return code: 0 01:26:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102a26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10299c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a05cb570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10283ad30>, '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 0x102660db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102832b20>, '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.GN1SrflfaC/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.JM7NFXgV8a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 01:26:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:26:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:26:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GN1SrflfaC/Render', 01:26:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:32 INFO - 'HOME': '/Users/cltbld', 01:26:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:32 INFO - 'LOGNAME': 'cltbld', 01:26:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:32 INFO - 'MOZ_NO_REMOTE': '1', 01:26:32 INFO - 'NO_EM_RESTART': '1', 01:26:32 INFO - 'PAGER': '/bin/cat', 01:26:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:32 INFO - 'PWD': '/builds/slave/test', 01:26:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:32 INFO - 'SHELL': '/bin/bash', 01:26:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JM7NFXgV8a/Listeners', 01:26:32 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 01:26:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:32 INFO - 'USER': 'cltbld', 01:26:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:32 INFO - 'XPC_FLAGS': '0x0', 01:26:32 INFO - 'XPC_SERVICE_NAME': '0', 01:26:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:32 INFO - Downloading/unpacking psutil>=3.1.1 01:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:26:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:26:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:26:36 INFO - Installing collected packages: psutil 01:26:36 INFO - Running setup.py install for psutil 01:26:36 INFO - building 'psutil._psutil_osx' extension 01:26:36 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 01:26:36 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 01:26:36 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 01:26:37 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 01:26:37 INFO - building 'psutil._psutil_posix' extension 01:26:37 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 01:26:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:26:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:26:37 INFO - ^ 01:26:37 INFO - 1 warning generated. 01:26:37 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 01:26:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:26:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:26:37 INFO - Successfully installed psutil 01:26:37 INFO - Cleaning up... 01:26:37 INFO - Return code: 0 01:26:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:26: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')]} 01:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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')]} 01:26:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10299c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a05cb570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10283ad30>, '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 0x102660db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102832b20>, '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.GN1SrflfaC/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.JM7NFXgV8a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 01:26: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:26: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 mozsystemmonitor==0.3 01:26:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GN1SrflfaC/Render', 01:26:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:37 INFO - 'HOME': '/Users/cltbld', 01:26:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:37 INFO - 'LOGNAME': 'cltbld', 01:26:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:37 INFO - 'MOZ_NO_REMOTE': '1', 01:26:37 INFO - 'NO_EM_RESTART': '1', 01:26:37 INFO - 'PAGER': '/bin/cat', 01:26:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:37 INFO - 'PWD': '/builds/slave/test', 01:26:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:37 INFO - 'SHELL': '/bin/bash', 01:26:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JM7NFXgV8a/Listeners', 01:26:37 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 01:26:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:37 INFO - 'USER': 'cltbld', 01:26:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:37 INFO - 'XPC_FLAGS': '0x0', 01:26:37 INFO - 'XPC_SERVICE_NAME': '0', 01:26:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:26: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 01:26: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 01:26: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 01:26: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 01:26: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 01:26: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 01:26:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:26:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:26:40 INFO - Installing collected packages: mozsystemmonitor 01:26:40 INFO - Running setup.py install for mozsystemmonitor 01:26:40 INFO - Successfully installed mozsystemmonitor 01:26:40 INFO - Cleaning up... 01:26:40 INFO - Return code: 0 01:26:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:40 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 0x102a26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10299c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a05cb570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10283ad30>, '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 0x102660db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102832b20>, '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.GN1SrflfaC/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.JM7NFXgV8a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 01:26:40 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 01:26:40 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 01:26:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GN1SrflfaC/Render', 01:26:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:40 INFO - 'HOME': '/Users/cltbld', 01:26:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:40 INFO - 'LOGNAME': 'cltbld', 01:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:40 INFO - 'MOZ_NO_REMOTE': '1', 01:26:40 INFO - 'NO_EM_RESTART': '1', 01:26:40 INFO - 'PAGER': '/bin/cat', 01:26:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:40 INFO - 'PWD': '/builds/slave/test', 01:26:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:40 INFO - 'SHELL': '/bin/bash', 01:26:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JM7NFXgV8a/Listeners', 01:26:40 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 01:26:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:40 INFO - 'USER': 'cltbld', 01:26:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:40 INFO - 'XPC_FLAGS': '0x0', 01:26:40 INFO - 'XPC_SERVICE_NAME': '0', 01:26:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:40 INFO - Downloading/unpacking jsonschema==2.5.1 01:26: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 01:26: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 01:26: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 01:26: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 01:26: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 01:26: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 01:26:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:26:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:26:43 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 01:26:43 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 01:26:43 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 01:26:43 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 01:26:43 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 01:26:43 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 01:26:43 INFO - Downloading functools32-3.2.3-2.tar.gz 01:26:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:26:43 INFO - warning: no files found matching '*.txt' 01:26:43 INFO - no previously-included directories found matching 'build' 01:26:43 INFO - no previously-included directories found matching 'dist' 01:26:43 INFO - no previously-included directories found matching '.git*' 01:26:43 INFO - Installing collected packages: jsonschema, functools32 01:26:43 INFO - Running setup.py install for functools32 01:26:44 INFO - warning: no files found matching '*.txt' 01:26:44 INFO - no previously-included directories found matching 'build' 01:26:44 INFO - no previously-included directories found matching 'dist' 01:26:44 INFO - no previously-included directories found matching '.git*' 01:26:44 INFO - Successfully installed jsonschema functools32 01:26:44 INFO - Cleaning up... 01:26:44 INFO - Return code: 0 01:26:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:44 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 0x102a26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10299c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a05cb570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10283ad30>, '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 0x102660db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102832b20>, '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.GN1SrflfaC/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.JM7NFXgV8a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 01:26:44 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 01:26:44 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 01:26:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GN1SrflfaC/Render', 01:26:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:44 INFO - 'HOME': '/Users/cltbld', 01:26:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:44 INFO - 'LOGNAME': 'cltbld', 01:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:44 INFO - 'MOZ_NO_REMOTE': '1', 01:26:44 INFO - 'NO_EM_RESTART': '1', 01:26:44 INFO - 'PAGER': '/bin/cat', 01:26:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:44 INFO - 'PWD': '/builds/slave/test', 01:26:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:44 INFO - 'SHELL': '/bin/bash', 01:26:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JM7NFXgV8a/Listeners', 01:26:44 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 01:26:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:44 INFO - 'USER': 'cltbld', 01:26:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:44 INFO - 'XPC_FLAGS': '0x0', 01:26:44 INFO - 'XPC_SERVICE_NAME': '0', 01:26:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:26:44 INFO - Cleaning up... 01:26:44 INFO - Return code: 0 01:26:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:44 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 0x102a26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10299c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a05cb570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10283ad30>, '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 0x102660db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102832b20>, '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.GN1SrflfaC/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.JM7NFXgV8a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 01:26:44 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 01:26:44 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 01:26:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GN1SrflfaC/Render', 01:26:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:44 INFO - 'HOME': '/Users/cltbld', 01:26:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:44 INFO - 'LOGNAME': 'cltbld', 01:26:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:44 INFO - 'MOZ_NO_REMOTE': '1', 01:26:44 INFO - 'NO_EM_RESTART': '1', 01:26:44 INFO - 'PAGER': '/bin/cat', 01:26:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:44 INFO - 'PWD': '/builds/slave/test', 01:26:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:44 INFO - 'SHELL': '/bin/bash', 01:26:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JM7NFXgV8a/Listeners', 01:26:44 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 01:26:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:44 INFO - 'USER': 'cltbld', 01:26:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:44 INFO - 'XPC_FLAGS': '0x0', 01:26:44 INFO - 'XPC_SERVICE_NAME': '0', 01:26:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:44 INFO - Downloading/unpacking blobuploader==1.2.4 01:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:47 INFO - Downloading blobuploader-1.2.4.tar.gz 01:26:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:26:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:26:47 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 01:26:47 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 01:26:47 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 01:26:47 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 01:26:47 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 01:26:47 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 01:26:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:26:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:48 INFO - Downloading docopt-0.6.1.tar.gz 01:26:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:26:48 INFO - Installing collected packages: blobuploader, requests, docopt 01:26:48 INFO - Running setup.py install for blobuploader 01:26:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:26:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:26:48 INFO - Running setup.py install for requests 01:26:49 INFO - Running setup.py install for docopt 01:26:49 INFO - Successfully installed blobuploader requests docopt 01:26:49 INFO - Cleaning up... 01:26:49 INFO - Return code: 0 01:26:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:49 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 0x102a26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10299c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a05cb570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10283ad30>, '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 0x102660db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102832b20>, '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.GN1SrflfaC/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.JM7NFXgV8a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 01:26:49 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 01:26:49 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 01:26:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GN1SrflfaC/Render', 01:26:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:49 INFO - 'HOME': '/Users/cltbld', 01:26:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:49 INFO - 'LOGNAME': 'cltbld', 01:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:49 INFO - 'MOZ_NO_REMOTE': '1', 01:26:49 INFO - 'NO_EM_RESTART': '1', 01:26:49 INFO - 'PAGER': '/bin/cat', 01:26:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:49 INFO - 'PWD': '/builds/slave/test', 01:26:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:49 INFO - 'SHELL': '/bin/bash', 01:26:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JM7NFXgV8a/Listeners', 01:26:49 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 01:26:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:49 INFO - 'USER': 'cltbld', 01:26:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:49 INFO - 'XPC_FLAGS': '0x0', 01:26:49 INFO - 'XPC_SERVICE_NAME': '0', 01:26:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:26:49 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-JmjcvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:26:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:26:49 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-zVNztg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:26:50 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-0ZRYck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:26:50 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-i7EHQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:26:50 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-KRGenx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:26:50 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-oHNhQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:26:50 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-I0_8Am-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:26:50 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-2PtN8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:26:50 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-PE5v9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:26:50 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ab1e8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:26:50 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-Jv2wyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:26:50 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-10yq2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:26:51 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-4MXMOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:26:51 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-9aS7Pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:26:51 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-Y4XKY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:26:51 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-nlg2tQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:26:51 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-0E0qOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:26:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:26:51 INFO - Running setup.py install for manifestparser 01:26:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:26:51 INFO - Running setup.py install for mozcrash 01:26:51 INFO - Running setup.py install for mozdebug 01:26:52 INFO - Running setup.py install for mozdevice 01:26:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:26:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:26:52 INFO - Running setup.py install for mozfile 01:26:52 INFO - Running setup.py install for mozhttpd 01:26:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:26:52 INFO - Running setup.py install for mozinfo 01:26:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:26:52 INFO - Running setup.py install for mozInstall 01:26:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:26:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:26:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:26:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:26:52 INFO - Running setup.py install for mozleak 01:26:52 INFO - Running setup.py install for mozlog 01:26:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:26:53 INFO - Running setup.py install for moznetwork 01:26:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:26:53 INFO - Running setup.py install for mozprocess 01:26:53 INFO - Running setup.py install for mozprofile 01:26:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:26:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:26:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:26:53 INFO - Running setup.py install for mozrunner 01:26:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:26:53 INFO - Running setup.py install for mozscreenshot 01:26:53 INFO - Running setup.py install for moztest 01:26:54 INFO - Running setup.py install for mozversion 01:26:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:26:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:26:54 INFO - Cleaning up... 01:26:54 INFO - Return code: 0 01:26:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:54 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 0x102a26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10299c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a05cb570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10283ad30>, '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 0x102660db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102832b20>, '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.GN1SrflfaC/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.JM7NFXgV8a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 01:26:54 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 01:26:54 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 01:26:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GN1SrflfaC/Render', 01:26:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:54 INFO - 'HOME': '/Users/cltbld', 01:26:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:54 INFO - 'LOGNAME': 'cltbld', 01:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:54 INFO - 'MOZ_NO_REMOTE': '1', 01:26:54 INFO - 'NO_EM_RESTART': '1', 01:26:54 INFO - 'PAGER': '/bin/cat', 01:26:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:54 INFO - 'PWD': '/builds/slave/test', 01:26:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:54 INFO - 'SHELL': '/bin/bash', 01:26:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JM7NFXgV8a/Listeners', 01:26:54 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 01:26:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:54 INFO - 'USER': 'cltbld', 01:26:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:54 INFO - 'XPC_FLAGS': '0x0', 01:26:54 INFO - 'XPC_SERVICE_NAME': '0', 01:26:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:26:54 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-e6kfoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:26:54 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)) 01:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:26:54 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ePvM49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:26:54 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-8dUiE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:26:54 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)) 01:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:26:54 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-i8CiJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:26:54 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)) 01:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:26:54 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-JZvP_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:26:54 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)) 01:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:26:54 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-gapEbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:26:55 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)) 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:26:55 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ek4HaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:26:55 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)) 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:26:55 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-FwHko9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:26:55 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)) 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:26:55 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-V_sQMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:26:55 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)) 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:26:55 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-wBaTqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:26:55 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)) 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:26:55 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-Zf1hbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:26:55 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)) 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:26:55 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-19mivX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:26:55 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)) 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:26:55 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-iMPaju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:26:55 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)) 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:26:55 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-_WyMQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:26:55 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-CxZOMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:26:56 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)) 01:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:26:56 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-0nTJzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:26:56 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)) 01:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:26:56 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-oxmzMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:26:56 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)) 01:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:26:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:26:56 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)) 01:26:56 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)) 01:26:56 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)) 01:26:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:26:56 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 01:26:56 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 01:26:56 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 01:26:56 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 01:26:56 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 01:26:56 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 01:26:58 INFO - Downloading blessings-1.6.tar.gz 01:26:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:26:58 INFO - Installing collected packages: blessings 01:26:58 INFO - Running setup.py install for blessings 01:26:59 INFO - Successfully installed blessings 01:26:59 INFO - Cleaning up... 01:26:59 INFO - Return code: 0 01:26:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:26:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:26:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:26:59 INFO - Reading from file tmpfile_stdout 01:26:59 INFO - Current package versions: 01:26:59 INFO - blessings == 1.6 01:26:59 INFO - blobuploader == 1.2.4 01:26:59 INFO - docopt == 0.6.1 01:26:59 INFO - functools32 == 3.2.3-2 01:26:59 INFO - jsonschema == 2.5.1 01:26:59 INFO - manifestparser == 1.1 01:26:59 INFO - mozInstall == 1.12 01:26:59 INFO - mozcrash == 0.17 01:26:59 INFO - mozdebug == 0.1 01:26:59 INFO - mozdevice == 0.48 01:26:59 INFO - mozfile == 1.2 01:26:59 INFO - mozhttpd == 0.7 01:26:59 INFO - mozinfo == 0.9 01:26:59 INFO - mozleak == 0.1 01:26:59 INFO - mozlog == 3.4 01:26:59 INFO - moznetwork == 0.27 01:26:59 INFO - mozprocess == 0.23 01:26:59 INFO - mozprofile == 0.28 01:26:59 INFO - mozrunner == 6.12 01:26:59 INFO - mozscreenshot == 0.1 01:26:59 INFO - mozsystemmonitor == 0.3 01:26:59 INFO - moztest == 0.7 01:26:59 INFO - mozversion == 1.4 01:26:59 INFO - psutil == 3.1.1 01:26:59 INFO - requests == 1.2.3 01:26:59 INFO - wsgiref == 0.1.2 01:26:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:26: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')]} 01:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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')]} 01:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:59 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 0x102a26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10299c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a05cb570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10283ad30>, '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 0x102660db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102832b20>, '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.GN1SrflfaC/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.JM7NFXgV8a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 01:26:59 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 01:26:59 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 01:26:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GN1SrflfaC/Render', 01:26:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:59 INFO - 'HOME': '/Users/cltbld', 01:26:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:59 INFO - 'LOGNAME': 'cltbld', 01:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:59 INFO - 'MOZ_NO_REMOTE': '1', 01:26:59 INFO - 'NO_EM_RESTART': '1', 01:26:59 INFO - 'PAGER': '/bin/cat', 01:26:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:59 INFO - 'PWD': '/builds/slave/test', 01:26:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:59 INFO - 'SHELL': '/bin/bash', 01:26:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JM7NFXgV8a/Listeners', 01:26:59 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 01:26:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:59 INFO - 'USER': 'cltbld', 01:26:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:59 INFO - 'XPC_FLAGS': '0x0', 01:26:59 INFO - 'XPC_SERVICE_NAME': '0', 01:26:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:59 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)) 01:26:59 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)) 01:26:59 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)) 01:26:59 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)) 01:26:59 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)) 01:26:59 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)) 01:26:59 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)) 01:26:59 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)) 01:26:59 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)) 01:26:59 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)) 01:26:59 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)) 01:26:59 INFO - Cleaning up... 01:26:59 INFO - Return code: 0 01:26:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:26: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')]} 01:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:00 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 0x102a26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10299c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3a05cb570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10283ad30>, '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 0x102660db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102832b20>, '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.GN1SrflfaC/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.JM7NFXgV8a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 01:27:00 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 01:27:00 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 01:27:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GN1SrflfaC/Render', 01:27:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:00 INFO - 'HOME': '/Users/cltbld', 01:27:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:00 INFO - 'LOGNAME': 'cltbld', 01:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:00 INFO - 'MOZ_NO_REMOTE': '1', 01:27:00 INFO - 'NO_EM_RESTART': '1', 01:27:00 INFO - 'PAGER': '/bin/cat', 01:27:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:00 INFO - 'PWD': '/builds/slave/test', 01:27:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:00 INFO - 'SHELL': '/bin/bash', 01:27:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JM7NFXgV8a/Listeners', 01:27:00 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 01:27:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:00 INFO - 'USER': 'cltbld', 01:27:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:00 INFO - 'XPC_FLAGS': '0x0', 01:27:00 INFO - 'XPC_SERVICE_NAME': '0', 01:27:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:27:01 INFO - Cleaning up... 01:27:01 INFO - Return code: 0 01:27:01 INFO - Running post-action listener: _resource_record_post_action 01:27:01 INFO - Running post-action listener: _start_resource_monitoring 01:27:01 INFO - Starting resource monitoring. 01:27:01 INFO - [mozharness: 2016-10-20 08:27:01.238054Z] Finished create-virtualenv step (success) 01:27:01 INFO - [mozharness: 2016-10-20 08:27:01.239163Z] Running install step. 01:27:01 INFO - Running pre-action listener: _resource_record_pre_action 01:27:01 INFO - Running main action method: install 01:27:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:01 INFO - Reading from file tmpfile_stdout 01:27:01 INFO - Detecting whether we're running mozinstall >=1.0... 01:27:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:27:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:27:01 INFO - Reading from file tmpfile_stdout 01:27:01 INFO - Output received: 01:27:01 INFO - Usage: mozinstall [options] installer 01:27:01 INFO - Options: 01:27:01 INFO - -h, --help show this help message and exit 01:27:01 INFO - -d DEST, --destination=DEST 01:27:01 INFO - Directory to install application into. [default: 01:27:01 INFO - "/builds/slave/test"] 01:27:01 INFO - --app=APP Application being installed. [default: firefox] 01:27:01 INFO - mkdir: /builds/slave/test/build/application 01:27:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:27:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 01:27:35 INFO - Reading from file tmpfile_stdout 01:27:35 INFO - Output received: 01:27:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:27:35 INFO - Running post-action listener: _resource_record_post_action 01:27:35 INFO - [mozharness: 2016-10-20 08:27:35.543144Z] Finished install step (success) 01:27:35 INFO - [mozharness: 2016-10-20 08:27:35.543304Z] Running run-tests step. 01:27:35 INFO - Running pre-action listener: _resource_record_pre_action 01:27:35 INFO - Running main action method: run_tests 01:27:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:27:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:27:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:27:35 INFO - 2016-10-20 01:27:35.557 screenresolution[1656:7645] starting screenresolution argv=screenresolution get 01:27:35 INFO - 2016-10-20 01:27:35.560 screenresolution[1656:7645] Display 0: 1600x1200x32@60 01:27:35 INFO - 2016-10-20 01:27:35.566 screenresolution[1657:7648] starting screenresolution argv=screenresolution list 01:27:35 INFO - Available Modes on Display 0 01:27:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:27:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:27:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:27:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:27:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:27:35 INFO - Intel Iris: 01:27:35 INFO - Chipset Model: Intel Iris 01:27:35 INFO - Type: GPU 01:27:35 INFO - Bus: Built-In 01:27:35 INFO - VRAM (Dynamic, Max): 1536 MB 01:27:35 INFO - Vendor: Intel (0x8086) 01:27:35 INFO - Device ID: 0x0a2e 01:27:35 INFO - Revision ID: 0x0009 01:27:35 INFO - Displays: 01:27:35 INFO - Display: 01:27:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:27:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:27:35 INFO - Display Serial Number: 1600x1200 60 01:27:35 INFO - Main Display: Yes 01:27:35 INFO - Mirror: Off 01:27:35 INFO - Online: Yes 01:27:35 INFO - Rotation: Supported 01:27:35 INFO - Return code: 0 01:27:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:27:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:27:35 INFO - Python 2.7.3 01:27:35 INFO - Return code: 0 01:27:35 INFO - grabbing minidump binary from tooltool 01:27:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:27:35 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 0x10283ad30>, '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 0x102660db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102832b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:27:35 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 01:27:35 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 01:27:35 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 01:27:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:27:35 INFO - Return code: 0 01:27:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:27:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:27:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:27:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:27:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:27:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--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 01:27:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0330 --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 01:27:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GN1SrflfaC/Render', 01:27:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:35 INFO - 'HOME': '/Users/cltbld', 01:27:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:35 INFO - 'LOGNAME': 'cltbld', 01:27:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:27:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:27:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:35 INFO - 'MOZ_NO_REMOTE': '1', 01:27:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:27:35 INFO - 'NO_EM_RESTART': '1', 01:27:35 INFO - 'PAGER': '/bin/cat', 01:27:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:35 INFO - 'PWD': '/builds/slave/test', 01:27:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:27:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:35 INFO - 'SHELL': '/bin/bash', 01:27:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JM7NFXgV8a/Listeners', 01:27:35 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 01:27:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:35 INFO - 'USER': 'cltbld', 01:27:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:35 INFO - 'XPC_FLAGS': '0x0', 01:27:35 INFO - 'XPC_SERVICE_NAME': '0', 01:27:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--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 01:27:36 INFO - mozversion application_buildid: 20161020002550 01:27:36 INFO - mozversion application_changeset: 99a239e1866a57f987b08dad796528e4ea30e622 01:27:36 INFO - mozversion application_display_name: Nightly 01:27:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:27:36 INFO - mozversion application_name: Firefox 01:27:36 INFO - mozversion application_remotingname: firefox 01:27:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 01:27:36 INFO - mozversion application_vendor: Mozilla 01:27:36 INFO - mozversion application_version: 52.0a1 01:27:36 INFO - mozversion platform_buildid: 20161020002550 01:27:36 INFO - mozversion platform_changeset: 99a239e1866a57f987b08dad796528e4ea30e622 01:27:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 01:27:36 INFO - mozversion platform_version: 52.0a1 01:27:36 INFO - using testdate: 1476952056 01:27:36 INFO - actual date: 1476952056 01:27:36 INFO - starting webserver on 'localhost:49273' 01:27:36 INFO - SUITE-START | Running 2 tests 01:27:36 INFO - TEST-START | dromaeo_css 01:27:36 INFO - operating with platform_type : mac_ 01:27:36 INFO - Initialising browser for dromaeo_css test... 01:27:36 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpa8SsVC/profile http://localhost:49273/getInfo.html) 01:27:38 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 01:27:38 INFO - PROCESS | 1667 | colorDepth:24 01:27:38 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 01:27:38 INFO - PROCESS | 1667 | __metrics 01:27:38 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:27:38 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:27:39 INFO - TEST-INFO | 1667: exit 0 01:27:39 INFO - Browser initialized. 01:27:39 INFO - Running cycle 1/1 for dromaeo_css test... 01:27:39 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpa8SsVC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:27:51 INFO - PROCESS | 1669 | RSS: Main: 238583808 01:27:51 INFO - PROCESS | 1669 | 01:28:42 INFO - PROCESS | 1669 | 1476952122811 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] 01:30:54 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 01:30:56 INFO - PROCESS | 1669 | RSS: Main: 352002048 01:30:56 INFO - PROCESS | 1669 | 01:34:00 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 01:34:00 INFO - PROCESS | 1669 | RSS: Main: 369577984 01:34:00 INFO - PROCESS | 1669 | 01:37:02 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 01:37:03 INFO - PROCESS | 1669 | RSS: Main: 337465344 01:37:03 INFO - PROCESS | 1669 | 01:40:05 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 01:40:06 INFO - PROCESS | 1669 | RSS: Main: 338284544 01:40:06 INFO - PROCESS | 1669 | 01:43:09 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 01:43:10 INFO - PROCESS | 1669 | RSS: Main: 327487488 01:43:10 INFO - PROCESS | 1669 | 01:46:13 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 01:46:13 INFO - PROCESS | 1669 | RSS: Main: 380338176 01:46:13 INFO - PROCESS | 1669 | 01:46:13 INFO - PROCESS | 1669 | __start_tp_report 01:46:13 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 01:46:13 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 01:46:13 INFO - PROCESS | 1669 | |i|pagename|runs| 01:46:13 INFO - PROCESS | 1669 | |0;dojo.html;3184.347208822191;3326.8502917368714;3334.9166270843225;3349.8660053597855;3389.068006298268;5619.100943848985;5717.428257174281;5742.253507044089;5823.941760582393;5835.824925252244;5717;5724.2558467399285;5814.854628634286;5815.331236907757;5827.592068555203;5131.204663277179;5154.149565321717;5182.455842136578;5226.738663066851;5237.7381130943495;5300.178472336794;5326.573874090063;5353.411124776271;5361.51746342829;5367.570594352462;4306.806193721259;5278.709670968095;5300.416954135042;5319.893602127955;5399.5680345572455;4521.59305662492;5099.643024988252;5208.921866172011;5227.947720522784;5231.032259032091;5142.614303927183;5211.947880521184;5222.582193424555;5227.398849132361;5229.816956406546;5717;5725.341585717654;5735.426457354273;5736.827895163152;5749.7700091996685;6139.285732132085;6163.476104531121;6167.383261673842;6182.196314479134;6205;5290.947090529084;6237.345078766755;6253.7185826637915;6265.624062556261;6299.149614801989;5787.652740835521;5851.531877449751;5854.560907931922;5862.443067908542;5867.413258674099;6133.891171610156;6197.628142311476;6202.5658203925295;6229.4393504584805;6234.844128896814;6095.649931565303;6113.2330707408255;6123.996209287264;6174.104754810617;6210.937890621126;6243.938530449835;6266.874662506815;6274;6288.213973253343;6292.370762923762;6019.909701354483;6202.317745048041;6269.780557680459;6269.905951410732;6270.686465676789;6097.969510152509;6178.474829639441;6204.81385558425;6266.530010249249;6278.6860656966965;4939.48135445773;4948.826791062367;4955.975220123876;4983.327250821165;4994.425641051253;4454.77726113693;4459.0858873930765;4460.933086003713;4483.14820184164;4504.369388285642;5598.216249725042;5696.544276457874;5726.198332233491;5813;5836.533077353802;6204.689765511706;6205.348438413902;6229.221347331583;6240.157578726814;6257.9687101564205;5871.56329126543;5883.14694369314;5900.085486749561;5907.232059832194;5914.970425147847;4776.976115119472;5213.8790160114995;5356.982173387044;5406.2701535292235;5428.83713488596;6240.625562466266;6244.469220116341;6252.343503932022;6311.400416960381;6359.141515895295;6190.969045154926;6210.285817130907;6213.689315534115;6227.782027629011;6230;6185.103160041859;6253.812385628438;6302.968485157545;6319.052142178526;6323.494120470259;6112.837854638657;6123.526657168115;6152.354002829638;6158.261008678987;6161.291451482957;6178.7219575120125;6225.750969961332;6246.063090536456;6250.468710159687;6254.874902502015;6117.480014198665;6148.016317389196;6164.260288765377;6167.044108163242;6170.290416601969;6063.939360606514;6103.298120716175;6147.047207682816;6155.47678447337;6159.414855588712;5851.356350801493;5918.733656985532;6030.487408570145;6037.426444487766;6107.358727333566;6100.9694951526735;6102.004547214632;6108.328083910677;6133.662648554372;6153.953827849186;5987.88024239504;5988.670623115596;6011;6035.728392222449;6048.758049677964 01:46:13 INFO - PROCESS | 1669 | |1;ext.html;443.70271917815063;476.91177132230564;481.94457637371715;518.8740433259826;537.8409527468306;40868;41630.54293099742;41674.791626041864;41742.58257417429;44788.55211447884;43354.56645433545;43658.34512482315;43739.56260437395;43741.562584374224;43933.340999885026;8637.3521985851;9537.47543885086;9725.902740972606;9728.951355243214;9731;8676.956615216915;11651.126165537577;11843.28940263587;11852.703682407922;12019.519219231264;11747.236429632047;11821.34982575958;11821.458386446911;11851;12003.519859205622;8740.169683880022;8924.509151996637;11760.705982350466;11824.054075673925;11824.467898944526;8740.213380795696;8801.691940782042;8816.515091669955;11413.828792568034;11722.941385293105;41476.79261603673;41874.37188442176;41893.371599426035;41901.79049104766;41971.37042944358;3536.5579302587175;3635.581908080578;3864.942025869614;4076.408920706481;4102.466679331697;30477.542836857465;31556.36887262265;31840.203994900083;31859.681403185903;32432.027039188863;3230.2731885325616;3251.528528363374;3265.314284000363;3351.748618853596;3398.09950363154;351835;352324;352862;352967;353233;57571.712141439864;57996.55008624818;60993.39006609966;61024.69487652533;61034.69482652647;64435.35564644387;64769.35230647633;64801;64936.675316623114;64989;13924.721505569833;14538.927305363406;15730.292136853868;16216.432424865308;16223.67552648964;11161.497732602053;11315.038221751243;11323.547058117747;12490.188137771016;12528.68678283032;16736.330546777994;16750.74873876893;16949.40677076321;17057.147142643065;17099.48701538931;6269.65516896566;6363.140975968185;6403.423691867754;6447;6485.627473713643;838.932885369169;944.9149576538144;957.3585697582637;1064.7284942339654;1079.3469950679882;26551.203463896112;27394;30049.549256761165;30238.54642180325;30959.380812383628;1062.8512008318842;1064.079571170947;1124.516457923101;1127.2841745491671;1130.3387518301886;15420.383184672712;15743.291551880193;15854.207289635473;15909.840901591067;16353.591160221089;188985;189309;190168;190598;193433;60003.39996600065;60232.39767602355;60518;60547.69726151341;60612.696936515036;1239.423667994369;1263.3051821498138;1350.156152404747;1378.23507953085;1387.5767890793204;29678.258043549085;30320.938767143045;31832.8408357966;32164.678353216168;32250.559194759473;42789.57210427856;52410;52599;52620.73689631528;52789.736051319494;430828;432130;432858;434091;434949;26078.34804129912;26792.866035669696;26869.86565067162;27892.721072789012;41365.793171033954;48004.29728882838;48016.519834801205;48501.27248091352;48631;49176.50823491719;36234.637653623126;39656;39772.80113599414;40646.06727535262;42199;39416;39599.54845226012;40730;40868.79565602153;41171.38242926419;38134.046648834;38832.41751373675;38962;39287.41068883912;39524;33750.324993499504;34493.310133797684;35306.82346588251;35400;35554;25289;25635.615465767656;25903.870480647474;25938.74061259439;26197.476050479265 01:46:13 INFO - PROCESS | 1669 | |2;jquery.html;351.60093294111186;356.7271037656195;377.4828484975582;385.28337292635695;409.14080431094703;14201.715965680693;14713.926430367846;14743.115413075224;20007.399778006646;20569.69145462819;20182.495437614067;20280.492987675316;20734.68897966528;20922.37232883016;21009;5227.817026404076;5820.883582328351;6157.538184636148;6165.198524191871;6167.475764559996;5967.075103358986;6071.180390647272;6104.664243466607;6117.143599896018;6118.29639591446;5757.251557297566;6038.124471951564;6056.273247210341;6056.303525094627;6065.150878876961;5407.810726624569;6021.187139736124;6023.36754640763;6031.8793624127275;6039.909401358983;4985.35190425246;5136.04983078128;5205.6876587404495;5212.009718153538;5227.555657769094;20081.39755807328;24874.5025099497;25083.37291567707;25495.745042549523;25542.489150217076;2058.5677007828376;2114.5770845830743;2142.892855357226;2148.0655914677163;2606.4005278785794;9727.854082188773;9885.604575816958;10260.74348141295;13288.936885049174;14348.569542913729;1830.3959693301179;1835.4034938644993;1857.6841936870765;1904.7142928560688;1983.8710483818506;123337;127932;128093.35953320267;129009.35495322557;129425.05862412072;11308.49111789955;11369.602063927723;11484.88515114838;11577.768444631229;11761.647150585497;14622.780658290134;14644.341004654816;14668.193249371174;14676.192809395585;14680.486182983544;8226.30076443509;8383.62273697673;8399.748007559783;8415.621297041647;8490.278326342206;8261.586920653943;8295.170482951657;8328.958355208186;8395.160483951679;8447.788805279793;4510.488986189597;4534.070515544371;4555.157295900269;4557.931631025537;4601.332806743004;1881.9298771351678;1929.1318906492136;1929.218666440066;1938.044482164682;1963.2735887721635;841.6338892581747;932.6409332407072;971.6113554578226;977.2328721953232;1003.8494225866033;19526.5118372039;21138.682919756215;21215.257465988925;21328.14687412486;21467.892660536596;883.4390162246891;883.4655033704645;945.4941606240628;951.6526467839188;953.5613617735919;10371.688849334532;11020.118390528898;11083.334999900031;11087.556497740161;11105.944470277596;538138;578337;581324;594899;599531;74515;124027;126657.73342266644;127595;127811;766.8888011238339;954.7756277274974;994.4878387630376;995.3027296027701;996.4121168510469;18256.543586410447;18392;18454.907725461286;18457.81542184561;18571.44285671432;41102.58897410987;44736.552634473235;51183.23225151551;52944.94110117699;54578.72710636421;76551.46897061916;77162;79871.60064199643;80212.3957520858;80657.19342806497;7806.141324454192;7813.413993950363;8047.315978141689;8089.555074470961;8252.298554622925;12132.575359862363;12161.39193040362;12208.145429819828;12379.133460657667;12871.356432178542;10933.125349972142;11165;11347.829782553434;11431.428428578703;11448.885511144781;10882.347059176474;10885.510152043336;11095.89041095884;11346.602868899548;11387.772244555228;10853.240273181118;11327.263727857662;11395.943020284845;11435.428228588704;11658.70853228676;6793.320667933167;7556.357709594746;7964.522128672298;8162.918370816454;8240.711575094843;10617.681469555926;10961.84900585427;11216.943915280372;11241.55033798639;11668.241564298294 01:46:13 INFO - PROCESS | 1669 | |3;mootools.html;1370.540868808949;1521.239380309845;1544.4131230132548;1561.195984068205;1726.361246338854;7739.690412383505;8245.216704413078;8365.539895305748;8738.300935925126;8759.036505984337;8618.396712230146;8697.52163631;8718.215360617543;8721.825563488726;8782.121787821216;2372.620380739082;2378.310290015902;2403.146882856587;2403.2790162951133;2409.626507891271;2011.6781314989605;2390.5816482115647;2401.2916189724074;2407.711074671033;2409.0484258717797;2370.300761275419;2385.9880700596473;2395.4849707312924;2400.975990240093;2403.42317843718;2342.156823543523;2371.7865392114704;2381.0951838301335;2385.7256415512265;2416.6012607919674;2259.130234859591;2344.2381226101584;2356.599378105726;2356.646503024543;2357.4577847095265;7833.764987050397;8093.150219226955;8713.7821554461;8731.781705457415;8834.819464992597;5153.845384638429;5320.441353657849;5327.946720532823;5357.249985002103;5376.919346209771;3605.4591811228265;3629.836657350399;3697.1126929536986;3703.4444833274956;3715.7213209009433;1079.0558261521169;1082.5345101606306;1093.3330668292335;1100.664297389296;1102.9338239705562;11462.598809041643;11597.594084207096;11602.651920442484;11612.18714689972;11615.186936914419;4530.977345113253;4711.599514041276;4809.495003024632;4867.70793752376;4869.391326084239;9870.161036311994;9886.209103271722;9888.604455821687;9892.752681183028;9913.405195688281;5349.919751203735;5351.224072509464;5354.223637572608;5362.410134885159;5362.758675859595;4690.953090469052;4831.227003679394;4908.582770464479;4915.238138088602;4922.58158056562;3372.8144952027874;3739.962600374016;3769.9434508482395;3803.980980095137;3838.251540949544;1162.8953394194564;1166.1545379599893;1181.2381014245934;1182.6629410617932;1186.4720199511307;802.5385403393072;823.7487566292218;896.9058248883773;935.8502639577634;951.8524628682566;5247.737613119329;5677.772889084473;5849.537615596101;5925.585209035326;6051.394860513913;815.1237419773648;928.5450129436528;940.5250348574064;945.6737425588161;995.8904520502739;3803.505544279226;3908.687305015592;3913.2173565287057;4007.198560287954;4021.4571032910767;9861.753456163653;10183.236257280734;10328.380297182193;10376.533056012497;10681.946590267155;9745.902540974499;10264.230182736324;10754.677359679223;10764.977327153907;10770.192235582363;7831.882521762178;8074.838503229903;8187.95906020478;8268.214519620626;8300.543470109082;4992.726854652041;5649.20911072442;5738.598298119092;5833.745744664918;5846.503047240862;10002.349847259904;10031.097201251923;10032.347897386646;10042.297039207186;10070.69787906364;9755.9024409755;10046.397216167054;10112.988701129829;10286.845697314697;10314.123299519624;6938.722451102101;7092.25531319204;7124.750633727795;7182.138143422822;7187.460940429593;8198.959005204935;8269.586520674062;8365.749027529184;8376.664933402595;8378.790530236793;7319.158296795724;7506.361959233526;7566.205548417337;7601.049868766404;7626.313631773167;7316.158641756266;7413.703451861865;7424.294692004251;7425.8143546411775;7468;6882.414994725505;6981.685824138028;6982.33667801558;6998.860022799414;7013.368796808311;4825.734584597741;4959.528844759742;4997.005988023952;5018.548330650259;5114.23286507012;4897.632677549269;4963.106640804545;5045.697258164522;5073.797048118182;5157.303763991963 01:46:14 INFO - PROCESS | 1669 | |4;prototype.html;300.8495752123938;303.7554768411431;309.34882073235843;315.23398142513685;317.3747716997514;4661.881148524355;5377.274068000819;5505.421930697279;5505.889882202364;5520.365158006831;4163.522703991128;4562.726236425817;5184.637075404723;5191.766370513326;5367.765413954793;3185.8407079645926;3836.5971572984854;3918.5689574146822;4116.855910043149;4154.6676265898805;4071.9592804071876;4085.5301640311445;4086.4687590613325;4121.887090485567;4124.298869192245;3790.3366910790637;3955.8242966368525;4099.3850922361735;4108.650764685005;4117.711760176789;4045.7572545647063;4102.774347410891;4107.835686572564;4108.301588729938;4135.73117747347;2763.6821765497025;2941.7646588272887;3528.417811061172;3548.4741561128703;3564.536610240678;4818.759062046884;4934.432540257844;5001.624878134118;5076.746162691888;5214.921776173361;4419.4808958893445;4473.552644735533;4587.266037434028;4668.6731928765;4765.928511072337;5673.546116310686;5708.600397972144;5717.914231286534;5734.655920644775;5735.655860648374;3888.1616420013265;3910.687145028391;3934.6852251819787;3943.369060950234;3967.8809635710977;5544.334679838405;5806.883862322772;5857.589968702193;5860.560457965669;5865.451401901831;3977.573680055164;3982.661473774762;3985.8206380712936;3991.6806655467494;4008.018035581243;3569.714422846166;3706.573744019472;3759.984804102932;4051.91896162081;4103.435352904435;3953.802309884494;4018.9196216076098;4643.814247430065;4697.694649847749;4752.239641657318;4758.214894542431;4969.652124351333;5580.832575022756;5600.971995140079;5613.456299517666;1179.6696924861053;1332.9133606315559;1372.8009438631343;1386.2860626777217;1395.6371343450776;850.6512329944693;873.9737807865774;878.6661068793945;880.4497189256715;881.5504092912532;4718.575328220477;4745.905081898342;5061.2661164131;5270.811159248599;5448.455154484599;5394.437642119961;5660.72633657432;5755.942440575541;5758.251427314506;5760.711964401763;4420.557944205618;4884.7069175849565;4959.950400496021;5274.12976858814;5321.414644389191;2496.563101457274;2630.7500787425165;2935.7963235073516;3002.609660744089;3022.2897619059504;4979.887570268582;5567.409021181315;5904.586678932519;5922.200502932135;6007.249093863267;5845.707714614252;5878.470937615635;5888.705564721832;5896.64620122794;5973.970130149321;5158.406783219828;5696.034867970544;5852.912206316993;5890.410958904075;5909;5265.842024739264;5685.374608792947;5773.278340207474;5820.796272130455;5826.213461182686;4814.350062741625;5675.035244008446;5688.40271771458;5690.544756419559;5698.572607054404;5701.828945131487;5947.442599682178;5996.421538738385;6055.455009049207;6076.781974579335;3318.784279021856;3835.9041023974623;3849.634284742945;3859.3053250415196;3875.224955008953;5642.943570564242;5799.9130013049;5858.297004359504;5896.823095307148;5968.850778730566;4653.302004699322;5255.842324730264;5700.916825803084;5746.454086861741;5820.708964551671;4758.881027974328;5695.401982791913;5701.287339082614;5711.171880077282;5724.484796368347;5246.871922536673;5526.253955715927;5569.470900264469;5569.55443564522;5579.860503487445;4858.3441235432765;4868.464468908346;4952.1581331173065;5392.460753924576;5505.642133261325;4821.179609928091;5525.336959564878;5526.032944234823;5538.695371754431;5576.637518561281 01:46:14 INFO - PROCESS | 1669 | |5;yui.html;712.4905692429915;741.7255615422295;741.881020955408;791.1179035375554;827.8634025385813;15173.696526069472;15916.681666366665;15924.44264450743;16002.039877607323;16209.918950405261;14214.644633884158;14326.283685815719;14338.139711617285;14363.497277595287;14370;712.654362634122;735.0811485642946;737.1228238396318;739.5303981971433;905.1220316293202;737.634870738985;742.8737114690489;884.7655371326604;915.0849150849151;921.0053142606002;960.140674096683;966.734148109272;974.4056125763278;975.4050029482011;978.3542861711272;963.2438535749438;966.9661561845372;967.4098799732158;967.5936106835181;970.4904924914476;564.8446677163772;583.6468936293523;701.6316433872258;724.2250791652933;727.4144313827367;14692.485762998243;15093.320800563892;15151.469698560608;15155.166465844373;15159.166245856584;13237.669058273525;13454;13553.525626603021;13599.728005439934;14047.929760351133;10707.196960227937;12375.62873113808;12489.93755031219;12653.051021173356;12965.546205882749;4433.290673492258;4465.620422264112;4475.820967161309;4476.7090139140855;4541.523140070278;115102;115224;115355;116463;116639.4168029163;10323.225758068174;10943.124550035978;10978.451077446096;11001.504932278067;11147.609833655782;20560.691589626167;21387;21433.035513401934;21433.249836255658;21575.35273941784;6566.409023187936;7529.924700753032;7660.578668173304;7761.650725717356;8062.072861620872;4141.4409054777825;4299.333603291432;4355.8475453359615;4408.140412619508;4444.977775111104;3597.5143355647174;3757.5678796938155;3758.3047136279874;3759.36467636579;3800.258949504875;614.9754009839646;620.6493331267819;625.8779537990139;639.6961443314389;640.4588123036027;581.3692144023781;650.8763334966342;655.4690700532584;695.1484431571284;698.9825254368682;8445.619947102396;9463.952680236554;9488.525573721286;9665.468399237969;9700.27247956406;499.4378836568381;624.4910398025595;649.9902501462482;680.0547239337325;695.9582425054411;4425.822967081345;4639.2113340731485;4742.810287588527;4783.545563171493;4790.449098353734;158061.2096939531;158734;161654;162328;168019;127608.72391276155;129421;129582.35208823896;130239.99999999811;132219;104622;105505.94494055114;109631;110505.23736906481;110943;8560.48637081777;8998.280137589109;9003.81992360136;9006;9158.67944621935;41504;47819.76090119527;59197.70401147967;62203.688981554806;65347.34652653603;61617.691911540154;70756;71675;89725;91083;13208.18501799682;13288.468461261442;13363.933180334036;13380.531681391105;13390;12712.427940742504;14563.199024053354;14891.329890154753;15153.469628562947;15211.467598633995;13277;13320.33398330099;14139.222342771247;14338.354774035402;14341.99606027568;13512.189268643913;13754.174749515061;13867.445302188204;14185.645358866112;14296.285185740464;13176.407061682055;13424.39590218462;13534.551231136056;13714.93142534281;13962.232077235849;11425.2002359834;11497.540098396305;11614.941925290319;11624.94187529057;11652.065205528555;11259.324440533594;11320.716982075513;11498.885011149781;11510.712232193928;11544.249623774427 01:46:14 INFO - PROCESS | 1669 | __end_tp_report 01:46:14 INFO - PROCESS | 1669 | __start_cc_report 01:46:14 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,2377 01:46:14 INFO - PROCESS | 1669 | __end_cc_report 01:46:14 INFO - PROCESS | 1669 | __startTimestamp1476953173988__endTimestamp 01:46:14 INFO - PROCESS | 1669 | 01:46:14 INFO - PROCESS | 1669 | ------- Summary: start ------- 01:46:14 INFO - PROCESS | 1669 | Number of tests: 6 01:46:14 INFO - PROCESS | 1669 | 01:46:14 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5748.00 Median:6030.49 stddev:651.31 (10.8%) stddev-sans-first:621.48 01:46:14 INFO - PROCESS | 1669 | Values: 3184.3 3326.9 3334.9 3349.9 3389.1 5619.1 5717.4 5742.3 5823.9 5835.8 5717.0 5724.3 5814.9 5815.3 5827.6 5131.2 5154.1 5182.5 5226.7 5237.7 5300.2 5326.6 5353.4 5361.5 5367.6 4306.8 5278.7 5300.4 5319.9 5399.6 4521.6 5099.6 5208.9 5227.9 5231.0 5142.6 5211.9 5222.6 5227.4 5229.8 5717.0 5725.3 5735.4 5736.8 5749.8 6139.3 6163.5 6167.4 6182.2 6205.0 5290.9 6237.3 6253.7 6265.6 6299.1 5787.7 5851.5 5854.6 5862.4 5867.4 6133.9 6197.6 6202.6 6229.4 6234.8 6095.6 6113.2 6124.0 6174.1 6210.9 6243.9 6266.9 6274.0 6288.2 6292.4 6019.9 6202.3 6269.8 6269.9 6270.7 6098.0 6178.5 6204.8 6266.5 6278.7 4939.5 4948.8 4956.0 4983.3 4994.4 4454.8 4459.1 4460.9 4483.1 4504.4 5598.2 5696.5 5726.2 5813.0 5836.5 6204.7 6205.3 6229.2 6240.2 6258.0 5871.6 5883.1 5900.1 5907.2 5915.0 4777.0 5213.9 5357.0 5406.3 5428.8 6240.6 6244.5 6252.3 6311.4 6359.1 6191.0 6210.3 6213.7 6227.8 6230.0 6185.1 6253.8 6303.0 6319.1 6323.5 6112.8 6123.5 6152.4 6158.3 6161.3 6178.7 6225.8 6246.1 6250.5 6254.9 6117.5 6148.0 6164.3 6167.0 6170.3 6063.9 6103.3 6147.0 6155.5 6159.4 5851.4 5918.7 6030.5 6037.4 6107.4 6101.0 6102.0 6108.3 6133.7 6154.0 5987.9 5988.7 6011.0 6035.7 6048.8 01:46:14 INFO - PROCESS | 1669 | 01:46:14 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:50542.41 Median:35894.32 stddev:89978.77 (250.7%) stddev-sans-first:90152.56 01:46:14 INFO - PROCESS | 1669 | Values: 443.7 476.9 481.9 518.9 537.8 40868.0 41630.5 41674.8 41742.6 44788.6 43354.6 43658.3 43739.6 43741.6 43933.3 8637.4 9537.5 9725.9 9729.0 9731.0 8677.0 11651.1 11843.3 11852.7 12019.5 11747.2 11821.3 11821.5 11851.0 12003.5 8740.2 8924.5 11760.7 11824.1 11824.5 8740.2 8801.7 8816.5 11413.8 11722.9 41476.8 41874.4 41893.4 41901.8 41971.4 3536.6 3635.6 3864.9 4076.4 4102.5 30477.5 31556.4 31840.2 31859.7 32432.0 3230.3 3251.5 3265.3 3351.7 3398.1 351835.0 352324.0 352862.0 352967.0 353233.0 57571.7 57996.6 60993.4 61024.7 61034.7 64435.4 64769.4 64801.0 64936.7 64989.0 13924.7 14538.9 15730.3 16216.4 16223.7 11161.5 11315.0 11323.5 12490.2 12528.7 16736.3 16750.7 16949.4 17057.1 17099.5 6269.7 6363.1 6403.4 6447.0 6485.6 838.9 944.9 957.4 1064.7 1079.3 26551.2 27394.0 30049.5 30238.5 30959.4 1062.9 1064.1 1124.5 1127.3 1130.3 15420.4 15743.3 15854.2 15909.8 16353.6 188985.0 189309.0 190168.0 190598.0 193433.0 60003.4 60232.4 60518.0 60547.7 60612.7 1239.4 1263.3 1350.2 1378.2 1387.6 29678.3 30320.9 31832.8 32164.7 32250.6 42789.6 52410.0 52599.0 52620.7 52789.7 430828.0 432130.0 432858.0 434091.0 434949.0 26078.3 26792.9 26869.9 27892.7 41365.8 48004.3 48016.5 48501.3 48631.0 49176.5 36234.6 39656.0 39772.8 40646.1 42199.0 39416.0 39599.5 40730.0 40868.8 41171.4 38134.0 38832.4 38962.0 39287.4 39524.0 33750.3 34493.3 35306.8 35400.0 35554.0 25289.0 25635.6 25903.9 25938.7 26197.5 01:46:14 INFO - PROCESS | 1669 | 01:46:14 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:34223.78 Median:25519.12 stddev:96814.94 (379.4%) stddev-sans-first:97053.13 01:46:14 INFO - PROCESS | 1669 | Values: 351.6 356.7 377.5 385.3 409.1 14201.7 14713.9 14743.1 20007.4 20569.7 20182.5 20280.5 20734.7 20922.4 21009.0 5227.8 5820.9 6157.5 6165.2 6167.5 5967.1 6071.2 6104.7 6117.1 6118.3 5757.3 6038.1 6056.3 6056.3 6065.2 5407.8 6021.2 6023.4 6031.9 6039.9 4985.4 5136.0 5205.7 5212.0 5227.6 20081.4 24874.5 25083.4 25495.7 25542.5 2058.6 2114.6 2142.9 2148.1 2606.4 9727.9 9885.6 10260.7 13288.9 14348.6 1830.4 1835.4 1857.7 1904.7 1983.9 123337.0 127932.0 128093.4 129009.4 129425.1 11308.5 11369.6 11484.9 11577.8 11761.6 14622.8 14644.3 14668.2 14676.2 14680.5 8226.3 8383.6 8399.7 8415.6 8490.3 8261.6 8295.2 8329.0 8395.2 8447.8 4510.5 4534.1 4555.2 4557.9 4601.3 1881.9 1929.1 1929.2 1938.0 1963.3 841.6 932.6 971.6 977.2 1003.8 19526.5 21138.7 21215.3 21328.1 21467.9 883.4 883.5 945.5 951.7 953.6 10371.7 11020.1 11083.3 11087.6 11105.9 538138.0 578337.0 581324.0 594899.0 599531.0 74515.0 124027.0 126657.7 127595.0 127811.0 766.9 954.8 994.5 995.3 996.4 18256.5 18392.0 18454.9 18457.8 18571.4 41102.6 44736.6 51183.2 52944.9 54578.7 76551.5 77162.0 79871.6 80212.4 80657.2 7806.1 7813.4 8047.3 8089.6 8252.3 12132.6 12161.4 12208.1 12379.1 12871.4 10933.1 11165.0 11347.8 11431.4 11448.9 10882.3 10885.5 11095.9 11346.6 11387.8 10853.2 11327.3 11395.9 11435.4 11658.7 6793.3 7556.4 7964.5 8162.9 8240.7 10617.7 10961.8 11216.9 11241.6 11668.2 01:46:14 INFO - PROCESS | 1669 | 01:46:14 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5659.16 Median:4977.92 stddev:3152.87 (63.3%) stddev-sans-first:3145.24 01:46:14 INFO - PROCESS | 1669 | Values: 1370.5 1521.2 1544.4 1561.2 1726.4 7739.7 8245.2 8365.5 8738.3 8759.0 8618.4 8697.5 8718.2 8721.8 8782.1 2372.6 2378.3 2403.1 2403.3 2409.6 2011.7 2390.6 2401.3 2407.7 2409.0 2370.3 2386.0 2395.5 2401.0 2403.4 2342.2 2371.8 2381.1 2385.7 2416.6 2259.1 2344.2 2356.6 2356.6 2357.5 7833.8 8093.2 8713.8 8731.8 8834.8 5153.8 5320.4 5327.9 5357.2 5376.9 3605.5 3629.8 3697.1 3703.4 3715.7 1079.1 1082.5 1093.3 1100.7 1102.9 11462.6 11597.6 11602.7 11612.2 11615.2 4531.0 4711.6 4809.5 4867.7 4869.4 9870.2 9886.2 9888.6 9892.8 9913.4 5349.9 5351.2 5354.2 5362.4 5362.8 4691.0 4831.2 4908.6 4915.2 4922.6 3372.8 3740.0 3769.9 3804.0 3838.3 1162.9 1166.2 1181.2 1182.7 1186.5 802.5 823.7 896.9 935.9 951.9 5247.7 5677.8 5849.5 5925.6 6051.4 815.1 928.5 940.5 945.7 995.9 3803.5 3908.7 3913.2 4007.2 4021.5 9861.8 10183.2 10328.4 10376.5 10681.9 9745.9 10264.2 10754.7 10765.0 10770.2 7831.9 8074.8 8188.0 8268.2 8300.5 4992.7 5649.2 5738.6 5833.7 5846.5 10002.3 10031.1 10032.3 10042.3 10070.7 9755.9 10046.4 10113.0 10286.8 10314.1 6938.7 7092.3 7124.8 7182.1 7187.5 8199.0 8269.6 8365.7 8376.7 8378.8 7319.2 7506.4 7566.2 7601.0 7626.3 7316.2 7413.7 7424.3 7425.8 7468.0 6882.4 6981.7 6982.3 6998.9 7013.4 4825.7 4959.5 4997.0 5018.5 5114.2 4897.6 4963.1 5045.7 5073.8 5157.3 01:46:14 INFO - PROCESS | 1669 | 01:46:14 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4557.91 Median:5031.45 stddev:1414.60 (28.1%) stddev-sans-first:1382.01 01:46:14 INFO - PROCESS | 1669 | Values: 300.8 303.8 309.3 315.2 317.4 4661.9 5377.3 5505.4 5505.9 5520.4 4163.5 4562.7 5184.6 5191.8 5367.8 3185.8 3836.6 3918.6 4116.9 4154.7 4072.0 4085.5 4086.5 4121.9 4124.3 3790.3 3955.8 4099.4 4108.7 4117.7 4045.8 4102.8 4107.8 4108.3 4135.7 2763.7 2941.8 3528.4 3548.5 3564.5 4818.8 4934.4 5001.6 5076.7 5214.9 4419.5 4473.6 4587.3 4668.7 4765.9 5673.5 5708.6 5717.9 5734.7 5735.7 3888.2 3910.7 3934.7 3943.4 3967.9 5544.3 5806.9 5857.6 5860.6 5865.5 3977.6 3982.7 3985.8 3991.7 4008.0 3569.7 3706.6 3760.0 4051.9 4103.4 3953.8 4018.9 4643.8 4697.7 4752.2 4758.2 4969.7 5580.8 5601.0 5613.5 1179.7 1332.9 1372.8 1386.3 1395.6 850.7 874.0 878.7 880.4 881.6 4718.6 4745.9 5061.3 5270.8 5448.5 5394.4 5660.7 5755.9 5758.3 5760.7 4420.6 4884.7 4960.0 5274.1 5321.4 2496.6 2630.8 2935.8 3002.6 3022.3 4979.9 5567.4 5904.6 5922.2 6007.2 5845.7 5878.5 5888.7 5896.6 5974.0 5158.4 5696.0 5852.9 5890.4 5909.0 5265.8 5685.4 5773.3 5820.8 5826.2 4814.4 5675.0 5688.4 5690.5 5698.6 5701.8 5947.4 5996.4 6055.5 6076.8 3318.8 3835.9 3849.6 3859.3 3875.2 5642.9 5799.9 5858.3 5896.8 5968.9 4653.3 5255.8 5700.9 5746.5 5820.7 4758.9 5695.4 5701.3 5711.2 5724.5 5246.9 5526.3 5569.5 5569.6 5579.9 4858.3 4868.5 4952.2 5392.5 5505.6 4821.2 5525.3 5526.0 5538.7 5576.6 01:46:14 INFO - PROCESS | 1669 | 01:46:14 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:24807.13 Median:3677.54 stddev:40357.12 (1097.4%) stddev-sans-first:40429.78 01:46:14 INFO - PROCESS | 1669 | Values: 712.5 741.7 741.9 791.1 827.9 15173.7 15916.7 15924.4 16002.0 16209.9 14214.6 14326.3 14338.1 14363.5 14370.0 712.7 735.1 737.1 739.5 905.1 737.6 742.9 884.8 915.1 921.0 960.1 966.7 974.4 975.4 978.4 963.2 967.0 967.4 967.6 970.5 564.8 583.6 701.6 724.2 727.4 14692.5 15093.3 15151.5 15155.2 15159.2 13237.7 13454.0 13553.5 13599.7 14047.9 10707.2 12375.6 12489.9 12653.1 12965.5 4433.3 4465.6 4475.8 4476.7 4541.5 115102.0 115224.0 115355.0 116463.0 116639.4 10323.2 10943.1 10978.5 11001.5 11147.6 20560.7 21387.0 21433.0 21433.2 21575.4 6566.4 7529.9 7660.6 7761.7 8062.1 4141.4 4299.3 4355.8 4408.1 4445.0 3597.5 3757.6 3758.3 3759.4 3800.3 615.0 620.6 625.9 639.7 640.5 581.4 650.9 655.5 695.1 699.0 8445.6 9464.0 9488.5 9665.5 9700.3 499.4 624.5 650.0 680.1 696.0 4425.8 4639.2 4742.8 4783.5 4790.4 158061.2 158734.0 161654.0 162328.0 168019.0 127608.7 129421.0 129582.4 130240.0 132219.0 104622.0 105505.9 109631.0 110505.2 110943.0 8560.5 8998.3 9003.8 9006.0 9158.7 41504.0 47819.8 59197.7 62203.7 65347.3 61617.7 70756.0 71675.0 89725.0 91083.0 13208.2 13288.5 13363.9 13380.5 13390.0 12712.4 14563.2 14891.3 15153.5 15211.5 13277.0 13320.3 14139.2 14338.4 14342.0 13512.2 13754.2 13867.4 14185.6 14296.3 13176.4 13424.4 13534.6 13714.9 13962.2 11425.2 11497.5 11614.9 11624.9 11652.1 11259.3 11320.7 11498.9 11510.7 11544.2 01:46:14 INFO - PROCESS | 1669 | -------- Summary: end -------- 01:46:14 INFO - PROCESS | 1669 | 01:46:14 INFO - TEST-INFO | 1669: exit 0 01:46:14 INFO - TEST-OK | dromaeo_css | took 1118485ms 01:46:14 INFO - TEST-START | kraken 01:46:14 INFO - operating with platform_type : mac_ 01:46:14 INFO - Initialising browser for kraken test... 01:46:14 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpXEAviO/profile http://localhost:49273/getInfo.html) 01:46:16 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 01:46:16 INFO - PROCESS | 1677 | colorDepth:24 01:46:16 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 01:46:16 INFO - PROCESS | 1677 | __metrics 01:46:17 INFO - PROCESS | 1677 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:46:17 INFO - PROCESS | 1677 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:46:17 INFO - TEST-INFO | 1677: exit 0 01:46:17 INFO - Browser initialized. 01:46:17 INFO - Running cycle 1/1 for kraken test... 01:46:17 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpXEAviO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:46:29 INFO - PROCESS | 1678 | RSS: Main: 231473152 01:46:29 INFO - PROCESS | 1678 | 01:46:32 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 01:46:33 INFO - PROCESS | 1678 | RSS: Main: 290246656 01:46:33 INFO - PROCESS | 1678 | 01:46:36 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 01:46:36 INFO - PROCESS | 1678 | RSS: Main: 291540992 01:46:36 INFO - PROCESS | 1678 | 01:46:40 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 01:46:40 INFO - PROCESS | 1678 | RSS: Main: 386498560 01:46:40 INFO - PROCESS | 1678 | 01:46:42 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 01:46:43 INFO - PROCESS | 1678 | RSS: Main: 285503488 01:46:43 INFO - PROCESS | 1678 | 01:46:45 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:46:45 INFO - PROCESS | 1678 | RSS: Main: 290627584 01:46:45 INFO - PROCESS | 1678 | 01:46:48 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 01:46:49 INFO - PROCESS | 1678 | RSS: Main: 328347648 01:46:49 INFO - PROCESS | 1678 | 01:46:52 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 01:46:52 INFO - PROCESS | 1678 | RSS: Main: 329568256 01:46:52 INFO - PROCESS | 1678 | 01:46:55 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 01:46:56 INFO - PROCESS | 1678 | RSS: Main: 326778880 01:46:56 INFO - PROCESS | 1678 | 01:46:57 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:46:58 INFO - PROCESS | 1678 | RSS: Main: 302739456 01:46:58 INFO - PROCESS | 1678 | 01:47:00 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:47:01 INFO - PROCESS | 1678 | RSS: Main: 306479104 01:47:01 INFO - PROCESS | 1678 | 01:47:03 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:47:03 INFO - PROCESS | 1678 | RSS: Main: 308875264 01:47:03 INFO - PROCESS | 1678 | 01:47:06 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:47:07 INFO - PROCESS | 1678 | RSS: Main: 318234624 01:47:07 INFO - PROCESS | 1678 | 01:47:10 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:47:11 INFO - PROCESS | 1678 | RSS: Main: 317210624 01:47:11 INFO - PROCESS | 1678 | 01:47:12 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 01:47:13 INFO - PROCESS | 1678 | RSS: Main: 360865792 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | __start_tp_report 01:47:13 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 01:47:13 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 01:47:13 INFO - PROCESS | 1678 | |i|pagename|runs| 01:47:13 INFO - PROCESS | 1678 | |0;ai-astar;104;112;113;113;113;114;112;112;113;112 01:47:13 INFO - PROCESS | 1678 | |1;audio-beat-detection;150;113;117;176;139;138;113;114;141;111 01:47:13 INFO - PROCESS | 1678 | |2;audio-dft;181;184;181;184;182;185;179;180;183;182 01:47:13 INFO - PROCESS | 1678 | |3;audio-fft;78;58;57;86;61;58;57;57;80;57 01:47:13 INFO - PROCESS | 1678 | |4;audio-oscillator;87;70;91;71;67;69;91;65;68;89 01:47:13 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;86;83;89;83;86;83;87;83;87;84 01:47:13 INFO - PROCESS | 1678 | |6;imaging-darkroom;96;96;95;95;120;155;93;157;95;95 01:47:13 INFO - PROCESS | 1678 | |7;imaging-desaturate;81;81;79;87;122;80;84;82;82;83 01:47:13 INFO - PROCESS | 1678 | |8;json-parse-financial;54;52;52;51;52;55;53;54;52;53 01:47:13 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;65;72;70;81;74;72;70;71;89;101 01:47:13 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;90;90;85;87;86;89;82;79;85;103 01:47:13 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;144;149;155;150;144;145;146;149;257;152 01:47:13 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;169;171;173;169;169;170;168;169;167;172 01:47:13 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;66;68;65;81;85;67;63;85;83;82 01:47:13 INFO - PROCESS | 1678 | __end_tp_report 01:47:13 INFO - PROCESS | 1678 | __start_cc_report 01:47:13 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1979 01:47:13 INFO - PROCESS | 1678 | __end_cc_report 01:47:13 INFO - PROCESS | 1678 | __startTimestamp1476953233258__endTimestamp 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | ------- Summary: start ------- 01:47:13 INFO - PROCESS | 1678 | Number of tests: 14 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:2.82 (2.5%) stddev-sans-first:0.71 01:47:13 INFO - PROCESS | 1678 | Values: 104.0 112.0 113.0 113.0 113.0 114.0 112.0 112.0 113.0 112.0 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:138.50 stddev:21.43 (15.5%) stddev-sans-first:21.62 01:47:13 INFO - PROCESS | 1678 | Values: 150.0 113.0 117.0 176.0 139.0 138.0 113.0 114.0 141.0 111.0 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:182.10 Median:182.50 stddev:1.91 (1.0%) stddev-sans-first:1.99 01:47:13 INFO - PROCESS | 1678 | Values: 181.0 184.0 181.0 184.0 182.0 185.0 179.0 180.0 183.0 182.0 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:64.90 Median:59.50 stddev:11.57 (19.4%) stddev-sans-first:11.26 01:47:13 INFO - PROCESS | 1678 | Values: 78.0 58.0 57.0 86.0 61.0 58.0 57.0 57.0 80.0 57.0 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:79.00 stddev:11.10 (14.1%) stddev-sans-first:11.15 01:47:13 INFO - PROCESS | 1678 | Values: 87.0 70.0 91.0 71.0 67.0 69.0 91.0 65.0 68.0 89.0 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:85.10 Median:86.00 stddev:2.18 (2.5%) stddev-sans-first:2.29 01:47:13 INFO - PROCESS | 1678 | Values: 86.0 83.0 89.0 83.0 86.0 83.0 87.0 83.0 87.0 84.0 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:109.70 Median:95.00 stddev:25.63 (27.0%) stddev-sans-first:26.71 01:47:13 INFO - PROCESS | 1678 | Values: 96.0 96.0 95.0 95.0 120.0 155.0 93.0 157.0 95.0 95.0 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:82.00 stddev:12.81 (15.6%) stddev-sans-first:13.45 01:47:13 INFO - PROCESS | 1678 | Values: 81.0 81.0 79.0 87.0 122.0 80.0 84.0 82.0 82.0 83.0 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.23 (2.3%) stddev-sans-first:1.22 01:47:13 INFO - PROCESS | 1678 | Values: 54.0 52.0 52.0 51.0 52.0 55.0 53.0 54.0 52.0 53.0 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:76.50 Median:72.00 stddev:10.91 (15.1%) stddev-sans-first:10.74 01:47:13 INFO - PROCESS | 1678 | Values: 65.0 72.0 70.0 81.0 74.0 72.0 70.0 71.0 89.0 101.0 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:86.50 stddev:6.43 (7.4%) stddev-sans-first:6.76 01:47:13 INFO - PROCESS | 1678 | Values: 90.0 90.0 85.0 87.0 86.0 89.0 82.0 79.0 85.0 103.0 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:159.10 Median:149.50 stddev:34.58 (23.1%) stddev-sans-first:36.25 01:47:13 INFO - PROCESS | 1678 | Values: 144.0 149.0 155.0 150.0 144.0 145.0 146.0 149.0 257.0 152.0 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:169.50 stddev:1.83 (1.1%) stddev-sans-first:1.92 01:47:13 INFO - PROCESS | 1678 | Values: 169.0 171.0 173.0 169.0 169.0 170.0 168.0 169.0 167.0 172.0 01:47:13 INFO - PROCESS | 1678 | 01:47:13 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.50 Median:81.50 stddev:9.34 (11.5%) stddev-sans-first:9.38 01:47:13 INFO - PROCESS | 1678 | Values: 66.0 68.0 65.0 81.0 85.0 67.0 63.0 85.0 83.0 82.0 01:47:13 INFO - PROCESS | 1678 | -------- Summary: end -------- 01:47:13 INFO - PROCESS | 1678 | 01:47:14 INFO - TEST-INFO | 1678: exit 0 01:47:14 INFO - TEST-OK | kraken | took 59504ms 01:47:14 INFO - SUITE-END | took 1177s 01:47:14 INFO - Completed test suite (00:19:38) 01:47:14 INFO - javascript benchmark 01:47:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3184.347208822191, 3326.8502917368714, 3334.9166270843225, 3349.8660053597855, 3389.068006298268, 5619.100943848985, 5717.428257174281, 5742.253507044089, 5823.941760582393, 5835.824925252244, 5717.0, 5724.2558467399285, 5814.854628634286, 5815.331236907757, 5827.592068555203, 5131.204663277179, 5154.149565321717, 5182.455842136578, 5226.738663066851, 5237.7381130943495, 5300.178472336794, 5326.573874090063, 5353.411124776271, 5361.51746342829, 5367.570594352462, 4306.806193721259, 5278.709670968095, 5300.416954135042, 5319.893602127955, 5399.5680345572455, 4521.59305662492, 5099.643024988252, 5208.921866172011, 5227.947720522784, 5231.032259032091, 5142.614303927183, 5211.947880521184, 5222.582193424555, 5227.398849132361, 5229.816956406546, 5717.0, 5725.341585717654, 5735.426457354273, 5736.827895163152, 5749.7700091996685, 6139.285732132085, 6163.476104531121, 6167.383261673842, 6182.196314479134, 6205.0, 5290.947090529084, 6237.345078766755, 6253.7185826637915, 6265.624062556261, 6299.149614801989, 5787.652740835521, 5851.531877449751, 5854.560907931922, 5862.443067908542, 5867.413258674099, 6133.891171610156, 6197.628142311476, 6202.5658203925295, 6229.4393504584805, 6234.844128896814, 6095.649931565303, 6113.2330707408255, 6123.996209287264, 6174.104754810617, 6210.937890621126, 6243.938530449835, 6266.874662506815, 6274.0, 6288.213973253343, 6292.370762923762, 6019.909701354483, 6202.317745048041, 6269.780557680459, 6269.905951410732, 6270.686465676789, 6097.969510152509, 6178.474829639441, 6204.81385558425, 6266.530010249249, 6278.6860656966965, 4939.48135445773, 4948.826791062367, 4955.975220123876, 4983.327250821165, 4994.425641051253, 4454.77726113693, 4459.0858873930765, 4460.933086003713, 4483.14820184164, 4504.369388285642, 5598.216249725042, 5696.544276457874, 5726.198332233491, 5813.0, 5836.533077353802, 6204.689765511706, 6205.348438413902, 6229.221347331583, 6240.157578726814, 6257.9687101564205, 5871.56329126543, 5883.14694369314, 5900.085486749561, 5907.232059832194, 5914.970425147847, 4776.976115119472, 5213.8790160114995, 5356.982173387044, 5406.2701535292235, 5428.83713488596, 6240.625562466266, 6244.469220116341, 6252.343503932022, 6311.400416960381, 6359.141515895295, 6190.969045154926, 6210.285817130907, 6213.689315534115, 6227.782027629011, 6230.0, 6185.103160041859, 6253.812385628438, 6302.968485157545, 6319.052142178526, 6323.494120470259, 6112.837854638657, 6123.526657168115, 6152.354002829638, 6158.261008678987, 6161.291451482957, 6178.7219575120125, 6225.750969961332, 6246.063090536456, 6250.468710159687, 6254.874902502015, 6117.480014198665, 6148.016317389196, 6164.260288765377, 6167.044108163242, 6170.290416601969, 6063.939360606514, 6103.298120716175, 6147.047207682816, 6155.47678447337, 6159.414855588712, 5851.356350801493, 5918.733656985532, 6030.487408570145, 6037.426444487766, 6107.358727333566, 6100.9694951526735, 6102.004547214632, 6108.328083910677, 6133.662648554372, 6153.953827849186, 5987.88024239504, 5988.670623115596, 6011.0, 6035.728392222449, 6048.758049677964], "value": 5705.461076315254, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.70271917815063, 476.91177132230564, 481.94457637371715, 518.8740433259826, 537.8409527468306, 40868.0, 41630.54293099742, 41674.791626041864, 41742.58257417429, 44788.55211447884, 43354.56645433545, 43658.34512482315, 43739.56260437395, 43741.562584374224, 43933.340999885026, 8637.3521985851, 9537.47543885086, 9725.902740972606, 9728.951355243214, 9731.0, 8676.956615216915, 11651.126165537577, 11843.28940263587, 11852.703682407922, 12019.519219231264, 11747.236429632047, 11821.34982575958, 11821.458386446911, 11851.0, 12003.519859205622, 8740.169683880022, 8924.509151996637, 11760.705982350466, 11824.054075673925, 11824.467898944526, 8740.213380795696, 8801.691940782042, 8816.515091669955, 11413.828792568034, 11722.941385293105, 41476.79261603673, 41874.37188442176, 41893.371599426035, 41901.79049104766, 41971.37042944358, 3536.5579302587175, 3635.581908080578, 3864.942025869614, 4076.408920706481, 4102.466679331697, 30477.542836857465, 31556.36887262265, 31840.203994900083, 31859.681403185903, 32432.027039188863, 3230.2731885325616, 3251.528528363374, 3265.314284000363, 3351.748618853596, 3398.09950363154, 351835.0, 352324.0, 352862.0, 352967.0, 353233.0, 57571.712141439864, 57996.55008624818, 60993.39006609966, 61024.69487652533, 61034.69482652647, 64435.35564644387, 64769.35230647633, 64801.0, 64936.675316623114, 64989.0, 13924.721505569833, 14538.927305363406, 15730.292136853868, 16216.432424865308, 16223.67552648964, 11161.497732602053, 11315.038221751243, 11323.547058117747, 12490.188137771016, 12528.68678283032, 16736.330546777994, 16750.74873876893, 16949.40677076321, 17057.147142643065, 17099.48701538931, 6269.65516896566, 6363.140975968185, 6403.423691867754, 6447.0, 6485.627473713643, 838.932885369169, 944.9149576538144, 957.3585697582637, 1064.7284942339654, 1079.3469950679882, 26551.203463896112, 27394.0, 30049.549256761165, 30238.54642180325, 30959.380812383628, 1062.8512008318842, 1064.079571170947, 1124.516457923101, 1127.2841745491671, 1130.3387518301886, 15420.383184672712, 15743.291551880193, 15854.207289635473, 15909.840901591067, 16353.591160221089, 188985.0, 189309.0, 190168.0, 190598.0, 193433.0, 60003.39996600065, 60232.39767602355, 60518.0, 60547.69726151341, 60612.696936515036, 1239.423667994369, 1263.3051821498138, 1350.156152404747, 1378.23507953085, 1387.5767890793204, 29678.258043549085, 30320.938767143045, 31832.8408357966, 32164.678353216168, 32250.559194759473, 42789.57210427856, 52410.0, 52599.0, 52620.73689631528, 52789.736051319494, 430828.0, 432130.0, 432858.0, 434091.0, 434949.0, 26078.34804129912, 26792.866035669696, 26869.86565067162, 27892.721072789012, 41365.793171033954, 48004.29728882838, 48016.519834801205, 48501.27248091352, 48631.0, 49176.50823491719, 36234.637653623126, 39656.0, 39772.80113599414, 40646.06727535262, 42199.0, 39416.0, 39599.54845226012, 40730.0, 40868.79565602153, 41171.38242926419, 38134.046648834, 38832.41751373675, 38962.0, 39287.41068883912, 39524.0, 33750.324993499504, 34493.310133797684, 35306.82346588251, 35400.0, 35554.0, 25289.0, 25635.615465767656, 25903.870480647474, 25938.74061259439, 26197.476050479265], "value": 19389.926954315808, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.60093294111186, 356.7271037656195, 377.4828484975582, 385.28337292635695, 409.14080431094703, 14201.715965680693, 14713.926430367846, 14743.115413075224, 20007.399778006646, 20569.69145462819, 20182.495437614067, 20280.492987675316, 20734.68897966528, 20922.37232883016, 21009.0, 5227.817026404076, 5820.883582328351, 6157.538184636148, 6165.198524191871, 6167.475764559996, 5967.075103358986, 6071.180390647272, 6104.664243466607, 6117.143599896018, 6118.29639591446, 5757.251557297566, 6038.124471951564, 6056.273247210341, 6056.303525094627, 6065.150878876961, 5407.810726624569, 6021.187139736124, 6023.36754640763, 6031.8793624127275, 6039.909401358983, 4985.35190425246, 5136.04983078128, 5205.6876587404495, 5212.009718153538, 5227.555657769094, 20081.39755807328, 24874.5025099497, 25083.37291567707, 25495.745042549523, 25542.489150217076, 2058.5677007828376, 2114.5770845830743, 2142.892855357226, 2148.0655914677163, 2606.4005278785794, 9727.854082188773, 9885.604575816958, 10260.74348141295, 13288.936885049174, 14348.569542913729, 1830.3959693301179, 1835.4034938644993, 1857.6841936870765, 1904.7142928560688, 1983.8710483818506, 123337.0, 127932.0, 128093.35953320267, 129009.35495322557, 129425.05862412072, 11308.49111789955, 11369.602063927723, 11484.88515114838, 11577.768444631229, 11761.647150585497, 14622.780658290134, 14644.341004654816, 14668.193249371174, 14676.192809395585, 14680.486182983544, 8226.30076443509, 8383.62273697673, 8399.748007559783, 8415.621297041647, 8490.278326342206, 8261.586920653943, 8295.170482951657, 8328.958355208186, 8395.160483951679, 8447.788805279793, 4510.488986189597, 4534.070515544371, 4555.157295900269, 4557.931631025537, 4601.332806743004, 1881.9298771351678, 1929.1318906492136, 1929.218666440066, 1938.044482164682, 1963.2735887721635, 841.6338892581747, 932.6409332407072, 971.6113554578226, 977.2328721953232, 1003.8494225866033, 19526.5118372039, 21138.682919756215, 21215.257465988925, 21328.14687412486, 21467.892660536596, 883.4390162246891, 883.4655033704645, 945.4941606240628, 951.6526467839188, 953.5613617735919, 10371.688849334532, 11020.118390528898, 11083.334999900031, 11087.556497740161, 11105.944470277596, 538138.0, 578337.0, 581324.0, 594899.0, 599531.0, 74515.0, 124027.0, 126657.73342266644, 127595.0, 127811.0, 766.8888011238339, 954.7756277274974, 994.4878387630376, 995.3027296027701, 996.4121168510469, 18256.543586410447, 18392.0, 18454.907725461286, 18457.81542184561, 18571.44285671432, 41102.58897410987, 44736.552634473235, 51183.23225151551, 52944.94110117699, 54578.72710636421, 76551.46897061916, 77162.0, 79871.60064199643, 80212.3957520858, 80657.19342806497, 7806.141324454192, 7813.413993950363, 8047.315978141689, 8089.555074470961, 8252.298554622925, 12132.575359862363, 12161.39193040362, 12208.145429819828, 12379.133460657667, 12871.356432178542, 10933.125349972142, 11165.0, 11347.829782553434, 11431.428428578703, 11448.885511144781, 10882.347059176474, 10885.510152043336, 11095.89041095884, 11346.602868899548, 11387.772244555228, 10853.240273181118, 11327.263727857662, 11395.943020284845, 11435.428228588704, 11658.70853228676, 6793.320667933167, 7556.357709594746, 7964.522128672298, 8162.918370816454, 8240.711575094843, 10617.681469555926, 10961.84900585427, 11216.943915280372, 11241.55033798639, 11668.241564298294], "value": 9475.121631322752, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.540868808949, 1521.239380309845, 1544.4131230132548, 1561.195984068205, 1726.361246338854, 7739.690412383505, 8245.216704413078, 8365.539895305748, 8738.300935925126, 8759.036505984337, 8618.396712230146, 8697.52163631, 8718.215360617543, 8721.825563488726, 8782.121787821216, 2372.620380739082, 2378.310290015902, 2403.146882856587, 2403.2790162951133, 2409.626507891271, 2011.6781314989605, 2390.5816482115647, 2401.2916189724074, 2407.711074671033, 2409.0484258717797, 2370.300761275419, 2385.9880700596473, 2395.4849707312924, 2400.975990240093, 2403.42317843718, 2342.156823543523, 2371.7865392114704, 2381.0951838301335, 2385.7256415512265, 2416.6012607919674, 2259.130234859591, 2344.2381226101584, 2356.599378105726, 2356.646503024543, 2357.4577847095265, 7833.764987050397, 8093.150219226955, 8713.7821554461, 8731.781705457415, 8834.819464992597, 5153.845384638429, 5320.441353657849, 5327.946720532823, 5357.249985002103, 5376.919346209771, 3605.4591811228265, 3629.836657350399, 3697.1126929536986, 3703.4444833274956, 3715.7213209009433, 1079.0558261521169, 1082.5345101606306, 1093.3330668292335, 1100.664297389296, 1102.9338239705562, 11462.598809041643, 11597.594084207096, 11602.651920442484, 11612.18714689972, 11615.186936914419, 4530.977345113253, 4711.599514041276, 4809.495003024632, 4867.70793752376, 4869.391326084239, 9870.161036311994, 9886.209103271722, 9888.604455821687, 9892.752681183028, 9913.405195688281, 5349.919751203735, 5351.224072509464, 5354.223637572608, 5362.410134885159, 5362.758675859595, 4690.953090469052, 4831.227003679394, 4908.582770464479, 4915.238138088602, 4922.58158056562, 3372.8144952027874, 3739.962600374016, 3769.9434508482395, 3803.980980095137, 3838.251540949544, 1162.8953394194564, 1166.1545379599893, 1181.2381014245934, 1182.6629410617932, 1186.4720199511307, 802.5385403393072, 823.7487566292218, 896.9058248883773, 935.8502639577634, 951.8524628682566, 5247.737613119329, 5677.772889084473, 5849.537615596101, 5925.585209035326, 6051.394860513913, 815.1237419773648, 928.5450129436528, 940.5250348574064, 945.6737425588161, 995.8904520502739, 3803.505544279226, 3908.687305015592, 3913.2173565287057, 4007.198560287954, 4021.4571032910767, 9861.753456163653, 10183.236257280734, 10328.380297182193, 10376.533056012497, 10681.946590267155, 9745.902540974499, 10264.230182736324, 10754.677359679223, 10764.977327153907, 10770.192235582363, 7831.882521762178, 8074.838503229903, 8187.95906020478, 8268.214519620626, 8300.543470109082, 4992.726854652041, 5649.20911072442, 5738.598298119092, 5833.745744664918, 5846.503047240862, 10002.349847259904, 10031.097201251923, 10032.347897386646, 10042.297039207186, 10070.69787906364, 9755.9024409755, 10046.397216167054, 10112.988701129829, 10286.845697314697, 10314.123299519624, 6938.722451102101, 7092.25531319204, 7124.750633727795, 7182.138143422822, 7187.460940429593, 8198.959005204935, 8269.586520674062, 8365.749027529184, 8376.664933402595, 8378.790530236793, 7319.158296795724, 7506.361959233526, 7566.205548417337, 7601.049868766404, 7626.313631773167, 7316.158641756266, 7413.703451861865, 7424.294692004251, 7425.8143546411775, 7468.0, 6882.414994725505, 6981.685824138028, 6982.33667801558, 6998.860022799414, 7013.368796808311, 4825.734584597741, 4959.528844759742, 4997.005988023952, 5018.548330650259, 5114.23286507012, 4897.632677549269, 4963.106640804545, 5045.697258164522, 5073.797048118182, 5157.303763991963], "value": 4550.137088693758, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.8495752123938, 303.7554768411431, 309.34882073235843, 315.23398142513685, 317.3747716997514, 4661.881148524355, 5377.274068000819, 5505.421930697279, 5505.889882202364, 5520.365158006831, 4163.522703991128, 4562.726236425817, 5184.637075404723, 5191.766370513326, 5367.765413954793, 3185.8407079645926, 3836.5971572984854, 3918.5689574146822, 4116.855910043149, 4154.6676265898805, 4071.9592804071876, 4085.5301640311445, 4086.4687590613325, 4121.887090485567, 4124.298869192245, 3790.3366910790637, 3955.8242966368525, 4099.3850922361735, 4108.650764685005, 4117.711760176789, 4045.7572545647063, 4102.774347410891, 4107.835686572564, 4108.301588729938, 4135.73117747347, 2763.6821765497025, 2941.7646588272887, 3528.417811061172, 3548.4741561128703, 3564.536610240678, 4818.759062046884, 4934.432540257844, 5001.624878134118, 5076.746162691888, 5214.921776173361, 4419.4808958893445, 4473.552644735533, 4587.266037434028, 4668.6731928765, 4765.928511072337, 5673.546116310686, 5708.600397972144, 5717.914231286534, 5734.655920644775, 5735.655860648374, 3888.1616420013265, 3910.687145028391, 3934.6852251819787, 3943.369060950234, 3967.8809635710977, 5544.334679838405, 5806.883862322772, 5857.589968702193, 5860.560457965669, 5865.451401901831, 3977.573680055164, 3982.661473774762, 3985.8206380712936, 3991.6806655467494, 4008.018035581243, 3569.714422846166, 3706.573744019472, 3759.984804102932, 4051.91896162081, 4103.435352904435, 3953.802309884494, 4018.9196216076098, 4643.814247430065, 4697.694649847749, 4752.239641657318, 4758.214894542431, 4969.652124351333, 5580.832575022756, 5600.971995140079, 5613.456299517666, 1179.6696924861053, 1332.9133606315559, 1372.8009438631343, 1386.2860626777217, 1395.6371343450776, 850.6512329944693, 873.9737807865774, 878.6661068793945, 880.4497189256715, 881.5504092912532, 4718.575328220477, 4745.905081898342, 5061.2661164131, 5270.811159248599, 5448.455154484599, 5394.437642119961, 5660.72633657432, 5755.942440575541, 5758.251427314506, 5760.711964401763, 4420.557944205618, 4884.7069175849565, 4959.950400496021, 5274.12976858814, 5321.414644389191, 2496.563101457274, 2630.7500787425165, 2935.7963235073516, 3002.609660744089, 3022.2897619059504, 4979.887570268582, 5567.409021181315, 5904.586678932519, 5922.200502932135, 6007.249093863267, 5845.707714614252, 5878.470937615635, 5888.705564721832, 5896.64620122794, 5973.970130149321, 5158.406783219828, 5696.034867970544, 5852.912206316993, 5890.410958904075, 5909.0, 5265.842024739264, 5685.374608792947, 5773.278340207474, 5820.796272130455, 5826.213461182686, 4814.350062741625, 5675.035244008446, 5688.40271771458, 5690.544756419559, 5698.572607054404, 5701.828945131487, 5947.442599682178, 5996.421538738385, 6055.455009049207, 6076.781974579335, 3318.784279021856, 3835.9041023974623, 3849.634284742945, 3859.3053250415196, 3875.224955008953, 5642.943570564242, 5799.9130013049, 5858.297004359504, 5896.823095307148, 5968.850778730566, 4653.302004699322, 5255.842324730264, 5700.916825803084, 5746.454086861741, 5820.708964551671, 4758.881027974328, 5695.401982791913, 5701.287339082614, 5711.171880077282, 5724.484796368347, 5246.871922536673, 5526.253955715927, 5569.470900264469, 5569.55443564522, 5579.860503487445, 4858.3441235432765, 4868.464468908346, 4952.1581331173065, 5392.460753924576, 5505.642133261325, 4821.179609928091, 5525.336959564878, 5526.032944234823, 5538.695371754431, 5576.637518561281], "value": 4105.699624997839, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.4905692429915, 741.7255615422295, 741.881020955408, 791.1179035375554, 827.8634025385813, 15173.696526069472, 15916.681666366665, 15924.44264450743, 16002.039877607323, 16209.918950405261, 14214.644633884158, 14326.283685815719, 14338.139711617285, 14363.497277595287, 14370.0, 712.654362634122, 735.0811485642946, 737.1228238396318, 739.5303981971433, 905.1220316293202, 737.634870738985, 742.8737114690489, 884.7655371326604, 915.0849150849151, 921.0053142606002, 960.140674096683, 966.734148109272, 974.4056125763278, 975.4050029482011, 978.3542861711272, 963.2438535749438, 966.9661561845372, 967.4098799732158, 967.5936106835181, 970.4904924914476, 564.8446677163772, 583.6468936293523, 701.6316433872258, 724.2250791652933, 727.4144313827367, 14692.485762998243, 15093.320800563892, 15151.469698560608, 15155.166465844373, 15159.166245856584, 13237.669058273525, 13454.0, 13553.525626603021, 13599.728005439934, 14047.929760351133, 10707.196960227937, 12375.62873113808, 12489.93755031219, 12653.051021173356, 12965.546205882749, 4433.290673492258, 4465.620422264112, 4475.820967161309, 4476.7090139140855, 4541.523140070278, 115102.0, 115224.0, 115355.0, 116463.0, 116639.4168029163, 10323.225758068174, 10943.124550035978, 10978.451077446096, 11001.504932278067, 11147.609833655782, 20560.691589626167, 21387.0, 21433.035513401934, 21433.249836255658, 21575.35273941784, 6566.409023187936, 7529.924700753032, 7660.578668173304, 7761.650725717356, 8062.072861620872, 4141.4409054777825, 4299.333603291432, 4355.8475453359615, 4408.140412619508, 4444.977775111104, 3597.5143355647174, 3757.5678796938155, 3758.3047136279874, 3759.36467636579, 3800.258949504875, 614.9754009839646, 620.6493331267819, 625.8779537990139, 639.6961443314389, 640.4588123036027, 581.3692144023781, 650.8763334966342, 655.4690700532584, 695.1484431571284, 698.9825254368682, 8445.619947102396, 9463.952680236554, 9488.525573721286, 9665.468399237969, 9700.27247956406, 499.4378836568381, 624.4910398025595, 649.9902501462482, 680.0547239337325, 695.9582425054411, 4425.822967081345, 4639.2113340731485, 4742.810287588527, 4783.545563171493, 4790.449098353734, 158061.2096939531, 158734.0, 161654.0, 162328.0, 168019.0, 127608.72391276155, 129421.0, 129582.35208823896, 130239.99999999811, 132219.0, 104622.0, 105505.94494055114, 109631.0, 110505.23736906481, 110943.0, 8560.48637081777, 8998.280137589109, 9003.81992360136, 9006.0, 9158.67944621935, 41504.0, 47819.76090119527, 59197.70401147967, 62203.688981554806, 65347.34652653603, 61617.691911540154, 70756.0, 71675.0, 89725.0, 91083.0, 13208.18501799682, 13288.468461261442, 13363.933180334036, 13380.531681391105, 13390.0, 12712.427940742504, 14563.199024053354, 14891.329890154753, 15153.469628562947, 15211.467598633995, 13277.0, 13320.33398330099, 14139.222342771247, 14338.354774035402, 14341.99606027568, 13512.189268643913, 13754.174749515061, 13867.445302188204, 14185.645358866112, 14296.285185740464, 13176.407061682055, 13424.39590218462, 13534.551231136056, 13714.93142534281, 13962.232077235849, 11425.2002359834, 11497.540098396305, 11614.941925290319, 11624.94187529057, 11652.065205528555, 11259.324440533594, 11320.716982075513, 11498.885011149781, 11510.712232193928, 11544.249623774427], "value": 7854.254790375325, "unit": "score"}], "name": "dromaeo_css", "value": 7319.850386053667, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 113.0, 113.0, 113.0, 114.0, 112.0, 112.0, 113.0, 112.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 113.0, 117.0, 176.0, 139.0, 138.0, 113.0, 114.0, 141.0, 111.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 181.0, 184.0, 182.0, 185.0, 179.0, 180.0, 183.0, 182.0], "value": 182.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 57.0, 86.0, 61.0, 58.0, 57.0, 57.0, 80.0, 57.0], "value": 64.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 91.0, 71.0, 67.0, 69.0, 91.0, 65.0, 68.0, 89.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 89.0, 83.0, 86.0, 83.0, 87.0, 83.0, 87.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 95.0, 120.0, 155.0, 93.0, 157.0, 95.0, 95.0], "value": 109.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 79.0, 87.0, 122.0, 80.0, 84.0, 82.0, 82.0, 83.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 51.0, 52.0, 55.0, 53.0, 54.0, 52.0, 53.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 70.0, 81.0, 74.0, 72.0, 70.0, 71.0, 89.0, 101.0], "value": 76.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 85.0, 87.0, 86.0, 89.0, 82.0, 79.0, 85.0, 103.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 149.0, 155.0, 150.0, 144.0, 145.0, 146.0, 149.0, 257.0, 152.0], "value": 159.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 173.0, 169.0, 169.0, 170.0, 168.0, 169.0, 167.0, 172.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 65.0, 81.0, 85.0, 67.0, 63.0, 85.0, 83.0, 82.0], "value": 74.5, "unit": "score"}], "name": "kraken", "value": 1467.8999999999999, "alertThreshold": 2.0}]} 01:47:14 INFO - Return code: 0 01:47:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:47:14 INFO - # TBPL SUCCESS # 01:47:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:47:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:47:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:47:14 INFO - 2016-10-20 01:47:14.528 screenresolution[1682:9895] starting screenresolution argv=screenresolution get 01:47:14 INFO - 2016-10-20 01:47:14.531 screenresolution[1682:9895] Display 0: 1600x1200x32@60 01:47:14 INFO - 2016-10-20 01:47:14.537 screenresolution[1683:9898] starting screenresolution argv=screenresolution list 01:47:14 INFO - Available Modes on Display 0 01:47:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:47:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:47:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:47:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:47:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:47:14 INFO - Intel Iris: 01:47:14 INFO - Chipset Model: Intel Iris 01:47:14 INFO - Type: GPU 01:47:14 INFO - Bus: Built-In 01:47:14 INFO - VRAM (Dynamic, Max): 1536 MB 01:47:14 INFO - Vendor: Intel (0x8086) 01:47:14 INFO - Device ID: 0x0a2e 01:47:14 INFO - Revision ID: 0x0009 01:47:14 INFO - Displays: 01:47:14 INFO - Display: 01:47:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:47:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:47:14 INFO - Display Serial Number: 1600x1200 60 01:47:14 INFO - Main Display: Yes 01:47:14 INFO - Mirror: Off 01:47:14 INFO - Online: Yes 01:47:14 INFO - Rotation: Supported 01:47:14 INFO - Return code: 0 01:47:14 INFO - Running post-action listener: _resource_record_post_action 01:47:14 INFO - [mozharness: 2016-10-20 08:47:14.780067Z] Finished run-tests step (success) 01:47:14 INFO - Running post-run listener: _resource_record_post_run 01:47:14 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 34859008; Write bytes: 710404608; Read time: 216; Write time: 1992 01:47:14 INFO - TinderboxPrint: CPU usage
53.8% 01:47:14 INFO - TinderboxPrint: I/O read bytes / time
34,859,008 / 216 01:47:14 INFO - TinderboxPrint: I/O write bytes / time
710,404,608 / 1,992 01:47:14 INFO - TinderboxPrint: CPU idle
2,241.9 (46.2%) 01:47:14 INFO - TinderboxPrint: CPU system
972.7 (20.0%) 01:47:14 INFO - TinderboxPrint: CPU user
1,639.0 (33.8%) 01:47:14 INFO - TinderboxPrint: Swap in / out
439,144,448 / 0 01:47:14 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401490432; Write bytes: 397756416; Read time: 29540; Write time: 816 01:47:14 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 30100480; Write bytes: 298258944; Read time: 180; Write time: 1132 01:47:15 INFO - Running post-run listener: _upload_blobber_files 01:47:15 INFO - Blob upload gear active. 01:47:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:47:15 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 01:47:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:47:15 INFO - (blobuploader) - INFO - Open directory for files ... 01:47:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:47:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:47:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:16 INFO - (blobuploader) - INFO - Done attempting. 01:47:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:47:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:47:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:17 INFO - (blobuploader) - INFO - Done attempting. 01:47:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:47:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:47:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:19 INFO - (blobuploader) - INFO - Done attempting. 01:47:19 INFO - (blobuploader) - INFO - Iteration through files over. 01:47:19 INFO - Return code: 0 01:47:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:47:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:47:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99d179620b3ddca069d85d3cea2e052b02f807cff0ed006d632510b26270726de94da7ecb35dec22ff541b568c4dfa298fc19f8a03bc2f401c7ce76e94f5553a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc1293d8f6411aa07e7a511fb706b9825efb719b9598aac182db6b0cdfc0785accf4e09625b5ddbd3a36230ae7fc38020518374d3a5013132e11e5d108b8ee01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb90f305f10a7da15377eaef4e7d63ede0e7fc0bb0e533902c9f7c5306cbcee83e398237a423be0f3311c008f7e7e9ed32d55ea63a84746100fa07b8923d6469"} 01:47:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:47:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:47:19 INFO - Contents: 01:47:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99d179620b3ddca069d85d3cea2e052b02f807cff0ed006d632510b26270726de94da7ecb35dec22ff541b568c4dfa298fc19f8a03bc2f401c7ce76e94f5553a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc1293d8f6411aa07e7a511fb706b9825efb719b9598aac182db6b0cdfc0785accf4e09625b5ddbd3a36230ae7fc38020518374d3a5013132e11e5d108b8ee01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb90f305f10a7da15377eaef4e7d63ede0e7fc0bb0e533902c9f7c5306cbcee83e398237a423be0f3311c008f7e7e9ed32d55ea63a84746100fa07b8923d6469"} 01:47:19 INFO - Running post-run listener: copy_logs_to_upload_dir 01:47:19 INFO - Copying logs to upload dir... 01:47:19 INFO - mkdir: /builds/slave/test/build/upload/logs 01:47:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.546862 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-20 01:47:19.589188) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 01:47:19.593220) ========= 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.GN1SrflfaC/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.JM7NFXgV8a/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99d179620b3ddca069d85d3cea2e052b02f807cff0ed006d632510b26270726de94da7ecb35dec22ff541b568c4dfa298fc19f8a03bc2f401c7ce76e94f5553a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc1293d8f6411aa07e7a511fb706b9825efb719b9598aac182db6b0cdfc0785accf4e09625b5ddbd3a36230ae7fc38020518374d3a5013132e11e5d108b8ee01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb90f305f10a7da15377eaef4e7d63ede0e7fc0bb0e533902c9f7c5306cbcee83e398237a423be0f3311c008f7e7e9ed32d55ea63a84746100fa07b8923d6469"} build_url:https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010952 build_url: 'https://queue.taskcluster.net/v1/task/DwfuUBGHQGSOSlf9bXpz_g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99d179620b3ddca069d85d3cea2e052b02f807cff0ed006d632510b26270726de94da7ecb35dec22ff541b568c4dfa298fc19f8a03bc2f401c7ce76e94f5553a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc1293d8f6411aa07e7a511fb706b9825efb719b9598aac182db6b0cdfc0785accf4e09625b5ddbd3a36230ae7fc38020518374d3a5013132e11e5d108b8ee01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb90f305f10a7da15377eaef4e7d63ede0e7fc0bb0e533902c9f7c5306cbcee83e398237a423be0f3311c008f7e7e9ed32d55ea63a84746100fa07b8923d6469"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 01:47:19.628282) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 01:47:19.628965) ========= 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.GN1SrflfaC/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.JM7NFXgV8a/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.005110 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 01:47:19.679002) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 01:47:19.679337) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 01:47:19.679897) ========= ========= Total master_lag: 0.19 =========