builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0268 starttime: 1485796352.18 results: success (0) buildid: 20170130065342 builduid: 16703b17ddbc4964acb485f04b1112b5 revision: 0f339c1e154f75c484fe2fac507a9a225990d212 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-30 09:12:32.175548) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-30 09:12:32.175991) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-30 09:12:32.176279) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-30 09:12:32.200496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 09:12:32.200763) ========= 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.B9vs46Y0qt/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.NZdV1DnBan/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 --2017-01-30 09:12:32-- 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% 61.1M=0s 2017-01-30 09:12:32 (61.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.339248 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 09:12:32.563052) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 09:12:32.563379) ========= 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.B9vs46Y0qt/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.NZdV1DnBan/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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.019840 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 09:12:32.611722) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-30 09:12:32.612042) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0f339c1e154f75c484fe2fac507a9a225990d212 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0f339c1e154f75c484fe2fac507a9a225990d212 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.B9vs46Y0qt/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.NZdV1DnBan/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 2017-01-30 09:12:32,704 truncating revision to first 12 chars 2017-01-30 09:12:32,704 Setting DEBUG logging. 2017-01-30 09:12:32,704 attempt 1/10 2017-01-30 09:12:32,704 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0f339c1e154f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-30 09:12:33,446 unpacking tar archive at: mozilla-beta-0f339c1e154f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.984634 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-30 09:12:33.619550) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-30 09:12:33.619866) ========= script_repo_revision: 0f339c1e154f75c484fe2fac507a9a225990d212 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-30 09:12:33.620248) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-30 09:12:33.620512) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-30 09:12:33.639522) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-01-30 09:12:33.639787) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.B9vs46Y0qt/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.NZdV1DnBan/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:12:33 INFO - MultiFileLogger online at 20170130 09:12:33 in /builds/slave/test 09:12:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:12:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:12:33 INFO - {'append_to_log': False, 09:12:33 INFO - 'base_work_dir': '/builds/slave/test', 09:12:33 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:12:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:12:33 INFO - 'branch': 'Mozilla-Beta', 09:12:33 INFO - 'buildbot_json_path': 'buildprops.json', 09:12:33 INFO - 'config_files': ('talos/mac_config.py',), 09:12:33 INFO - 'default_actions': ('clobber', 09:12:33 INFO - 'read-buildbot-config', 09:12:33 INFO - 'download-and-extract', 09:12:33 INFO - 'populate-webroot', 09:12:33 INFO - 'create-virtualenv', 09:12:33 INFO - 'install', 09:12:33 INFO - 'run-tests'), 09:12:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:12:33 INFO - 'download_minidump_stackwalk': True, 09:12:33 INFO - 'download_symbols': 'ondemand', 09:12:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:12:33 INFO - 'tooltool.py': '/tools/tooltool.py', 09:12:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:12:33 INFO - '/tools/misc-python/virtualenv.py')}, 09:12:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:12:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:12:33 INFO - 'installer_path': 'installer.exe', 09:12:33 INFO - 'log_level': 'info', 09:12:33 INFO - 'log_name': 'talos', 09:12:33 INFO - 'log_to_console': True, 09:12:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:12:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:12:33 INFO - 'opt_config_files': (), 09:12:33 INFO - 'pip_index': False, 09:12:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:12:33 INFO - 'cmd': ('bash', 09:12:33 INFO - '-c', 09:12:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:12:33 INFO - 'enabled': True, 09:12:33 INFO - 'halt_on_failure': False, 09:12:33 INFO - 'name': 'check_screen_resolution'},), 09:12:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:12:33 INFO - 'cmd': ('bash', 09:12:33 INFO - '-c', 09:12:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:12:33 INFO - 'enabled': True, 09:12:33 INFO - 'halt_on_failure': False, 09:12:33 INFO - 'name': 'check_screen_resolution'},), 09:12:33 INFO - 'run_cmd_checks_enabled': True, 09:12:33 INFO - 'sps_profile': False, 09:12:33 INFO - 'sps_profile_interval': 0, 09:12:33 INFO - 'suite': 'dromaeojs-e10s', 09:12:33 INFO - 'system_bits': '32', 09:12:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:12:33 INFO - 'title': 't-yosemite-r7-0268', 09:12:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:12:33 INFO - 'use_talos_json': True, 09:12:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:12:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:12:33 INFO - 'work_dir': 'build'} 09:12:33 INFO - [mozharness: 2017-01-30 17:12:33.852765Z] Running clobber step. 09:12:33 INFO - Running pre-action listener: _resource_record_pre_action 09:12:33 INFO - Running main action method: clobber 09:12:33 INFO - rmtree: /builds/slave/test/build 09:12:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:12:35 INFO - Running post-action listener: _resource_record_post_action 09:12:35 INFO - [mozharness: 2017-01-30 17:12:35.510533Z] Finished clobber step (success) 09:12:35 INFO - [mozharness: 2017-01-30 17:12:35.510657Z] Running read-buildbot-config step. 09:12:35 INFO - Running pre-action listener: _resource_record_pre_action 09:12:35 INFO - Running main action method: read_buildbot_config 09:12:35 INFO - Using buildbot properties: 09:12:35 INFO - { 09:12:35 INFO - "project": "", 09:12:35 INFO - "product": "firefox", 09:12:35 INFO - "script_repo_revision": "production", 09:12:35 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:12:35 INFO - "repository": "", 09:12:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:12:35 INFO - "buildid": "20170130065342", 09:12:35 INFO - "pgo_build": "False", 09:12:35 INFO - "basedir": "/builds/slave/test", 09:12:35 INFO - "buildnumber": 1, 09:12:35 INFO - "slavename": "t-yosemite-r7-0268", 09:12:35 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:12:35 INFO - "platform": "yosemite_r7", 09:12:35 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:12:35 INFO - "branch": "mozilla-beta", 09:12:35 INFO - "repo_path": "releases/mozilla-beta", 09:12:35 INFO - "revision": "0f339c1e154f75c484fe2fac507a9a225990d212", 09:12:35 INFO - "stage_platform": "macosx64", 09:12:35 INFO - "builduid": "16703b17ddbc4964acb485f04b1112b5", 09:12:35 INFO - "slavebuilddir": "test" 09:12:35 INFO - } 09:12:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 09:12:35 INFO - Running post-action listener: _resource_record_post_action 09:12:35 INFO - [mozharness: 2017-01-30 17:12:35.513999Z] Finished read-buildbot-config step (success) 09:12:35 INFO - [mozharness: 2017-01-30 17:12:35.514162Z] Running download-and-extract step. 09:12:35 INFO - Running pre-action listener: _resource_record_pre_action 09:12:35 INFO - Running main action method: download_and_extract 09:12:35 INFO - mkdir: /builds/slave/test/build/tests 09:12: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')]} 09:12:35 INFO - trying https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 09:12:35 INFO - Downloading https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 09:12:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 09:12:36 INFO - Downloaded 1165 bytes. 09:12:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 09:12:36 INFO - Using the following test package requirements: 09:12:36 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 09:12:36 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:12:36 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 09:12:36 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:12:36 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 09:12:36 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:12:36 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:12:36 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 09:12:36 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 09:12:36 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:12:36 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 09:12:36 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:12:36 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 09:12:36 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:12:36 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 09:12:36 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:12:36 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 09:12:36 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 09:12:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 09:12:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 09:12:36 INFO - Fetch https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 09:12:37 INFO - Http code: 200 09:12:37 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 09:12:37 INFO - x-amz-cf-id: fGBH9GJol4tHpECIcCI5lBwLy1peMo1-nPIRGTP4797Q88l8_9164A== 09:12:37 INFO - x-amz-version-id: EGs5uRqghk6NpAXvXwid5SGKPtaX.nA4 09:12:37 INFO - Expected file size: 16087641 09:12:37 INFO - Obtained file size: 16087641 09:12:44 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:12:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 09:12:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 09:12:44 INFO - Fetch https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 09:12:44 INFO - Http code: 200 09:12:44 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 09:12:44 INFO - x-amz-cf-id: fT-929767uPr6-MKQ62kjEcJP_28O7AE37SUtkaIkn-ZxDwGGVvbFg== 09:12:44 INFO - x-amz-version-id: EGs5uRqghk6NpAXvXwid5SGKPtaX.nA4 09:12:45 INFO - Expected file size: 16087641 09:12:45 INFO - Obtained file size: 16087641 09:12:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 09:12:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 09:12:51 INFO - Fetch https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 09:12:52 INFO - Http code: 200 09:12:52 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 09:12:52 INFO - x-amz-cf-id: 3zM2NMcVD0Z_FeEK69GRpcufWlgQEr1lXgD4uKqXMQ2Zm-hFnsAp9g== 09:12:52 INFO - x-amz-version-id: DM2Rx8F_zp3YcjT9JOfslQZct79Am3qJ 09:12:52 INFO - Expected file size: 14402992 09:12:52 INFO - Obtained file size: 14402992 09:12:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:52 INFO - trying https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:12:52 INFO - Downloading https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 09:12:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 09:12:54 INFO - Downloaded 82137351 bytes. 09:12:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:12:54 INFO - mkdir: /builds/slave/test/properties 09:12:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:12:54 INFO - Writing to file /builds/slave/test/properties/build_url 09:12:54 INFO - Contents: 09:12:54 INFO - build_url:https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:12:55 INFO - Running post-action listener: _resource_record_post_action 09:12:55 INFO - Running post-action listener: set_extra_try_arguments 09:12:55 INFO - [mozharness: 2017-01-30 17:12:55.556699Z] Finished download-and-extract step (success) 09:12:55 INFO - [mozharness: 2017-01-30 17:12:55.556806Z] Running populate-webroot step. 09:12:55 INFO - Running pre-action listener: _resource_record_pre_action 09:12:55 INFO - Running main action method: populate_webroot 09:12:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:12:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:12:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:12:55 INFO - '--tppagecycles', 09:12:55 INFO - '7'], 09:12:55 INFO - 'tests': ['tsvgm']}}, 09:12:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:12:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:12:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:12:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:12:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:12:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:12:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:55 INFO - 'talos_options': ['--disable-e10s'], 09:12:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:55 INFO - 'talos_options': ['--disable-e10s'], 09:12:55 INFO - 'tests': ['damp', 'tps']}, 09:12:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:55 INFO - 'tests': ['damp', 'tps']}, 09:12:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:12:55 INFO - 'tests': ['dromaeo_dom']}, 09:12:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:12:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:12:55 INFO - 'tests': ['basic_compositor_video']}, 09:12:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:12:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:12:55 INFO - 'tests': ['a11yr', 09:12:55 INFO - 'ts_paint', 09:12:55 INFO - 'tpaint', 09:12:55 INFO - 'sessionrestore', 09:12:55 INFO - 'sessionrestore_no_auto_restore', 09:12:55 INFO - 'tabpaint']}, 09:12:55 INFO - 'other-e10s': {'tests': ['a11yr', 09:12:55 INFO - 'ts_paint', 09:12:55 INFO - 'tpaint', 09:12:55 INFO - 'sessionrestore', 09:12:55 INFO - 'sessionrestore_no_auto_restore', 09:12:55 INFO - 'tabpaint']}, 09:12:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:12:55 INFO - 'ts_paint', 09:12:55 INFO - 'tpaint', 09:12:55 INFO - 'sessionrestore', 09:12:55 INFO - 'sessionrestore_no_auto_restore', 09:12:55 INFO - 'tabpaint']}, 09:12:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:12:55 INFO - 'ts_paint', 09:12:55 INFO - 'tpaint', 09:12:55 INFO - 'sessionrestore', 09:12:55 INFO - 'sessionrestore_no_auto_restore', 09:12:55 INFO - 'tabpaint']}, 09:12:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:12:55 INFO - 'tests': ['a11yr', 09:12:55 INFO - 'ts_paint', 09:12:55 INFO - 'tpaint', 09:12:55 INFO - 'sessionrestore', 09:12:55 INFO - 'sessionrestore_no_auto_restore', 09:12:55 INFO - 'tabpaint']}, 09:12:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:12:55 INFO - 'tests': ['a11yr', 09:12:55 INFO - 'ts_paint', 09:12:55 INFO - 'tpaint', 09:12:55 INFO - 'sessionrestore', 09:12:55 INFO - 'sessionrestore_no_auto_restore', 09:12:55 INFO - 'tabpaint']}, 09:12:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:12:55 INFO - 'tests': ['tsvgx', 09:12:55 INFO - 'tsvgr_opacity', 09:12:55 INFO - 'tart', 09:12:55 INFO - 'tscrollx', 09:12:55 INFO - 'cart']}, 09:12:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:12:55 INFO - 'tsvgr_opacity', 09:12:55 INFO - 'tart', 09:12:55 INFO - 'tscrollx', 09:12:55 INFO - 'cart']}, 09:12:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:55 INFO - 'talos_options': ['--disable-e10s'], 09:12:55 INFO - 'tests': ['tp5o']}, 09:12:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:55 INFO - 'tests': ['tp5o']}, 09:12:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:55 INFO - 'talos_options': ['--disable-e10s', 09:12:55 INFO - '--xperf_path', 09:12:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:55 INFO - 'tests': ['tp5n']}, 09:12:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:12:55 INFO - 'talos_options': ['--xperf_path', 09:12:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:55 INFO - 'tests': ['tp5n']}}, 09:12:55 INFO - 'talos.zip': {'path': '', 09:12:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:12:55 INFO - Running post-action listener: _resource_record_post_action 09:12:55 INFO - [mozharness: 2017-01-30 17:12:55.576021Z] Finished populate-webroot step (success) 09:12:55 INFO - [mozharness: 2017-01-30 17:12:55.576160Z] Running create-virtualenv step. 09:12:55 INFO - Running pre-action listener: _resource_record_pre_action 09:12:55 INFO - Running main action method: create_virtualenv 09:12:55 INFO - Creating virtualenv /builds/slave/test/build/venv 09:12:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:12:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:12:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:12:55 INFO - Using real prefix '/tools/python27' 09:12:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:12:56 INFO - Installing distribute.............................................................................................................................................................................................done. 09:12:59 INFO - Installing pip.................done. 09:12:59 INFO - Return code: 0 09:12:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:12:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:12:59 INFO - Reading from file tmpfile_stdout 09:12:59 INFO - Output received: 09:12:59 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:12:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:59 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 0x10eaba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedbd8ae00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ced30>, '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 0x10e734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6b20>, '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.B9vs46Y0qt/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.NZdV1DnBan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:12:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:12:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:12:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B9vs46Y0qt/Render', 09:12:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:59 INFO - 'HOME': '/Users/cltbld', 09:12:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:59 INFO - 'LOGNAME': 'cltbld', 09:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:59 INFO - 'MOZ_NO_REMOTE': '1', 09:12:59 INFO - 'NO_EM_RESTART': '1', 09:12:59 INFO - 'PAGER': '/bin/cat', 09:12:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:59 INFO - 'PWD': '/builds/slave/test', 09:12:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:59 INFO - 'SHELL': '/bin/bash', 09:12:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZdV1DnBan/Listeners', 09:12:59 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 09:12:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:59 INFO - 'USER': 'cltbld', 09:12:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:59 INFO - 'XPC_FLAGS': '0x0', 09:12:59 INFO - 'XPC_SERVICE_NAME': '0', 09:12:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:12:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:12:59 INFO - Cleaning up... 09:12:59 INFO - Return code: 0 09:12:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:59 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 0x10eaba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedbd8ae00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ced30>, '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 0x10e734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6b20>, '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.B9vs46Y0qt/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.NZdV1DnBan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:12:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:12:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:12:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B9vs46Y0qt/Render', 09:12:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:12:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:12:59 INFO - 'HOME': '/Users/cltbld', 09:12:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:12:59 INFO - 'LOGNAME': 'cltbld', 09:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:59 INFO - 'MOZ_NO_REMOTE': '1', 09:12:59 INFO - 'NO_EM_RESTART': '1', 09:12:59 INFO - 'PAGER': '/bin/cat', 09:12:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:59 INFO - 'PWD': '/builds/slave/test', 09:12:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:12:59 INFO - 'SHELL': '/bin/bash', 09:12:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZdV1DnBan/Listeners', 09:12:59 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 09:12:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:12:59 INFO - 'USER': 'cltbld', 09:12:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:12:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:59 INFO - 'XPC_FLAGS': '0x0', 09:12:59 INFO - 'XPC_SERVICE_NAME': '0', 09:12:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:00 INFO - Downloading/unpacking psutil>=3.1.1 09:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:13:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:03 INFO - Installing collected packages: psutil 09:13:03 INFO - Running setup.py install for psutil 09:13:03 INFO - building 'psutil._psutil_osx' extension 09:13:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:13:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:13:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:13:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:13:04 INFO - building 'psutil._psutil_posix' extension 09:13:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:13:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:13:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:13:04 INFO - ^ 09:13:04 INFO - 1 warning generated. 09:13:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:13:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:04 INFO - Successfully installed psutil 09:13:04 INFO - Cleaning up... 09:13:04 INFO - Return code: 0 09:13:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:04 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 0x10eaba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedbd8ae00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ced30>, '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 0x10e734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6b20>, '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.B9vs46Y0qt/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.NZdV1DnBan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:13:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:13:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:13:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B9vs46Y0qt/Render', 09:13:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:04 INFO - 'HOME': '/Users/cltbld', 09:13:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:04 INFO - 'LOGNAME': 'cltbld', 09:13:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:04 INFO - 'MOZ_NO_REMOTE': '1', 09:13:04 INFO - 'NO_EM_RESTART': '1', 09:13:04 INFO - 'PAGER': '/bin/cat', 09:13:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:04 INFO - 'PWD': '/builds/slave/test', 09:13:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:04 INFO - 'SHELL': '/bin/bash', 09:13:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZdV1DnBan/Listeners', 09:13:04 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 09:13:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:04 INFO - 'USER': 'cltbld', 09:13:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:04 INFO - 'XPC_FLAGS': '0x0', 09:13:04 INFO - 'XPC_SERVICE_NAME': '0', 09:13:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:13:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:13:07 INFO - Installing collected packages: mozsystemmonitor 09:13:07 INFO - Running setup.py install for mozsystemmonitor 09:13:07 INFO - Successfully installed mozsystemmonitor 09:13:07 INFO - Cleaning up... 09:13:07 INFO - Return code: 0 09:13:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:07 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 0x10eaba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedbd8ae00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ced30>, '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 0x10e734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6b20>, '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.B9vs46Y0qt/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.NZdV1DnBan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:13:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:13:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B9vs46Y0qt/Render', 09:13:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:07 INFO - 'HOME': '/Users/cltbld', 09:13:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:07 INFO - 'LOGNAME': 'cltbld', 09:13:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:07 INFO - 'MOZ_NO_REMOTE': '1', 09:13:07 INFO - 'NO_EM_RESTART': '1', 09:13:07 INFO - 'PAGER': '/bin/cat', 09:13:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:07 INFO - 'PWD': '/builds/slave/test', 09:13:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:07 INFO - 'SHELL': '/bin/bash', 09:13:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZdV1DnBan/Listeners', 09:13:07 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 09:13:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:07 INFO - 'USER': 'cltbld', 09:13:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:07 INFO - 'XPC_FLAGS': '0x0', 09:13:07 INFO - 'XPC_SERVICE_NAME': '0', 09:13:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:07 INFO - Downloading/unpacking jsonschema==2.5.1 09:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:13:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:11 INFO - Downloading functools32-3.2.3-2.tar.gz 09:13:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:13:11 INFO - warning: no files found matching '*.txt' 09:13:11 INFO - no previously-included directories found matching 'build' 09:13:11 INFO - no previously-included directories found matching 'dist' 09:13:11 INFO - no previously-included directories found matching '.git*' 09:13:11 INFO - Installing collected packages: jsonschema, functools32 09:13:11 INFO - Running setup.py install for functools32 09:13:11 INFO - warning: no files found matching '*.txt' 09:13:11 INFO - no previously-included directories found matching 'build' 09:13:11 INFO - no previously-included directories found matching 'dist' 09:13:11 INFO - no previously-included directories found matching '.git*' 09:13:11 INFO - Successfully installed jsonschema functools32 09:13:11 INFO - Cleaning up... 09:13:11 INFO - Return code: 0 09:13:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:11 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 0x10eaba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedbd8ae00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ced30>, '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 0x10e734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6b20>, '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.B9vs46Y0qt/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.NZdV1DnBan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:13:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:13:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:13:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B9vs46Y0qt/Render', 09:13:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:11 INFO - 'HOME': '/Users/cltbld', 09:13:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:11 INFO - 'LOGNAME': 'cltbld', 09:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:11 INFO - 'MOZ_NO_REMOTE': '1', 09:13:11 INFO - 'NO_EM_RESTART': '1', 09:13:11 INFO - 'PAGER': '/bin/cat', 09:13:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:11 INFO - 'PWD': '/builds/slave/test', 09:13:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:11 INFO - 'SHELL': '/bin/bash', 09:13:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZdV1DnBan/Listeners', 09:13:11 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 09:13:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:11 INFO - 'USER': 'cltbld', 09:13:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:11 INFO - 'XPC_FLAGS': '0x0', 09:13:11 INFO - 'XPC_SERVICE_NAME': '0', 09:13:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:13:11 INFO - Cleaning up... 09:13:11 INFO - Return code: 0 09:13:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:11 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 0x10eaba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedbd8ae00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ced30>, '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 0x10e734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6b20>, '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.B9vs46Y0qt/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.NZdV1DnBan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:13:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:13:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:13:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B9vs46Y0qt/Render', 09:13:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:11 INFO - 'HOME': '/Users/cltbld', 09:13:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:11 INFO - 'LOGNAME': 'cltbld', 09:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:11 INFO - 'MOZ_NO_REMOTE': '1', 09:13:11 INFO - 'NO_EM_RESTART': '1', 09:13:11 INFO - 'PAGER': '/bin/cat', 09:13:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:11 INFO - 'PWD': '/builds/slave/test', 09:13:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:11 INFO - 'SHELL': '/bin/bash', 09:13:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZdV1DnBan/Listeners', 09:13:11 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 09:13:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:11 INFO - 'USER': 'cltbld', 09:13:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:11 INFO - 'XPC_FLAGS': '0x0', 09:13:11 INFO - 'XPC_SERVICE_NAME': '0', 09:13:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:11 INFO - Downloading/unpacking blobuploader==1.2.4 09:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:14 INFO - Downloading blobuploader-1.2.4.tar.gz 09:13:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:13:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:13:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:15 INFO - Downloading docopt-0.6.1.tar.gz 09:13:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:13:15 INFO - Installing collected packages: blobuploader, requests, docopt 09:13:15 INFO - Running setup.py install for blobuploader 09:13:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:13:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:13:16 INFO - Running setup.py install for requests 09:13:16 INFO - Running setup.py install for docopt 09:13:16 INFO - Successfully installed blobuploader requests docopt 09:13:16 INFO - Cleaning up... 09:13:16 INFO - Return code: 0 09:13:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:16 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 0x10eaba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedbd8ae00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ced30>, '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 0x10e734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6b20>, '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.B9vs46Y0qt/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.NZdV1DnBan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:13:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:13:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:13:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B9vs46Y0qt/Render', 09:13:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:16 INFO - 'HOME': '/Users/cltbld', 09:13:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:16 INFO - 'LOGNAME': 'cltbld', 09:13:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:16 INFO - 'MOZ_NO_REMOTE': '1', 09:13:16 INFO - 'NO_EM_RESTART': '1', 09:13:16 INFO - 'PAGER': '/bin/cat', 09:13:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:16 INFO - 'PWD': '/builds/slave/test', 09:13:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:16 INFO - 'SHELL': '/bin/bash', 09:13:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZdV1DnBan/Listeners', 09:13:16 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 09:13:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:16 INFO - 'USER': 'cltbld', 09:13:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:16 INFO - 'XPC_FLAGS': '0x0', 09:13:16 INFO - 'XPC_SERVICE_NAME': '0', 09:13:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-ppaaUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-mFo9m6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-D4cytt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-2XjFDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-cZ_X6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-PbHftK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-7McjuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-bmcHGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-ceB627-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Rd9ohk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-X72U28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-EkpcGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-iBAmXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-MF6JxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-838glf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-ZuKSkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-I5uFKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:13:18 INFO - Running setup.py install for manifestparser 09:13:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:13:18 INFO - Running setup.py install for mozcrash 09:13:19 INFO - Running setup.py install for mozdebug 09:13:19 INFO - Running setup.py install for mozdevice 09:13:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:13:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:13:19 INFO - Running setup.py install for mozfile 09:13:19 INFO - Running setup.py install for mozhttpd 09:13:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:13:19 INFO - Running setup.py install for mozinfo 09:13:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:13:19 INFO - Running setup.py install for mozInstall 09:13:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:13:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:13:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:13:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:13:19 INFO - Running setup.py install for mozleak 09:13:20 INFO - Running setup.py install for mozlog 09:13:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:13:20 INFO - Running setup.py install for moznetwork 09:13:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:13:20 INFO - Running setup.py install for mozprocess 09:13:20 INFO - Running setup.py install for mozprofile 09:13:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:13:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:13:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:13:20 INFO - Running setup.py install for mozrunner 09:13:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:13:20 INFO - Running setup.py install for mozscreenshot 09:13:21 INFO - Running setup.py install for moztest 09:13:21 INFO - Running setup.py install for mozversion 09:13:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:13:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:13:21 INFO - Cleaning up... 09:13:21 INFO - Return code: 0 09:13:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:21 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 0x10eaba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedbd8ae00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ced30>, '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 0x10e734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6b20>, '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.B9vs46Y0qt/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.NZdV1DnBan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:13:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:13:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:13:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B9vs46Y0qt/Render', 09:13:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:21 INFO - 'HOME': '/Users/cltbld', 09:13:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:21 INFO - 'LOGNAME': 'cltbld', 09:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:21 INFO - 'MOZ_NO_REMOTE': '1', 09:13:21 INFO - 'NO_EM_RESTART': '1', 09:13:21 INFO - 'PAGER': '/bin/cat', 09:13:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:21 INFO - 'PWD': '/builds/slave/test', 09:13:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:21 INFO - 'SHELL': '/bin/bash', 09:13:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZdV1DnBan/Listeners', 09:13:21 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 09:13:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:21 INFO - 'USER': 'cltbld', 09:13:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:21 INFO - 'XPC_FLAGS': '0x0', 09:13:21 INFO - 'XPC_SERVICE_NAME': '0', 09:13:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-4i9ePj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Dcrhb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:13:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-5AwJ8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-USujmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-khRtWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Oobusg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-rCsaCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-6vJmia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-CcfrgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-uJpP6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-ITtNvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-ryXr4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-e9UlBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:23 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-WhAU8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:23 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-wFKNDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:23 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-a1HrQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:23 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-3K_WGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:13:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:26 INFO - Downloading blessings-1.6.tar.gz 09:13:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:13:26 INFO - Installing collected packages: blessings 09:13:26 INFO - Running setup.py install for blessings 09:13:26 INFO - Successfully installed blessings 09:13:26 INFO - Cleaning up... 09:13:26 INFO - Return code: 0 09:13:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:13:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:13:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:13:26 INFO - Reading from file tmpfile_stdout 09:13:26 INFO - Current package versions: 09:13:26 INFO - blessings == 1.6 09:13:26 INFO - blobuploader == 1.2.4 09:13:26 INFO - docopt == 0.6.1 09:13:26 INFO - functools32 == 3.2.3-2 09:13:26 INFO - jsonschema == 2.5.1 09:13:26 INFO - manifestparser == 1.1 09:13:26 INFO - mozInstall == 1.12 09:13:26 INFO - mozcrash == 1.0 09:13:26 INFO - mozdebug == 0.1 09:13:26 INFO - mozdevice == 0.48 09:13:26 INFO - mozfile == 1.2 09:13:26 INFO - mozhttpd == 0.7 09:13:26 INFO - mozinfo == 0.9 09:13:26 INFO - mozleak == 0.1 09:13:26 INFO - mozlog == 3.4 09:13:26 INFO - moznetwork == 0.27 09:13:26 INFO - mozprocess == 0.23 09:13:26 INFO - mozprofile == 0.28 09:13:26 INFO - mozrunner == 6.13 09:13:26 INFO - mozscreenshot == 0.1 09:13:26 INFO - mozsystemmonitor == 0.3 09:13:26 INFO - moztest == 0.8 09:13:26 INFO - mozversion == 1.4 09:13:26 INFO - psutil == 3.1.1 09:13:26 INFO - requests == 1.2.3 09:13:26 INFO - wsgiref == 0.1.2 09:13:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:26 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 0x10eaba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedbd8ae00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ced30>, '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 0x10e734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6b20>, '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.B9vs46Y0qt/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.NZdV1DnBan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:13:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:13:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:13:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B9vs46Y0qt/Render', 09:13:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:26 INFO - 'HOME': '/Users/cltbld', 09:13:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:26 INFO - 'LOGNAME': 'cltbld', 09:13:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:26 INFO - 'MOZ_NO_REMOTE': '1', 09:13:26 INFO - 'NO_EM_RESTART': '1', 09:13:26 INFO - 'PAGER': '/bin/cat', 09:13:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:26 INFO - 'PWD': '/builds/slave/test', 09:13:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:26 INFO - 'SHELL': '/bin/bash', 09:13:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZdV1DnBan/Listeners', 09:13:26 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 09:13:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:26 INFO - 'USER': 'cltbld', 09:13:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:26 INFO - 'XPC_FLAGS': '0x0', 09:13:26 INFO - 'XPC_SERVICE_NAME': '0', 09:13:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:13:27 INFO - Cleaning up... 09:13:27 INFO - Return code: 0 09:13:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eaba490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea30570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcedbd8ae00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ced30>, '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 0x10e734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6b20>, '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.B9vs46Y0qt/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.NZdV1DnBan/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:13:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:13:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B9vs46Y0qt/Render', 09:13:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:27 INFO - 'HOME': '/Users/cltbld', 09:13:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:27 INFO - 'LOGNAME': 'cltbld', 09:13:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:27 INFO - 'MOZ_NO_REMOTE': '1', 09:13:27 INFO - 'NO_EM_RESTART': '1', 09:13:27 INFO - 'PAGER': '/bin/cat', 09:13:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:27 INFO - 'PWD': '/builds/slave/test', 09:13:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:27 INFO - 'SHELL': '/bin/bash', 09:13:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZdV1DnBan/Listeners', 09:13:27 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 09:13:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:27 INFO - 'USER': 'cltbld', 09:13:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:27 INFO - 'XPC_FLAGS': '0x0', 09:13:27 INFO - 'XPC_SERVICE_NAME': '0', 09:13:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:13:27 INFO - Cleaning up... 09:13:27 INFO - Return code: 0 09:13:27 INFO - Running post-action listener: _resource_record_post_action 09:13:27 INFO - Running post-action listener: _start_resource_monitoring 09:13:27 INFO - Starting resource monitoring. 09:13:27 INFO - [mozharness: 2017-01-30 17:13:27.354981Z] Finished create-virtualenv step (success) 09:13:27 INFO - [mozharness: 2017-01-30 17:13:27.356010Z] Running install step. 09:13:27 INFO - Running pre-action listener: _resource_record_pre_action 09:13:27 INFO - Running main action method: install 09:13:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:13:27 INFO - Reading from file tmpfile_stdout 09:13:27 INFO - Detecting whether we're running mozinstall >=1.0... 09:13:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:13:27 INFO - Reading from file tmpfile_stdout 09:13:27 INFO - Output received: 09:13:27 INFO - Usage: mozinstall [options] installer 09:13:27 INFO - Options: 09:13:27 INFO - -h, --help show this help message and exit 09:13:27 INFO - -d DEST, --destination=DEST 09:13:27 INFO - Directory to install application into. [default: 09:13:27 INFO - "/builds/slave/test"] 09:13:27 INFO - --app=APP Application being installed. [default: firefox] 09:13:27 INFO - mkdir: /builds/slave/test/build/application 09:13:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:13:48 INFO - Reading from file tmpfile_stdout 09:13:48 INFO - Output received: 09:13:48 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:13:48 INFO - Running post-action listener: _resource_record_post_action 09:13:48 INFO - [mozharness: 2017-01-30 17:13:48.657244Z] Finished install step (success) 09:13:48 INFO - [mozharness: 2017-01-30 17:13:48.657415Z] Running run-tests step. 09:13:48 INFO - Running pre-action listener: _resource_record_pre_action 09:13:48 INFO - Running main action method: run_tests 09:13:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:13:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:13:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:13:48 INFO - 2017-01-30 09:13:48.672 screenresolution[1916:20169] starting screenresolution argv=screenresolution get 09:13:48 INFO - 2017-01-30 09:13:48.675 screenresolution[1916:20169] Display 0: 1600x1200x32@60 09:13:48 INFO - 2017-01-30 09:13:48.680 screenresolution[1917:20172] starting screenresolution argv=screenresolution list 09:13:48 INFO - Available Modes on Display 0 09:13:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:13:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:13:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:13:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:13:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:13:48 INFO - Intel Iris: 09:13:48 INFO - Chipset Model: Intel Iris 09:13:48 INFO - Type: GPU 09:13:48 INFO - Bus: Built-In 09:13:48 INFO - VRAM (Dynamic, Max): 1536 MB 09:13:48 INFO - Vendor: Intel (0x8086) 09:13:48 INFO - Device ID: 0x0a2e 09:13:48 INFO - Revision ID: 0x0009 09:13:48 INFO - Displays: 09:13:48 INFO - Display: 09:13:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:13:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:13:48 INFO - Display Serial Number: 1600x1200 60 09:13:48 INFO - Main Display: Yes 09:13:48 INFO - Mirror: Off 09:13:48 INFO - Online: Yes 09:13:48 INFO - Rotation: Supported 09:13:48 INFO - Return code: 0 09:13:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:13:48 INFO - Python 2.7.3 09:13:48 INFO - Return code: 0 09:13:48 INFO - grabbing minidump binary from tooltool 09:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:13:48 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 0x10e8ced30>, '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 0x10e734db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8c6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:13:48 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:13:48 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:13:48 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:13:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:13:49 INFO - Return code: 0 09:13:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:13:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:13:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:13:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:13:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0268', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0268 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B9vs46Y0qt/Render', 09:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:49 INFO - 'HOME': '/Users/cltbld', 09:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:49 INFO - 'LOGNAME': 'cltbld', 09:13:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:13:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:49 INFO - 'MOZ_NO_REMOTE': '1', 09:13:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:13:49 INFO - 'NO_EM_RESTART': '1', 09:13:49 INFO - 'PAGER': '/bin/cat', 09:13:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:49 INFO - 'PWD': '/builds/slave/test', 09:13:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:49 INFO - 'SHELL': '/bin/bash', 09:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NZdV1DnBan/Listeners', 09:13:49 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 09:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:49 INFO - 'USER': 'cltbld', 09:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:49 INFO - 'XPC_FLAGS': '0x0', 09:13:49 INFO - 'XPC_SERVICE_NAME': '0', 09:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0268', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:13:49 INFO - mozversion application_buildid: 20170130065342 09:13:49 INFO - mozversion application_changeset: 0f339c1e154f75c484fe2fac507a9a225990d212 09:13:49 INFO - mozversion application_display_name: Firefox 09:13:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:13:49 INFO - mozversion application_name: Firefox 09:13:49 INFO - mozversion application_remotingname: firefox 09:13:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:13:49 INFO - mozversion application_vendor: Mozilla 09:13:49 INFO - mozversion application_version: 52.0 09:13:49 INFO - mozversion platform_buildid: 20170130065342 09:13:49 INFO - mozversion platform_changeset: 0f339c1e154f75c484fe2fac507a9a225990d212 09:13:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:13:49 INFO - mozversion platform_version: 52.0 09:13:49 INFO - using testdate: 1485796429 09:13:49 INFO - actual date: 1485796429 09:13:49 INFO - starting webserver on 'localhost:55985' 09:13:49 INFO - SUITE-START | Running 2 tests 09:13:49 INFO - TEST-START | dromaeo_css 09:13:49 INFO - operating with platform_type : mac_ 09:13:49 INFO - Initialising browser for dromaeo_css test... 09:13:49 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpWlykQe/profile http://localhost:55985/getInfo.html) 09:13:52 INFO - PROCESS | 1927 | 2017-01-30 09:13:52.347 plugin-container[1930:20342] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b33, name = 'com.apple.tsm.portname' 09:13:52 INFO - PROCESS | 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:52 INFO - PROCESS | 1927 | 2017-01-30 09:13:52.348 plugin-container[1930:20342] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 09:13:52 INFO - PROCESS | 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:52 INFO - PROCESS | 1927 | 2017-01-30 09:13:52.348 plugin-container[1930:20342] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:13:52 INFO - PROCESS | 1927 | __metrics Screen width/height:1600/1200 09:13:52 INFO - PROCESS | 1927 | colorDepth:24 09:13:52 INFO - PROCESS | 1927 | Browser inner width/height: 1024/689 09:13:52 INFO - PROCESS | 1927 | __metrics 09:13:52 ERROR - PROCESS | 1927 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 09:13:53 INFO - TEST-INFO | 1927: exit 0 09:13:53 INFO - Browser initialized. 09:13:53 INFO - Running cycle 1/1 for dromaeo_css test... 09:13:53 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpWlykQe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:55 INFO - PROCESS | 1931 | 2017-01-30 09:13:55.511 plugin-container[1932:20473] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 09:13:55 INFO - PROCESS | 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:55 INFO - PROCESS | 1931 | 2017-01-30 09:13:55.511 plugin-container[1932:20473] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 09:13:55 INFO - PROCESS | 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:13:55 INFO - PROCESS | 1931 | 2017-01-30 09:13:55.511 plugin-container[1932:20473] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:14:05 INFO - PROCESS | 1931 | RSS: Main: 177205248 09:14:05 INFO - PROCESS | 1931 | 09:17:07 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55985/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55985/tests/dromaeo/cssquery-ext.html) 09:17:08 INFO - PROCESS | 1931 | RSS: Main: 182673408 09:17:08 INFO - PROCESS | 1931 | 09:20:11 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55985/tests/dromaeo/cssquery-ext.html (next: http://localhost:55985/tests/dromaeo/cssquery-jquery.html) 09:20:11 INFO - PROCESS | 1931 | RSS: Main: 193200128 09:20:11 INFO - PROCESS | 1931 | 09:23:13 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55985/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55985/tests/dromaeo/cssquery-mootools.html) 09:23:14 INFO - PROCESS | 1931 | RSS: Main: 195006464 09:23:14 INFO - PROCESS | 1931 | 09:26:16 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55985/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55985/tests/dromaeo/cssquery-prototype.html) 09:26:16 INFO - PROCESS | 1931 | RSS: Main: 198115328 09:26:16 INFO - PROCESS | 1931 | 09:29:19 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55985/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55985/tests/dromaeo/cssquery-yui.html) 09:29:20 INFO - PROCESS | 1931 | RSS: Main: 199446528 09:29:20 INFO - PROCESS | 1931 | 09:32:22 INFO - PROCESS | 1931 | Cycle 1(1): loaded http://localhost:55985/tests/dromaeo/cssquery-yui.html (next: http://localhost:55985/tests/dromaeo/cssquery-dojo.html) 09:32:22 INFO - PROCESS | 1931 | RSS: Main: 199884800 09:32:22 INFO - PROCESS | 1931 | 09:32:22 INFO - PROCESS | 1931 | __start_tp_report 09:32:22 INFO - PROCESS | 1931 | _x_x_mozilla_page_load 09:32:22 INFO - PROCESS | 1931 | _x_x_mozilla_page_load_details 09:32:22 INFO - PROCESS | 1931 | |i|pagename|runs| 09:32:22 INFO - PROCESS | 1931 | |0;dojo.html;2979.6424429068516;3599.388104022316;3599.8380072896716;3607.4408466687673;3626.474161246619;6016.789412370568;6053.939460605393;6074.392560743934;6082.5742198046155;6101.664408457533;6014.8797024059495;6078.604890682102;6097.207363042809;6100.23747031621;6131.233595800525;5410.404855465896;5438.53772429344;5466.207399927008;5475.479829416199;5558.388577256498;5499.120140777476;5529.281193444866;5552.694601796899;5554.363608240749;5567.415421380758;5336;5403.918941215885;5495.148252020943;5503.752331145088;5506.614536982413;5434.266374039514;5454.263674403965;5465.098258787295;5469.343678758534;5492.313460817358;5276.551493123051;5408.4050754416985;5409.459054094598;5437.564994800407;5481.835544933659;4987.301777751118;6072.36240194782;6095.9695201524155;6101.368489499342;6123.540734444934;6149.2620885494025;6210.50315974721;6240.251169859601;6256;6298.937010629927;6400.519961002943;6402.359764023607;6419.35806419359;6427.6786160692245;6440.774572889926;6095.085737139465;6121.296050954153;6132.141500189977;6154.692265386758;6180;6351.079093531412;6373.936260637427;6386.07401926718;6387.776427825004;6588.637624930579;6197.504199664016;6232.781852635227;6248.4064013918605;6277.340879207709;6311.400416960473;6411.743530258831;6433.485321174295;6433.935660643334;6435.678216089177;6443.355664433519;6198.566100373021;6211.440970312694;6303.117563541108;6319.462845658079;6338.492920566344;5607.943920560824;6142.232220972379;6270.655113968775;6319.020551814476;6319.46284565817;5174.353205849269;5182.6372153949615;5183.844484665466;5190.24741412493;5193.195054766517;4505.189065968158;4537.954620453819;4551.06703125865;4565.360849481075;4620.53794620542;4950.950490495121;6153.600015998945;6237.563370564017;6263.467605253604;6283;6411.877921363836;6441.838954026187;6464.385883341075;6477.805665830033;6493.577917435352;6098.847528811815;6113.567524283718;6137.4689182037;6137.60105593135;6196.225471816023;5645.237892884408;5645.350784659817;5645.5483561314995;5645.830625081254;5671.773129074873;6512.47900167976;6521.543491955613;6531.248906375828;6577.144971153815;6645.468362530889;6234.220722409699;6300.59046161998;6300.716467759054;6340.587861788969;6410.45511131559;6632.436242919406;6637.867242655217;6654.83362915931;6658.5671931324305;6660.900086498609;4672.462666793226;6337.081123237012;6349.619022858643;6358.554901156875;6372.713227904663;6032.215811944419;6275.278342990432;6314.526410519137;6416.7754128605275;6420;5143.588512919032;6153.661548614869;6214.161088253028;6265.906011409741;6281.748730050746;6239.844003899939;6240.126382306572;6250.031245157008;6266.717997690206;6269.717862696099;6048.909266361096;6122.581127188433;6197.5351848610635;6238.064290356483;6265.248170219421;5150.48495150482;6198.442140207403;6216.036514340284;6249.406306400884;6275.933091374569;6257.561970662192;6261.749530018748;6274.529410294337;6286.465650419583;6301.8109456716375 09:32:22 INFO - PROCESS | 1931 | |1;ext.html;494.57713654825125;501.87202263422824;512.1881817319548;539.3958766181876;553.1094937151188;38159.80920095403;40837.38743918844;40930.79534602327;40956.976075598126;40959.795201024026;41207.17585648293;41415;41668.16663666725;41694.99999999993;41876.58123418765;9614.0385961404;9734.853977190347;9843.655472058479;9845.458499782544;9845.803083938317;9659.082387173243;9688.370255933378;9698.224142068617;9702.07830256128;9719.61121555137;9699;9722.222222222204;9826.263030272723;9856.211503079772;9859.556319965583;9673.2261419087;9782.461964591945;9791.706248812547;9823.91936886942;9834.557444914995;9718.902810971942;9725.124738773473;9739.172170365457;9765.316427850054;9856.605735770561;39335.8033209835;39476.80261598674;39478.21043579112;39518.40722389166;39799;3352.346292472969;3532.993096967352;3599.748017638766;3617.8733744318824;3684.5946945835935;29517;29695.554566681516;29737.70262297371;30397.061743101007;30676;3213.3412650406844;3353.3125709229794;3378.054144839449;3409.4203985322556;3486.9128271793156;509448;509718;518905;519196;519700;58268;58388.708056460295;58416.4158358411;58516;58523.4147658518;60855;61066.77866442735;61137;61174;61335.69332153311;15944.441944531885;16112.113833739257;16197.271122799502;16203.83796162047;16214.432494862858;12178.81731774024;12209.816852747215;12210.633680989584;12228;12376.443060062318;14819.259037048105;15338.616534586501;15345.079295242322;15465.458708945132;15655.060696358254;3150.4329220740487;3423.17843717506;6001.339852616209;6478.1578394808375;6629.939209726421;752.8042708895726;826.6941231744294;852.9360297977677;990.5839547390113;1063.1388575254068;25572.545699058475;27877.86061069682;29582.964596239028;29641.703582964325;30001.849990750343;1063.1813503602184;1081.5565618096543;1084.2572837606265;1087.7117563845586;1161.4192903548226;13796.310184490736;16441.91779041121;16454.83545164557;16905.15474226284;16940.83059169417;653160;695371.5231423911;711811;715995;718136;57806;58303.70848145817;58307.12539311914;58392;58483.70758146182;1393.108410617206;1396.2460271453504;1430.213382639544;1432.3840748478046;1435.103060587133;30473.695263047084;30891.382172356876;31087.689123109383;31894;32261.677383225866;48780.5121948776;49104.75447622739;49311.75344123256;49393.259101112795;49412.01175976532;612833;617711;618003;618027;618032;25128.37179070538;26705.732942670325;26706.332341691614;26706.732932671202;26771.7322826777;45867.54132458766;45905.54094459146;46081.76959115183;46196.076078478916;46252.306215406126;38295;38509.037274068374;40077;40350.9912252196;41093.79453102835;32530.674693252764;38546.80726596349;38908.221835563694;39206.215875682894;39564.60435395609;37372;38336.2332753349;38362.42456363101;38954.2209155821;38999.02502437462;28316.136157849734;32140.357192856478;34882;34999.65000349964;35130.29739405249;21583.892080539496;26137.869310653325;26200.606990895532;26266.343341416617;26501.867490662422 09:32:22 INFO - PROCESS | 1931 | |2;jquery.html;377.97165212609053;383.7448097015485;387.46336324191003;404.29248814574487;423.87495688771827;16358.591035224095;20881.582368352643;20939.371818845422;21750.782492175094;21782.346529604118;21258.36224913255;21318;21570.89214553929;21851.453713657127;21892.014859331328;5973.820785376435;6203.596766210205;6279.403456671632;6289.905651415233;6332.905006424908;4963.627727920402;5963.135345374918;6098.69506524672;6204.224471941007;6204.906926396107;6066.332703402622;6130.724117414705;6158.199434073565;6170.259568851721;6170.7531698732;5866.882662346772;6077.3314935357075;6121.081837724377;6126.081087836816;6147.262328520584;5263.973680131575;5267.947320526784;5288.338957630296;5343.064963631349;5380.569554435636;26021.73978260231;26568.601470977952;26833.329166770793;27100.45799084027;27165.1850444487;2312.7802858728396;2465.0879174705474;2596.701379341381;2830.249983754307;2922.5616157576533;12571.622851314476;13165.473381064747;15170.393184272616;16214.918925405416;16403;1568.9267272565187;1587.3253867106434;1869.2242719271485;1945.9708104378446;1974.5655955689726;83937.07059643569;92029;118274.99999999914;119126;119421;11375;11512.76974460506;11651.00966417847;11820;11887;11578.305301681927;12830.101892867597;14733.336999835034;14754.409823606935;14766.852331476763;8503.70237041713;8831.823363532694;8835.469871807712;8913;8950.283977281802;7976.641051152712;8772.640240762692;8805.42764720291;8812.559372031501;8918.375713700108;6443.162388889415;6590.340965903467;7042.366187043191;7076.858462830714;7084.645767711697;1932.4202739178472;1958.3243780895568;1958.9118489667999;1959.206521358852;1966.8426525877896;910.7677542226822;926.6247169896203;963.797602503468;972.8054389122062;1104.315324498816;22344.99447524865;23001;23014;23156.65265021026;23198.768012319997;965.5365424596233;1005.2259759984771;1007.3401921741271;1013.1793247469574;1062.628080171949;11468.54125834974;11942.402879855972;12183.512659493697;12203.694907627203;12218.572349967708;576784;588218;609397;615406;621958;68000.31999680039;70970.29029709636;72649;79136;120253.19620205704;1005.492226425665;1042.4943902207424;1043.4261156363818;1060.9257351985286;1061.984070238947;19166.329178479245;19658.50853728668;19801.702974455682;19840.70238946446;19847.90836504006;38667.2266554673;48473.5152648469;48840.75579622221;51123.48876511187;51318;62371.12886613409;72303;73128.26871731428;75116;76516.61741691256;6672.899906501503;7924.296276300623;8164.10194878551;8195.262426381416;8285.54429506383;11883;12414.751704965669;13204.339783011003;13281.468741250243;13295.335233238493;9240.491772952284;10932.0707739843;11108.777824443305;11244.775104497701;11471.770564588829;10954.287971281841;10992.230543861853;11032.724181895517;11355.432228388383;11462.484188211718;11198.38408887519;11397.886021139682;11562.132840036862;11724.472398742248;11822.822657660314;8095.473794203594;8166.6733330668485;8212.753617391489;8323.542205178532;8374.366998435247;11366.829497557708;11382.772344553228;11409.315441073562;11657.358845263592;11741.530338786353 09:32:22 INFO - PROCESS | 1931 | |3;mootools.html;1523.1013701915872;1531.7166324230016;1740.7998080220777;1840.9171587278572;1889.9716504252438;8268.007839059313;8478.618462169201;8757;8760;8762.912370876289;8629.093945135748;8760.255378292837;8762.123787621234;8762.167594078568;8775;2409.3133456964724;2435.2937648082034;2452.5217582571404;2454.7667971542674;2456.6437866509345;2415.335782659765;2442.1086303499183;2456.5701002324613;2471.02394554151;2475.9133430329944;2220.311703371952;2423.466837295792;2431.5866302728577;2440.0971640493044;2447.889844956981;2392.6411038344213;2395.0419832067137;2398.0168131066266;2415.891284892184;2430.2952143878256;2387.85672859629;2423.503181847717;2460.1143588308196;2465.3466831289725;2469.2221950085845;8601.31189504838;8617.310615150773;8632.13678632138;8722.215000649972;8778.780530486789;5251.36983561975;5386.111291636876;5410.945890541084;5420.268263784378;5422.53908417785;3564.269324788438;3600.3879340512176;3615;3634.018814919983;3655.342038433081;1069.6737495064003;1077.1921059205595;1128.2948157401624;1137.2380505061628;1142.6457798082622;10199.827019892733;10776.999999999922;11234.606788762354;11276.492557834836;11342.149338799623;4319.460067491564;4531.750753708512;4586.88532786683;4597.862064138081;4598.8620341389815;8800.075992020873;8864;8907.51008694515;8931.419457735356;8936.597853096497;5212.061828870839;5235.188545775411;5236.9738151309;5239.502247286502;5248.317718696621;4385.771983844528;4394.560543945579;4835.202191638411;4892.828750993769;4893.119238537026;3800.809959502014;3817.408301713239;3860.652541271299;3909.4722212501515;3928.6071392860486;1005.8181636576993;1007.4660429972126;1172.4874911364245;1177.6349331707197;1186.602488166474;932.6782260120248;938.5823308627731;941.3316545252948;947.251671179774;959.3716115944068;5320.973395133;5777.191193232867;5868.530517558585;5895.587308888423;6092.964196086676;447.59269065150556;458.4750460722521;460.78573463339706;462.10351917280366;463.63604570412065;3400.9149771255916;3473.496343030297;4033.495813023372;4101.5488296287385;4124.649404800625;10201.489925503694;10442.477876106164;10579.153667706565;10593.152547796159;10610.628627997983;10017.899821001696;10188.235882308856;10487.528061237263;10563.207759418074;10588.205884558689;8077.434579579491;8111.432199745961;8217.671293148207;8282.834343313221;8285.295749861209;5789.710514474344;5987.550933679904;5988.071848863433;5988.520918326611;6041.395860414099;9616.278779091455;9626.374285671409;9646.649469074475;9660.806783864424;9807.06832850878;9641.89118251382;9685.612575496902;9822.901770982198;9873.111419972238;9908.405495670282;6433.839154021187;6443.904536228946;6612.70242839083;6953.33943275388;7041.788746337618;7558.58427786477;8193.918060819315;8318.45930014547;8361.331093512626;8398.622062007102;6129.754809807558;7042.964785176041;7099.467539934629;7189.604571748395;7240.203577606354;6051.606645568024;7049.929500705132;7079.327463890922;7101.254368291462;7145.071140751669;6832.0435139081565;6873.965630171817;6915.688794004182;6972.546784458994;7000.7549735759;5011.674241174458;5032.572231360376;5077.416097148875;5082.136036873814;5085.872853178552;4375.081232941118;5017.62367822422;5058.31712718793;5117.43708192106;5143.022825663112 09:32:22 INFO - PROCESS | 1931 | |4;prototype.html;279.60435983083937;303.7706531568667;317.59665225164036;321.5177234148777;324.3205484509952;4431.468223813143;5550.973069982057;5639.887202255953;5660.745266463009;5719.19931209631;5536.81295888124;5598.412166722496;5604.915926261099;5619.157126431038;5658.773649054043;3536.9115772105774;3855.5951625079383;3962.1679447315955;4231.344141658048;4278.6363159131515;3985.70107241958;4232.49210094789;4285.550017248191;4285.657147428214;4286.657067434613;3471.8716417164396;4155.854545090922;4195.349720793282;4219.071804203086;4245.554216807237;3861.8841434757;4213.683973701954;4218.557051509578;4237.618614324695;4249.6387807036435;3642.8907132786057;3650.470681751158;3658.8536458541626;3664.450332450154;3669.486271921933;4902.166631672589;5106.157484015133;5393.6763794172475;5396.379416367129;5461.508464238198;4257.233697934371;4569.474510431276;4785.401824771903;4792.9281060784115;4796.450626536338;5606.971965140189;5829.650220986712;5863.853403664943;5904.409559044104;5913.586048976574;3930.1157239621143;3950.525936887592;3970.305196590585;4005.7396269242404;4019.718619696622;5489.505944464978;6259.561830671855;6259.561830671855;6292.828974004007;6345.33373995733;4020.371749441481;4032.689375952827;4040.898977525527;4054.391591483582;4059.106996460774;3460.0830779843363;3800.543934727795;3988.3219852625125;4040.2323558523785;4076.5515793262716;4804.447488538863;4822.7829747660735;4845.781939812717;4846.5153484651955;4851.975740121276;5491.560675145979;5924.348321684612;5956.493698035715;5998.910016349758;6001.459868611846;1189.423129782055;1193.540486912545;1264.5068423314915;1326.973460530784;1346.7845144776788;692.394155114275;830.8296799156159;892.8973168085753;900.230303090861;903.4353529044347;4678.175511550498;4760.643216683228;5102.795888164432;5321.3348331458565;5337.359516858002;4782.9760851196215;5101.20931255656;5564.360098588714;5732.684702341411;5756.597038207286;4904.583110435653;5065.480355893291;5336.626436149432;5339.546138578186;5357.812476563302;2695.5848179705813;2981.092101055326;3033.450862490891;3041.680623534541;3071.754259659222;5191.532762051358;5643.858903527363;5686.867424715731;5711.428857114255;6013.789517366871;5304.143549757554;5465.726713664301;5673.659580425187;5828.59199856006;6040.697965101727;4866.9513304867205;4886.289798570472;5076.847694569169;5879.588898664375;5958.463738263577;5209.635325527177;5313.654612450178;5797.797077102281;5832.679202643895;5860.461863538915;5063.8480845574695;5593.888122237614;5635.661200601704;5669.93047537654;5716.628419152742;5093.8726531837;5355.955588660174;5415.431379705232;5759.272218334399;5921.99326114553;3434.5614613623607;3606.1969015492255;3762;3858.9228215436096;3886.9805650972694;5374.785008599612;6021.946159422171;6075;6099.2985806632805;6125.070602760116;4998.575121114746;5431.832156086461;5879.353271140086;5941.37615550361;5942.64344139353;5127.66670166438;5350.973245133905;5762.164486149569;5792.710364481674;5850.36189866838;5691.345495268097;5730.369659337386;5732.426757324234;5749.626274292157;5753.568482363923;5455.372632147195;5677.489025987681;5696.772129114955;5821.446962538552;5927.4665280125;4715;5557.777688892399;5717.714114294352;5758.654480731169;5786.652800831964 09:32:22 INFO - PROCESS | 1931 | |5;yui.html;668.4931506849315;739.4084732214228;774.5352788327001;793.2939683681525;836.3351135847001;14772.03981741186;15304.234788260597;15692.21538923055;15699.587037166653;15771.053736775788;13929.30353482327;14079.296035198251;14216.92891535541;14217.431302747904;14259.857401426008;872.8516152254116;885.5041176940902;886.6453418632534;890.5992303463435;891.2246345679268;820.2125959079291;848.0628905059913;858.9226969572737;864.9879640820255;866.1079088538784;758.5221310574331;913.5204017890594;914.6112902016616;918.4168053286178;920.4293338130368;891.38494438837;893.1782759860944;899.685110211424;900.3292547052455;910.0035461170041;661.8246164766341;686.1114856261154;687.8108520156946;687.9002544631051;688.9414399776025;12648.810267845989;12660.240385576895;12769.808452873121;12814.103012789108;13036.41336139876;11439.19925605208;11760.647180584596;11952.04383649306;12015.93992030043;12142;10075.949620251926;10186.160173246219;10347.379157250514;10675.466226688635;10782;3343.498475228711;3617.728670349708;3678.2275722098393;3711.406175012012;3814.5231846019246;57638.71180644112;61012.389876101115;77718.83421748679;87624;88178.67731984025;10603.94698026505;10732.195085368628;10740.677779666623;10982.505787239594;11234.15743819217;16953.915230424016;20894;20908.68636970447;21067.789322106582;21339.14643414246;7769.106552746393;7788.454808163374;7883.5664038478435;8167.877481837777;8188.50868947865;4451.421315228999;4479.126570318756;4532.116237333754;4547.636189104864;4607.631389488833;3522.0490467573613;3597.8560857565403;3636.1091532574687;3650.1969566695284;3656.0677027358383;665.0921492163259;669.0833558025439;680.6834821807884;685.3454950522239;686.807693845724;634.1819053421035;653.4119292636665;662.9403353698191;675.2774531251514;687.6114995027793;8993.595288212045;9240.122188392092;9498.715038548855;9529.952350238344;9611.183049440875;588.8233529941086;667.4960404894274;676.9018492318586;707.1478867964091;711.5410560188649;4380.496242932104;4571;4598.97700511502;4609.262517997105;4618.49196588375;76579.61710191525;77454.83817742625;79763.60118199488;121414.39292803656;127528;95807;108668;112431;112757;114281;77813.61093194611;95412.0458795417;95771;97419.51290243503;100480;8227.382946278933;8785.385023048326;8883.800686907185;9003.774905627411;9038.412503187272;60360.69819651049;61626.4593385151;62981.37018629755;64236.71526569536;64436.677816610616;70872;72031.27968720246;72547.27452725616;85830;89621;11051.115910727283;12753.872461275269;12871.742565148832;13098.934505327412;13269.668258293234;11699.88300116988;14312.785308220593;14618;15030;15193.392264309305;10130.746731331776;11058.447077645924;11638;11948.820767688665;14230.644233894236;12664.366781660696;13333.266670333225;13540.32298385057;13603.523876664269;13613.183209007491;12559.623211303675;12896.935515322364;12987.415566299727;13102.606921792361;13274.203547786778;10719.732006699584;11187.944060279646;11339.829902551302;11698.76602467963;11744.237918215613;11170.27393219438;11441.313521188755;11711.707207319885;11732.12009099304;11747.706307342385 09:32:22 INFO - PROCESS | 1931 | __end_tp_report 09:32:22 INFO - PROCESS | 1931 | __start_cc_report 09:32:22 INFO - PROCESS | 1931 | _x_x_mozilla_cycle_collect,356 09:32:22 INFO - PROCESS | 1931 | __end_cc_report 09:32:22 INFO - PROCESS | 1931 | __startTimestamp1485797542645__endTimestamp 09:32:22 INFO - PROCESS | 1931 | 09:32:22 INFO - PROCESS | 1931 | ------- Summary: start ------- 09:32:22 INFO - PROCESS | 1931 | Number of tests: 6 09:32:22 INFO - PROCESS | 1931 | 09:32:22 INFO - PROCESS | 1931 | [#0] dojo.html Cycles:165 Average:5929.10 Median:6197.50 stddev:655.47 (10.6%) stddev-sans-first:615.29 09:32:22 INFO - PROCESS | 1931 | Values: 2979.6 3599.4 3599.8 3607.4 3626.5 6016.8 6053.9 6074.4 6082.6 6101.7 6014.9 6078.6 6097.2 6100.2 6131.2 5410.4 5438.5 5466.2 5475.5 5558.4 5499.1 5529.3 5552.7 5554.4 5567.4 5336.0 5403.9 5495.1 5503.8 5506.6 5434.3 5454.3 5465.1 5469.3 5492.3 5276.6 5408.4 5409.5 5437.6 5481.8 4987.3 6072.4 6096.0 6101.4 6123.5 6149.3 6210.5 6240.3 6256.0 6298.9 6400.5 6402.4 6419.4 6427.7 6440.8 6095.1 6121.3 6132.1 6154.7 6180.0 6351.1 6373.9 6386.1 6387.8 6588.6 6197.5 6232.8 6248.4 6277.3 6311.4 6411.7 6433.5 6433.9 6435.7 6443.4 6198.6 6211.4 6303.1 6319.5 6338.5 5607.9 6142.2 6270.7 6319.0 6319.5 5174.4 5182.6 5183.8 5190.2 5193.2 4505.2 4538.0 4551.1 4565.4 4620.5 4951.0 6153.6 6237.6 6263.5 6283.0 6411.9 6441.8 6464.4 6477.8 6493.6 6098.8 6113.6 6137.5 6137.6 6196.2 5645.2 5645.4 5645.5 5645.8 5671.8 6512.5 6521.5 6531.2 6577.1 6645.5 6234.2 6300.6 6300.7 6340.6 6410.5 6632.4 6637.9 6654.8 6658.6 6660.9 4672.5 6337.1 6349.6 6358.6 6372.7 6032.2 6275.3 6314.5 6416.8 6420.0 5143.6 6153.7 6214.2 6265.9 6281.7 6239.8 6240.1 6250.0 6266.7 6269.7 6048.9 6122.6 6197.5 6238.1 6265.2 5150.5 6198.4 6216.0 6249.4 6275.9 6257.6 6261.7 6274.5 6286.5 6301.8 09:32:22 INFO - PROCESS | 1931 | 09:32:22 INFO - PROCESS | 1931 | [#1] ext.html Cycles:180 Average:73357.01 Median:39938.00 stddev:164828.98 (412.7%) stddev-sans-first:165200.58 09:32:22 INFO - PROCESS | 1931 | Values: 494.6 501.9 512.2 539.4 553.1 38159.8 40837.4 40930.8 40957.0 40959.8 41207.2 41415.0 41668.2 41695.0 41876.6 9614.0 9734.9 9843.7 9845.5 9845.8 9659.1 9688.4 9698.2 9702.1 9719.6 9699.0 9722.2 9826.3 9856.2 9859.6 9673.2 9782.5 9791.7 9823.9 9834.6 9718.9 9725.1 9739.2 9765.3 9856.6 39335.8 39476.8 39478.2 39518.4 39799.0 3352.3 3533.0 3599.7 3617.9 3684.6 29517.0 29695.6 29737.7 30397.1 30676.0 3213.3 3353.3 3378.1 3409.4 3486.9 509448.0 509718.0 518905.0 519196.0 519700.0 58268.0 58388.7 58416.4 58516.0 58523.4 60855.0 61066.8 61137.0 61174.0 61335.7 15944.4 16112.1 16197.3 16203.8 16214.4 12178.8 12209.8 12210.6 12228.0 12376.4 14819.3 15338.6 15345.1 15465.5 15655.1 3150.4 3423.2 6001.3 6478.2 6629.9 752.8 826.7 852.9 990.6 1063.1 25572.5 27877.9 29583.0 29641.7 30001.8 1063.2 1081.6 1084.3 1087.7 1161.4 13796.3 16441.9 16454.8 16905.2 16940.8 653160.0 695371.5 711811.0 715995.0 718136.0 57806.0 58303.7 58307.1 58392.0 58483.7 1393.1 1396.2 1430.2 1432.4 1435.1 30473.7 30891.4 31087.7 31894.0 32261.7 48780.5 49104.8 49311.8 49393.3 49412.0 612833.0 617711.0 618003.0 618027.0 618032.0 25128.4 26705.7 26706.3 26706.7 26771.7 45867.5 45905.5 46081.8 46196.1 46252.3 38295.0 38509.0 40077.0 40351.0 41093.8 32530.7 38546.8 38908.2 39206.2 39564.6 37372.0 38336.2 38362.4 38954.2 38999.0 28316.1 32140.4 34882.0 34999.7 35130.3 21583.9 26137.9 26200.6 26266.3 26501.9 09:32:22 INFO - PROCESS | 1931 | 09:32:22 INFO - PROCESS | 1931 | [#2] jquery.html Cycles:180 Average:33657.60 Median:14309.22 stddev:99291.59 (693.9%) stddev-sans-first:99538.68 09:32:22 INFO - PROCESS | 1931 | Values: 378.0 383.7 387.5 404.3 423.9 16358.6 20881.6 20939.4 21750.8 21782.3 21258.4 21318.0 21570.9 21851.5 21892.0 5973.8 6203.6 6279.4 6289.9 6332.9 4963.6 5963.1 6098.7 6204.2 6204.9 6066.3 6130.7 6158.2 6170.3 6170.8 5866.9 6077.3 6121.1 6126.1 6147.3 5264.0 5267.9 5288.3 5343.1 5380.6 26021.7 26568.6 26833.3 27100.5 27165.2 2312.8 2465.1 2596.7 2830.2 2922.6 12571.6 13165.5 15170.4 16214.9 16403.0 1568.9 1587.3 1869.2 1946.0 1974.6 83937.1 92029.0 118275.0 119126.0 119421.0 11375.0 11512.8 11651.0 11820.0 11887.0 11578.3 12830.1 14733.3 14754.4 14766.9 8503.7 8831.8 8835.5 8913.0 8950.3 7976.6 8772.6 8805.4 8812.6 8918.4 6443.2 6590.3 7042.4 7076.9 7084.6 1932.4 1958.3 1958.9 1959.2 1966.8 910.8 926.6 963.8 972.8 1104.3 22345.0 23001.0 23014.0 23156.7 23198.8 965.5 1005.2 1007.3 1013.2 1062.6 11468.5 11942.4 12183.5 12203.7 12218.6 576784.0 588218.0 609397.0 615406.0 621958.0 68000.3 70970.3 72649.0 79136.0 120253.2 1005.5 1042.5 1043.4 1060.9 1062.0 19166.3 19658.5 19801.7 19840.7 19847.9 38667.2 48473.5 48840.8 51123.5 51318.0 62371.1 72303.0 73128.3 75116.0 76516.6 6672.9 7924.3 8164.1 8195.3 8285.5 11883.0 12414.8 13204.3 13281.5 13295.3 9240.5 10932.1 11108.8 11244.8 11471.8 10954.3 10992.2 11032.7 11355.4 11462.5 11198.4 11397.9 11562.1 11724.5 11822.8 8095.5 8166.7 8212.8 8323.5 8374.4 11366.8 11382.8 11409.3 11657.4 11741.5 09:32:22 INFO - PROCESS | 1931 | 09:32:22 INFO - PROCESS | 1931 | [#3] mootools.html Cycles:180 Average:5564.44 Median:5079.78 stddev:3066.43 (60.4%) stddev-sans-first:3059.99 09:32:22 INFO - PROCESS | 1931 | Values: 1523.1 1531.7 1740.8 1840.9 1890.0 8268.0 8478.6 8757.0 8760.0 8762.9 8629.1 8760.3 8762.1 8762.2 8775.0 2409.3 2435.3 2452.5 2454.8 2456.6 2415.3 2442.1 2456.6 2471.0 2475.9 2220.3 2423.5 2431.6 2440.1 2447.9 2392.6 2395.0 2398.0 2415.9 2430.3 2387.9 2423.5 2460.1 2465.3 2469.2 8601.3 8617.3 8632.1 8722.2 8778.8 5251.4 5386.1 5410.9 5420.3 5422.5 3564.3 3600.4 3615.0 3634.0 3655.3 1069.7 1077.2 1128.3 1137.2 1142.6 10199.8 10777.0 11234.6 11276.5 11342.1 4319.5 4531.8 4586.9 4597.9 4598.9 8800.1 8864.0 8907.5 8931.4 8936.6 5212.1 5235.2 5237.0 5239.5 5248.3 4385.8 4394.6 4835.2 4892.8 4893.1 3800.8 3817.4 3860.7 3909.5 3928.6 1005.8 1007.5 1172.5 1177.6 1186.6 932.7 938.6 941.3 947.3 959.4 5321.0 5777.2 5868.5 5895.6 6093.0 447.6 458.5 460.8 462.1 463.6 3400.9 3473.5 4033.5 4101.5 4124.6 10201.5 10442.5 10579.2 10593.2 10610.6 10017.9 10188.2 10487.5 10563.2 10588.2 8077.4 8111.4 8217.7 8282.8 8285.3 5789.7 5987.6 5988.1 5988.5 6041.4 9616.3 9626.4 9646.6 9660.8 9807.1 9641.9 9685.6 9822.9 9873.1 9908.4 6433.8 6443.9 6612.7 6953.3 7041.8 7558.6 8193.9 8318.5 8361.3 8398.6 6129.8 7043.0 7099.5 7189.6 7240.2 6051.6 7049.9 7079.3 7101.3 7145.1 6832.0 6874.0 6915.7 6972.5 7000.8 5011.7 5032.6 5077.4 5082.1 5085.9 4375.1 5017.6 5058.3 5117.4 5143.0 09:32:22 INFO - PROCESS | 1931 | 09:32:22 INFO - PROCESS | 1931 | [#4] prototype.html Cycles:180 Average:4656.41 Median:5308.90 stddev:1434.78 (27.0%) stddev-sans-first:1400.69 09:32:22 INFO - PROCESS | 1931 | Values: 279.6 303.8 317.6 321.5 324.3 4431.5 5551.0 5639.9 5660.7 5719.2 5536.8 5598.4 5604.9 5619.2 5658.8 3536.9 3855.6 3962.2 4231.3 4278.6 3985.7 4232.5 4285.6 4285.7 4286.7 3471.9 4155.9 4195.3 4219.1 4245.6 3861.9 4213.7 4218.6 4237.6 4249.6 3642.9 3650.5 3658.9 3664.5 3669.5 4902.2 5106.2 5393.7 5396.4 5461.5 4257.2 4569.5 4785.4 4792.9 4796.5 5607.0 5829.7 5863.9 5904.4 5913.6 3930.1 3950.5 3970.3 4005.7 4019.7 5489.5 6259.6 6259.6 6292.8 6345.3 4020.4 4032.7 4040.9 4054.4 4059.1 3460.1 3800.5 3988.3 4040.2 4076.6 4804.4 4822.8 4845.8 4846.5 4852.0 5491.6 5924.3 5956.5 5998.9 6001.5 1189.4 1193.5 1264.5 1327.0 1346.8 692.4 830.8 892.9 900.2 903.4 4678.2 4760.6 5102.8 5321.3 5337.4 4783.0 5101.2 5564.4 5732.7 5756.6 4904.6 5065.5 5336.6 5339.5 5357.8 2695.6 2981.1 3033.5 3041.7 3071.8 5191.5 5643.9 5686.9 5711.4 6013.8 5304.1 5465.7 5673.7 5828.6 6040.7 4867.0 4886.3 5076.8 5879.6 5958.5 5209.6 5313.7 5797.8 5832.7 5860.5 5063.8 5593.9 5635.7 5669.9 5716.6 5093.9 5356.0 5415.4 5759.3 5922.0 3434.6 3606.2 3762.0 3858.9 3887.0 5374.8 6021.9 6075.0 6099.3 6125.1 4998.6 5431.8 5879.4 5941.4 5942.6 5127.7 5351.0 5762.2 5792.7 5850.4 5691.3 5730.4 5732.4 5749.6 5753.6 5455.4 5677.5 5696.8 5821.4 5927.5 4715.0 5557.8 5717.7 5758.7 5786.7 09:32:22 INFO - PROCESS | 1931 | 09:32:22 INFO - PROCESS | 1931 | [#5] yui.html Cycles:180 Average:20750.52 Median:4539.88 stddev:30646.66 (675.1%) stddev-sans-first:30695.54 09:32:22 INFO - PROCESS | 1931 | Values: 668.5 739.4 774.5 793.3 836.3 14772.0 15304.2 15692.2 15699.6 15771.1 13929.3 14079.3 14216.9 14217.4 14259.9 872.9 885.5 886.6 890.6 891.2 820.2 848.1 858.9 865.0 866.1 758.5 913.5 914.6 918.4 920.4 891.4 893.2 899.7 900.3 910.0 661.8 686.1 687.8 687.9 688.9 12648.8 12660.2 12769.8 12814.1 13036.4 11439.2 11760.6 11952.0 12015.9 12142.0 10075.9 10186.2 10347.4 10675.5 10782.0 3343.5 3617.7 3678.2 3711.4 3814.5 57638.7 61012.4 77718.8 87624.0 88178.7 10603.9 10732.2 10740.7 10982.5 11234.2 16953.9 20894.0 20908.7 21067.8 21339.1 7769.1 7788.5 7883.6 8167.9 8188.5 4451.4 4479.1 4532.1 4547.6 4607.6 3522.0 3597.9 3636.1 3650.2 3656.1 665.1 669.1 680.7 685.3 686.8 634.2 653.4 662.9 675.3 687.6 8993.6 9240.1 9498.7 9530.0 9611.2 588.8 667.5 676.9 707.1 711.5 4380.5 4571.0 4599.0 4609.3 4618.5 76579.6 77454.8 79763.6 121414.4 127528.0 95807.0 108668.0 112431.0 112757.0 114281.0 77813.6 95412.0 95771.0 97419.5 100480.0 8227.4 8785.4 8883.8 9003.8 9038.4 60360.7 61626.5 62981.4 64236.7 64436.7 70872.0 72031.3 72547.3 85830.0 89621.0 11051.1 12753.9 12871.7 13098.9 13269.7 11699.9 14312.8 14618.0 15030.0 15193.4 10130.7 11058.4 11638.0 11948.8 14230.6 12664.4 13333.3 13540.3 13603.5 13613.2 12559.6 12896.9 12987.4 13102.6 13274.2 10719.7 11187.9 11339.8 11698.8 11744.2 11170.3 11441.3 11711.7 11732.1 11747.7 09:32:22 INFO - PROCESS | 1931 | -------- Summary: end -------- 09:32:22 INFO - PROCESS | 1931 | 09:32:23 INFO - TEST-INFO | 1931: exit 0 09:32:23 INFO - TEST-OK | dromaeo_css | took 1113965ms 09:32:23 INFO - TEST-START | kraken 09:32:23 INFO - operating with platform_type : mac_ 09:32:23 INFO - Initialising browser for kraken test... 09:32:23 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpCxivoe/profile http://localhost:55985/getInfo.html) 09:32:26 INFO - PROCESS | 1938 | 2017-01-30 09:32:26.125 plugin-container[1940:22431] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 09:32:26 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:32:26 INFO - PROCESS | 1938 | 2017-01-30 09:32:26.125 plugin-container[1940:22431] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 09:32:26 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:32:26 INFO - PROCESS | 1938 | 2017-01-30 09:32:26.125 plugin-container[1940:22431] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:32:26 INFO - PROCESS | 1938 | __metrics Screen width/height:1600/1200 09:32:26 INFO - PROCESS | 1938 | colorDepth:24 09:32:26 INFO - PROCESS | 1938 | Browser inner width/height: 1024/689 09:32:26 INFO - PROCESS | 1938 | __metrics 09:32:26 ERROR - PROCESS | 1938 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 09:32:27 INFO - TEST-INFO | 1938: exit 0 09:32:27 INFO - Browser initialized. 09:32:27 INFO - Running cycle 1/1 for kraken test... 09:32:27 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpCxivoe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:29 INFO - PROCESS | 1941 | 2017-01-30 09:32:29.572 plugin-container[1942:22558] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x894b, name = 'com.apple.tsm.portname' 09:32:29 INFO - PROCESS | 1941 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:32:29 INFO - PROCESS | 1941 | 2017-01-30 09:32:29.573 plugin-container[1942:22558] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 09:32:29 INFO - PROCESS | 1941 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:32:29 INFO - PROCESS | 1941 | 2017-01-30 09:32:29.573 plugin-container[1942:22558] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:32:39 INFO - PROCESS | 1941 | RSS: Main: 177225728 09:32:39 INFO - PROCESS | 1941 | 09:32:42 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55985/tests/kraken/driver.html?testName=audio-beat-detection) 09:32:43 INFO - PROCESS | 1941 | RSS: Main: 190111744 09:32:43 INFO - PROCESS | 1941 | 09:32:45 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55985/tests/kraken/driver.html?testName=audio-dft) 09:32:46 INFO - PROCESS | 1941 | RSS: Main: 199102464 09:32:46 INFO - PROCESS | 1941 | 09:32:49 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55985/tests/kraken/driver.html?testName=audio-fft) 09:32:50 INFO - PROCESS | 1941 | RSS: Main: 202141696 09:32:50 INFO - PROCESS | 1941 | 09:32:52 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55985/tests/kraken/driver.html?testName=audio-oscillator) 09:32:52 INFO - PROCESS | 1941 | RSS: Main: 202125312 09:32:52 INFO - PROCESS | 1941 | 09:32:54 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55985/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:32:55 INFO - PROCESS | 1941 | RSS: Main: 202145792 09:32:55 INFO - PROCESS | 1941 | 09:32:58 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55985/tests/kraken/driver.html?testName=imaging-darkroom) 09:32:58 INFO - PROCESS | 1941 | RSS: Main: 205836288 09:32:58 INFO - PROCESS | 1941 | 09:33:02 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55985/tests/kraken/driver.html?testName=imaging-desaturate) 09:33:02 INFO - PROCESS | 1941 | RSS: Main: 207261696 09:33:02 INFO - PROCESS | 1941 | 09:33:05 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55985/tests/kraken/driver.html?testName=json-parse-financial) 09:33:06 INFO - PROCESS | 1941 | RSS: Main: 206508032 09:33:06 INFO - PROCESS | 1941 | 09:33:07 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55985/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:33:08 INFO - PROCESS | 1941 | RSS: Main: 205742080 09:33:08 INFO - PROCESS | 1941 | 09:33:10 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:33:10 INFO - PROCESS | 1941 | RSS: Main: 205938688 09:33:10 INFO - PROCESS | 1941 | 09:33:12 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:33:13 INFO - PROCESS | 1941 | RSS: Main: 205905920 09:33:13 INFO - PROCESS | 1941 | 09:33:16 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:33:17 INFO - PROCESS | 1941 | RSS: Main: 205795328 09:33:17 INFO - PROCESS | 1941 | 09:33:20 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:33:20 INFO - PROCESS | 1941 | RSS: Main: 206139392 09:33:20 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | Cycle 1(1): loaded http://localhost:55985/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55985/tests/kraken/driver.html?testName=ai-astar) 09:33:22 INFO - PROCESS | 1941 | RSS: Main: 207867904 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | __start_tp_report 09:33:22 INFO - PROCESS | 1941 | _x_x_mozilla_page_load 09:33:22 INFO - PROCESS | 1941 | _x_x_mozilla_page_load_details 09:33:22 INFO - PROCESS | 1941 | |i|pagename|runs| 09:33:22 INFO - PROCESS | 1941 | |0;ai-astar;103;116;116;117;117;116;117;133;171;158 09:33:22 INFO - PROCESS | 1941 | |1;audio-beat-detection;136;134;99;170;142;142;127;111;138;116 09:33:22 INFO - PROCESS | 1941 | |2;audio-dft;195;198;196;229;256;197;195;195;199;201 09:33:22 INFO - PROCESS | 1941 | |3;audio-fft;81;65;85;111;86;67;58;58;77;66 09:33:22 INFO - PROCESS | 1941 | |4;audio-oscillator;88;70;90;60;85;102;71;91;64;90 09:33:22 INFO - PROCESS | 1941 | |5;imaging-gaussian-blur;88;88;88;86;88;106;88;87;88;86 09:33:22 INFO - PROCESS | 1941 | |6;imaging-darkroom;98;157;154;95;152;112;99;97;129;170 09:33:22 INFO - PROCESS | 1941 | |7;imaging-desaturate;88;90;86;87;95;88;90;92;102;119 09:33:22 INFO - PROCESS | 1941 | |8;json-parse-financial;56;55;57;58;70;64;56;60;59;59 09:33:22 INFO - PROCESS | 1941 | |9;json-stringify-tinderbox;69;68;70;80;72;71;68;70;70;72 09:33:22 INFO - PROCESS | 1941 | |10;stanford-crypto-aes;81;80;93;86;82;84;86;81;84;83 09:33:22 INFO - PROCESS | 1941 | |11;stanford-crypto-ccm;121;132;170;151;157;161;162;153;184;149 09:33:22 INFO - PROCESS | 1941 | |12;stanford-crypto-pbkdf2;176;147;222;163;165;163;161;166;163;166 09:33:22 INFO - PROCESS | 1941 | |13;stanford-crypto-sha256-iterative;68;65;66;67;72;73;88;89;85;88 09:33:22 INFO - PROCESS | 1941 | __end_tp_report 09:33:22 INFO - PROCESS | 1941 | __start_cc_report 09:33:22 INFO - PROCESS | 1941 | _x_x_mozilla_cycle_collect,912 09:33:22 INFO - PROCESS | 1941 | __end_cc_report 09:33:22 INFO - PROCESS | 1941 | __startTimestamp1485797602715__endTimestamp 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | ------- Summary: start ------- 09:33:22 INFO - PROCESS | 1941 | Number of tests: 14 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | [#0] ai-astar Cycles:10 Average:126.40 Median:117.00 stddev:21.52 (18.4%) stddev-sans-first:21.10 09:33:22 INFO - PROCESS | 1941 | Values: 103.0 116.0 116.0 117.0 117.0 116.0 117.0 133.0 171.0 158.0 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | [#1] audio-beat-detection Cycles:10 Average:131.50 Median:140.00 stddev:19.74 (14.1%) stddev-sans-first:20.87 09:33:22 INFO - PROCESS | 1941 | Values: 136.0 134.0 99.0 170.0 142.0 142.0 127.0 111.0 138.0 116.0 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | [#2] audio-dft Cycles:10 Average:206.10 Median:198.50 stddev:20.31 (10.2%) stddev-sans-first:21.14 09:33:22 INFO - PROCESS | 1941 | Values: 195.0 198.0 196.0 229.0 256.0 197.0 195.0 195.0 199.0 201.0 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | [#3] audio-fft Cycles:10 Average:75.40 Median:72.00 stddev:16.26 (22.6%) stddev-sans-first:17.12 09:33:22 INFO - PROCESS | 1941 | Values: 81.0 65.0 85.0 111.0 86.0 67.0 58.0 58.0 77.0 66.0 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:86.50 stddev:13.82 (16.0%) stddev-sans-first:14.43 09:33:22 INFO - PROCESS | 1941 | Values: 88.0 70.0 90.0 60.0 85.0 102.0 71.0 91.0 64.0 90.0 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:88.00 stddev:5.93 (6.7%) stddev-sans-first:6.27 09:33:22 INFO - PROCESS | 1941 | Values: 88.0 88.0 88.0 86.0 88.0 106.0 88.0 87.0 88.0 86.0 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | [#6] imaging-darkroom Cycles:10 Average:126.30 Median:132.50 stddev:29.58 (22.3%) stddev-sans-first:29.55 09:33:22 INFO - PROCESS | 1941 | Values: 98.0 157.0 154.0 95.0 152.0 112.0 99.0 97.0 129.0 170.0 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:89.00 stddev:10.06 (11.3%) stddev-sans-first:10.45 09:33:22 INFO - PROCESS | 1941 | Values: 88.0 90.0 86.0 87.0 95.0 88.0 90.0 92.0 102.0 119.0 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | [#8] json-parse-financial Cycles:10 Average:59.40 Median:59.00 stddev:4.53 (7.7%) stddev-sans-first:4.63 09:33:22 INFO - PROCESS | 1941 | Values: 56.0 55.0 57.0 58.0 70.0 64.0 56.0 60.0 59.0 59.0 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:70.50 stddev:3.46 (4.9%) stddev-sans-first:3.60 09:33:22 INFO - PROCESS | 1941 | Values: 69.0 68.0 70.0 80.0 72.0 71.0 68.0 70.0 70.0 72.0 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:3.77 (4.5%) stddev-sans-first:3.84 09:33:22 INFO - PROCESS | 1941 | Values: 81.0 80.0 93.0 86.0 82.0 84.0 86.0 81.0 84.0 83.0 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | [#11] stanford-crypto-ccm Cycles:10 Average:154.00 Median:159.00 stddev:17.91 (11.3%) stddev-sans-first:14.47 09:33:22 INFO - PROCESS | 1941 | Values: 121.0 132.0 170.0 151.0 157.0 161.0 162.0 153.0 184.0 149.0 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.20 Median:165.50 stddev:19.85 (12.0%) stddev-sans-first:20.91 09:33:22 INFO - PROCESS | 1941 | Values: 176.0 147.0 222.0 163.0 165.0 163.0 161.0 166.0 163.0 166.0 09:33:22 INFO - PROCESS | 1941 | 09:33:22 INFO - PROCESS | 1941 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.10 Median:79.00 stddev:10.16 (12.9%) stddev-sans-first:10.34 09:33:22 INFO - PROCESS | 1941 | Values: 68.0 65.0 66.0 67.0 72.0 73.0 88.0 89.0 85.0 88.0 09:33:22 INFO - PROCESS | 1941 | -------- Summary: end -------- 09:33:22 INFO - PROCESS | 1941 | 09:33:23 INFO - TEST-INFO | 1941: exit 0 09:33:23 INFO - TEST-OK | kraken | took 60563ms 09:33:23 INFO - SUITE-END | took 1174s 09:33:23 INFO - Completed test suite (00:19:34) 09:33:23 INFO - javascript benchmark 09:33:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7350.993827778774, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2979.6424429068516, 3599.388104022316, 3599.8380072896716, 3607.4408466687673, 3626.474161246619, 6016.789412370568, 6053.939460605393, 6074.392560743934, 6082.5742198046155, 6101.664408457533, 6014.8797024059495, 6078.604890682102, 6097.207363042809, 6100.23747031621, 6131.233595800525, 5410.404855465896, 5438.53772429344, 5466.207399927008, 5475.479829416199, 5558.388577256498, 5499.120140777476, 5529.281193444866, 5552.694601796899, 5554.363608240749, 5567.415421380758, 5336.0, 5403.918941215885, 5495.148252020943, 5503.752331145088, 5506.614536982413, 5434.266374039514, 5454.263674403965, 5465.098258787295, 5469.343678758534, 5492.313460817358, 5276.551493123051, 5408.4050754416985, 5409.459054094598, 5437.564994800407, 5481.835544933659, 4987.301777751118, 6072.36240194782, 6095.9695201524155, 6101.368489499342, 6123.540734444934, 6149.2620885494025, 6210.50315974721, 6240.251169859601, 6256.0, 6298.937010629927, 6400.519961002943, 6402.359764023607, 6419.35806419359, 6427.6786160692245, 6440.774572889926, 6095.085737139465, 6121.296050954153, 6132.141500189977, 6154.692265386758, 6180.0, 6351.079093531412, 6373.936260637427, 6386.07401926718, 6387.776427825004, 6588.637624930579, 6197.504199664016, 6232.781852635227, 6248.4064013918605, 6277.340879207709, 6311.400416960473, 6411.743530258831, 6433.485321174295, 6433.935660643334, 6435.678216089177, 6443.355664433519, 6198.566100373021, 6211.440970312694, 6303.117563541108, 6319.462845658079, 6338.492920566344, 5607.943920560824, 6142.232220972379, 6270.655113968775, 6319.020551814476, 6319.46284565817, 5174.353205849269, 5182.6372153949615, 5183.844484665466, 5190.24741412493, 5193.195054766517, 4505.189065968158, 4537.954620453819, 4551.06703125865, 4565.360849481075, 4620.53794620542, 4950.950490495121, 6153.600015998945, 6237.563370564017, 6263.467605253604, 6283.0, 6411.877921363836, 6441.838954026187, 6464.385883341075, 6477.805665830033, 6493.577917435352, 6098.847528811815, 6113.567524283718, 6137.4689182037, 6137.60105593135, 6196.225471816023, 5645.237892884408, 5645.350784659817, 5645.5483561314995, 5645.830625081254, 5671.773129074873, 6512.47900167976, 6521.543491955613, 6531.248906375828, 6577.144971153815, 6645.468362530889, 6234.220722409699, 6300.59046161998, 6300.716467759054, 6340.587861788969, 6410.45511131559, 6632.436242919406, 6637.867242655217, 6654.83362915931, 6658.5671931324305, 6660.900086498609, 4672.462666793226, 6337.081123237012, 6349.619022858643, 6358.554901156875, 6372.713227904663, 6032.215811944419, 6275.278342990432, 6314.526410519137, 6416.7754128605275, 6420.0, 5143.588512919032, 6153.661548614869, 6214.161088253028, 6265.906011409741, 6281.748730050746, 6239.844003899939, 6240.126382306572, 6250.031245157008, 6266.717997690206, 6269.717862696099, 6048.909266361096, 6122.581127188433, 6197.5351848610635, 6238.064290356483, 6265.248170219421, 5150.48495150482, 6198.442140207403, 6216.036514340284, 6249.406306400884, 6275.933091374569, 6257.561970662192, 6261.749530018748, 6274.529410294337, 6286.465650419583, 6301.8109456716375], "value": 5890.086258553986, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.57713654825125, 501.87202263422824, 512.1881817319548, 539.3958766181876, 553.1094937151188, 38159.80920095403, 40837.38743918844, 40930.79534602327, 40956.976075598126, 40959.795201024026, 41207.17585648293, 41415.0, 41668.16663666725, 41694.99999999993, 41876.58123418765, 9614.0385961404, 9734.853977190347, 9843.655472058479, 9845.458499782544, 9845.803083938317, 9659.082387173243, 9688.370255933378, 9698.224142068617, 9702.07830256128, 9719.61121555137, 9699.0, 9722.222222222204, 9826.263030272723, 9856.211503079772, 9859.556319965583, 9673.2261419087, 9782.461964591945, 9791.706248812547, 9823.91936886942, 9834.557444914995, 9718.902810971942, 9725.124738773473, 9739.172170365457, 9765.316427850054, 9856.605735770561, 39335.8033209835, 39476.80261598674, 39478.21043579112, 39518.40722389166, 39799.0, 3352.346292472969, 3532.993096967352, 3599.748017638766, 3617.8733744318824, 3684.5946945835935, 29517.0, 29695.554566681516, 29737.70262297371, 30397.061743101007, 30676.0, 3213.3412650406844, 3353.3125709229794, 3378.054144839449, 3409.4203985322556, 3486.9128271793156, 509448.0, 509718.0, 518905.0, 519196.0, 519700.0, 58268.0, 58388.708056460295, 58416.4158358411, 58516.0, 58523.4147658518, 60855.0, 61066.77866442735, 61137.0, 61174.0, 61335.69332153311, 15944.441944531885, 16112.113833739257, 16197.271122799502, 16203.83796162047, 16214.432494862858, 12178.81731774024, 12209.816852747215, 12210.633680989584, 12228.0, 12376.443060062318, 14819.259037048105, 15338.616534586501, 15345.079295242322, 15465.458708945132, 15655.060696358254, 3150.4329220740487, 3423.17843717506, 6001.339852616209, 6478.1578394808375, 6629.939209726421, 752.8042708895726, 826.6941231744294, 852.9360297977677, 990.5839547390113, 1063.1388575254068, 25572.545699058475, 27877.86061069682, 29582.964596239028, 29641.703582964325, 30001.849990750343, 1063.1813503602184, 1081.5565618096543, 1084.2572837606265, 1087.7117563845586, 1161.4192903548226, 13796.310184490736, 16441.91779041121, 16454.83545164557, 16905.15474226284, 16940.83059169417, 653160.0, 695371.5231423911, 711811.0, 715995.0, 718136.0, 57806.0, 58303.70848145817, 58307.12539311914, 58392.0, 58483.70758146182, 1393.108410617206, 1396.2460271453504, 1430.213382639544, 1432.3840748478046, 1435.103060587133, 30473.695263047084, 30891.382172356876, 31087.689123109383, 31894.0, 32261.677383225866, 48780.5121948776, 49104.75447622739, 49311.75344123256, 49393.259101112795, 49412.01175976532, 612833.0, 617711.0, 618003.0, 618027.0, 618032.0, 25128.37179070538, 26705.732942670325, 26706.332341691614, 26706.732932671202, 26771.7322826777, 45867.54132458766, 45905.54094459146, 46081.76959115183, 46196.076078478916, 46252.306215406126, 38295.0, 38509.037274068374, 40077.0, 40350.9912252196, 41093.79453102835, 32530.674693252764, 38546.80726596349, 38908.221835563694, 39206.215875682894, 39564.60435395609, 37372.0, 38336.2332753349, 38362.42456363101, 38954.2209155821, 38999.02502437462, 28316.136157849734, 32140.357192856478, 34882.0, 34999.65000349964, 35130.29739405249, 21583.892080539496, 26137.869310653325, 26200.606990895532, 26266.343341416617, 26501.867490662422], "value": 19777.547447698642, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.97165212609053, 383.7448097015485, 387.46336324191003, 404.29248814574487, 423.87495688771827, 16358.591035224095, 20881.582368352643, 20939.371818845422, 21750.782492175094, 21782.346529604118, 21258.36224913255, 21318.0, 21570.89214553929, 21851.453713657127, 21892.014859331328, 5973.820785376435, 6203.596766210205, 6279.403456671632, 6289.905651415233, 6332.905006424908, 4963.627727920402, 5963.135345374918, 6098.69506524672, 6204.224471941007, 6204.906926396107, 6066.332703402622, 6130.724117414705, 6158.199434073565, 6170.259568851721, 6170.7531698732, 5866.882662346772, 6077.3314935357075, 6121.081837724377, 6126.081087836816, 6147.262328520584, 5263.973680131575, 5267.947320526784, 5288.338957630296, 5343.064963631349, 5380.569554435636, 26021.73978260231, 26568.601470977952, 26833.329166770793, 27100.45799084027, 27165.1850444487, 2312.7802858728396, 2465.0879174705474, 2596.701379341381, 2830.249983754307, 2922.5616157576533, 12571.622851314476, 13165.473381064747, 15170.393184272616, 16214.918925405416, 16403.0, 1568.9267272565187, 1587.3253867106434, 1869.2242719271485, 1945.9708104378446, 1974.5655955689726, 83937.07059643569, 92029.0, 118274.99999999914, 119126.0, 119421.0, 11375.0, 11512.76974460506, 11651.00966417847, 11820.0, 11887.0, 11578.305301681927, 12830.101892867597, 14733.336999835034, 14754.409823606935, 14766.852331476763, 8503.70237041713, 8831.823363532694, 8835.469871807712, 8913.0, 8950.283977281802, 7976.641051152712, 8772.640240762692, 8805.42764720291, 8812.559372031501, 8918.375713700108, 6443.162388889415, 6590.340965903467, 7042.366187043191, 7076.858462830714, 7084.645767711697, 1932.4202739178472, 1958.3243780895568, 1958.9118489667999, 1959.206521358852, 1966.8426525877896, 910.7677542226822, 926.6247169896203, 963.797602503468, 972.8054389122062, 1104.315324498816, 22344.99447524865, 23001.0, 23014.0, 23156.65265021026, 23198.768012319997, 965.5365424596233, 1005.2259759984771, 1007.3401921741271, 1013.1793247469574, 1062.628080171949, 11468.54125834974, 11942.402879855972, 12183.512659493697, 12203.694907627203, 12218.572349967708, 576784.0, 588218.0, 609397.0, 615406.0, 621958.0, 68000.31999680039, 70970.29029709636, 72649.0, 79136.0, 120253.19620205704, 1005.492226425665, 1042.4943902207424, 1043.4261156363818, 1060.9257351985286, 1061.984070238947, 19166.329178479245, 19658.50853728668, 19801.702974455682, 19840.70238946446, 19847.90836504006, 38667.2266554673, 48473.5152648469, 48840.75579622221, 51123.48876511187, 51318.0, 62371.12886613409, 72303.0, 73128.26871731428, 75116.0, 76516.61741691256, 6672.899906501503, 7924.296276300623, 8164.10194878551, 8195.262426381416, 8285.54429506383, 11883.0, 12414.751704965669, 13204.339783011003, 13281.468741250243, 13295.335233238493, 9240.491772952284, 10932.0707739843, 11108.777824443305, 11244.775104497701, 11471.770564588829, 10954.287971281841, 10992.230543861853, 11032.724181895517, 11355.432228388383, 11462.484188211718, 11198.38408887519, 11397.886021139682, 11562.132840036862, 11724.472398742248, 11822.822657660314, 8095.473794203594, 8166.6733330668485, 8212.753617391489, 8323.542205178532, 8374.366998435247, 11366.829497557708, 11382.772344553228, 11409.315441073562, 11657.358845263592, 11741.530338786353], "value": 9814.163614789837, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1523.1013701915872, 1531.7166324230016, 1740.7998080220777, 1840.9171587278572, 1889.9716504252438, 8268.007839059313, 8478.618462169201, 8757.0, 8760.0, 8762.912370876289, 8629.093945135748, 8760.255378292837, 8762.123787621234, 8762.167594078568, 8775.0, 2409.3133456964724, 2435.2937648082034, 2452.5217582571404, 2454.7667971542674, 2456.6437866509345, 2415.335782659765, 2442.1086303499183, 2456.5701002324613, 2471.02394554151, 2475.9133430329944, 2220.311703371952, 2423.466837295792, 2431.5866302728577, 2440.0971640493044, 2447.889844956981, 2392.6411038344213, 2395.0419832067137, 2398.0168131066266, 2415.891284892184, 2430.2952143878256, 2387.85672859629, 2423.503181847717, 2460.1143588308196, 2465.3466831289725, 2469.2221950085845, 8601.31189504838, 8617.310615150773, 8632.13678632138, 8722.215000649972, 8778.780530486789, 5251.36983561975, 5386.111291636876, 5410.945890541084, 5420.268263784378, 5422.53908417785, 3564.269324788438, 3600.3879340512176, 3615.0, 3634.018814919983, 3655.342038433081, 1069.6737495064003, 1077.1921059205595, 1128.2948157401624, 1137.2380505061628, 1142.6457798082622, 10199.827019892733, 10776.999999999922, 11234.606788762354, 11276.492557834836, 11342.149338799623, 4319.460067491564, 4531.750753708512, 4586.88532786683, 4597.862064138081, 4598.8620341389815, 8800.075992020873, 8864.0, 8907.51008694515, 8931.419457735356, 8936.597853096497, 5212.061828870839, 5235.188545775411, 5236.9738151309, 5239.502247286502, 5248.317718696621, 4385.771983844528, 4394.560543945579, 4835.202191638411, 4892.828750993769, 4893.119238537026, 3800.809959502014, 3817.408301713239, 3860.652541271299, 3909.4722212501515, 3928.6071392860486, 1005.8181636576993, 1007.4660429972126, 1172.4874911364245, 1177.6349331707197, 1186.602488166474, 932.6782260120248, 938.5823308627731, 941.3316545252948, 947.251671179774, 959.3716115944068, 5320.973395133, 5777.191193232867, 5868.530517558585, 5895.587308888423, 6092.964196086676, 447.59269065150556, 458.4750460722521, 460.78573463339706, 462.10351917280366, 463.63604570412065, 3400.9149771255916, 3473.496343030297, 4033.495813023372, 4101.5488296287385, 4124.649404800625, 10201.489925503694, 10442.477876106164, 10579.153667706565, 10593.152547796159, 10610.628627997983, 10017.899821001696, 10188.235882308856, 10487.528061237263, 10563.207759418074, 10588.205884558689, 8077.434579579491, 8111.432199745961, 8217.671293148207, 8282.834343313221, 8285.295749861209, 5789.710514474344, 5987.550933679904, 5988.071848863433, 5988.520918326611, 6041.395860414099, 9616.278779091455, 9626.374285671409, 9646.649469074475, 9660.806783864424, 9807.06832850878, 9641.89118251382, 9685.612575496902, 9822.901770982198, 9873.111419972238, 9908.405495670282, 6433.839154021187, 6443.904536228946, 6612.70242839083, 6953.33943275388, 7041.788746337618, 7558.58427786477, 8193.918060819315, 8318.45930014547, 8361.331093512626, 8398.622062007102, 6129.754809807558, 7042.964785176041, 7099.467539934629, 7189.604571748395, 7240.203577606354, 6051.606645568024, 7049.929500705132, 7079.327463890922, 7101.254368291462, 7145.071140751669, 6832.0435139081565, 6873.965630171817, 6915.688794004182, 6972.546784458994, 7000.7549735759, 5011.674241174458, 5032.572231360376, 5077.416097148875, 5082.136036873814, 5085.872853178552, 4375.081232941118, 5017.62367822422, 5058.31712718793, 5117.43708192106, 5143.022825663112], "value": 4441.56596027613, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.60435983083937, 303.7706531568667, 317.59665225164036, 321.5177234148777, 324.3205484509952, 4431.468223813143, 5550.973069982057, 5639.887202255953, 5660.745266463009, 5719.19931209631, 5536.81295888124, 5598.412166722496, 5604.915926261099, 5619.157126431038, 5658.773649054043, 3536.9115772105774, 3855.5951625079383, 3962.1679447315955, 4231.344141658048, 4278.6363159131515, 3985.70107241958, 4232.49210094789, 4285.550017248191, 4285.657147428214, 4286.657067434613, 3471.8716417164396, 4155.854545090922, 4195.349720793282, 4219.071804203086, 4245.554216807237, 3861.8841434757, 4213.683973701954, 4218.557051509578, 4237.618614324695, 4249.6387807036435, 3642.8907132786057, 3650.470681751158, 3658.8536458541626, 3664.450332450154, 3669.486271921933, 4902.166631672589, 5106.157484015133, 5393.6763794172475, 5396.379416367129, 5461.508464238198, 4257.233697934371, 4569.474510431276, 4785.401824771903, 4792.9281060784115, 4796.450626536338, 5606.971965140189, 5829.650220986712, 5863.853403664943, 5904.409559044104, 5913.586048976574, 3930.1157239621143, 3950.525936887592, 3970.305196590585, 4005.7396269242404, 4019.718619696622, 5489.505944464978, 6259.561830671855, 6259.561830671855, 6292.828974004007, 6345.33373995733, 4020.371749441481, 4032.689375952827, 4040.898977525527, 4054.391591483582, 4059.106996460774, 3460.0830779843363, 3800.543934727795, 3988.3219852625125, 4040.2323558523785, 4076.5515793262716, 4804.447488538863, 4822.7829747660735, 4845.781939812717, 4846.5153484651955, 4851.975740121276, 5491.560675145979, 5924.348321684612, 5956.493698035715, 5998.910016349758, 6001.459868611846, 1189.423129782055, 1193.540486912545, 1264.5068423314915, 1326.973460530784, 1346.7845144776788, 692.394155114275, 830.8296799156159, 892.8973168085753, 900.230303090861, 903.4353529044347, 4678.175511550498, 4760.643216683228, 5102.795888164432, 5321.3348331458565, 5337.359516858002, 4782.9760851196215, 5101.20931255656, 5564.360098588714, 5732.684702341411, 5756.597038207286, 4904.583110435653, 5065.480355893291, 5336.626436149432, 5339.546138578186, 5357.812476563302, 2695.5848179705813, 2981.092101055326, 3033.450862490891, 3041.680623534541, 3071.754259659222, 5191.532762051358, 5643.858903527363, 5686.867424715731, 5711.428857114255, 6013.789517366871, 5304.143549757554, 5465.726713664301, 5673.659580425187, 5828.59199856006, 6040.697965101727, 4866.9513304867205, 4886.289798570472, 5076.847694569169, 5879.588898664375, 5958.463738263577, 5209.635325527177, 5313.654612450178, 5797.797077102281, 5832.679202643895, 5860.461863538915, 5063.8480845574695, 5593.888122237614, 5635.661200601704, 5669.93047537654, 5716.628419152742, 5093.8726531837, 5355.955588660174, 5415.431379705232, 5759.272218334399, 5921.99326114553, 3434.5614613623607, 3606.1969015492255, 3762.0, 3858.9228215436096, 3886.9805650972694, 5374.785008599612, 6021.946159422171, 6075.0, 6099.2985806632805, 6125.070602760116, 4998.575121114746, 5431.832156086461, 5879.353271140086, 5941.37615550361, 5942.64344139353, 5127.66670166438, 5350.973245133905, 5762.164486149569, 5792.710364481674, 5850.36189866838, 5691.345495268097, 5730.369659337386, 5732.426757324234, 5749.626274292157, 5753.568482363923, 5455.372632147195, 5677.489025987681, 5696.772129114955, 5821.446962538552, 5927.4665280125, 4715.0, 5557.777688892399, 5717.714114294352, 5758.654480731169, 5786.652800831964], "value": 4186.15312743908, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.4931506849315, 739.4084732214228, 774.5352788327001, 793.2939683681525, 836.3351135847001, 14772.03981741186, 15304.234788260597, 15692.21538923055, 15699.587037166653, 15771.053736775788, 13929.30353482327, 14079.296035198251, 14216.92891535541, 14217.431302747904, 14259.857401426008, 872.8516152254116, 885.5041176940902, 886.6453418632534, 890.5992303463435, 891.2246345679268, 820.2125959079291, 848.0628905059913, 858.9226969572737, 864.9879640820255, 866.1079088538784, 758.5221310574331, 913.5204017890594, 914.6112902016616, 918.4168053286178, 920.4293338130368, 891.38494438837, 893.1782759860944, 899.685110211424, 900.3292547052455, 910.0035461170041, 661.8246164766341, 686.1114856261154, 687.8108520156946, 687.9002544631051, 688.9414399776025, 12648.810267845989, 12660.240385576895, 12769.808452873121, 12814.103012789108, 13036.41336139876, 11439.19925605208, 11760.647180584596, 11952.04383649306, 12015.93992030043, 12142.0, 10075.949620251926, 10186.160173246219, 10347.379157250514, 10675.466226688635, 10782.0, 3343.498475228711, 3617.728670349708, 3678.2275722098393, 3711.406175012012, 3814.5231846019246, 57638.71180644112, 61012.389876101115, 77718.83421748679, 87624.0, 88178.67731984025, 10603.94698026505, 10732.195085368628, 10740.677779666623, 10982.505787239594, 11234.15743819217, 16953.915230424016, 20894.0, 20908.68636970447, 21067.789322106582, 21339.14643414246, 7769.106552746393, 7788.454808163374, 7883.5664038478435, 8167.877481837777, 8188.50868947865, 4451.421315228999, 4479.126570318756, 4532.116237333754, 4547.636189104864, 4607.631389488833, 3522.0490467573613, 3597.8560857565403, 3636.1091532574687, 3650.1969566695284, 3656.0677027358383, 665.0921492163259, 669.0833558025439, 680.6834821807884, 685.3454950522239, 686.807693845724, 634.1819053421035, 653.4119292636665, 662.9403353698191, 675.2774531251514, 687.6114995027793, 8993.595288212045, 9240.122188392092, 9498.715038548855, 9529.952350238344, 9611.183049440875, 588.8233529941086, 667.4960404894274, 676.9018492318586, 707.1478867964091, 711.5410560188649, 4380.496242932104, 4571.0, 4598.97700511502, 4609.262517997105, 4618.49196588375, 76579.61710191525, 77454.83817742625, 79763.60118199488, 121414.39292803656, 127528.0, 95807.0, 108668.0, 112431.0, 112757.0, 114281.0, 77813.61093194611, 95412.0458795417, 95771.0, 97419.51290243503, 100480.0, 8227.382946278933, 8785.385023048326, 8883.800686907185, 9003.774905627411, 9038.412503187272, 60360.69819651049, 61626.4593385151, 62981.37018629755, 64236.71526569536, 64436.677816610616, 70872.0, 72031.27968720246, 72547.27452725616, 85830.0, 89621.0, 11051.115910727283, 12753.872461275269, 12871.742565148832, 13098.934505327412, 13269.668258293234, 11699.88300116988, 14312.785308220593, 14618.0, 15030.0, 15193.392264309305, 10130.746731331776, 11058.447077645924, 11638.0, 11948.820767688665, 14230.644233894236, 12664.366781660696, 13333.266670333225, 13540.32298385057, 13603.523876664269, 13613.183209007491, 12559.623211303675, 12896.935515322364, 12987.415566299727, 13102.606921792361, 13274.203547786778, 10719.732006699584, 11187.944060279646, 11339.829902551302, 11698.76602467963, 11744.237918215613, 11170.27393219438, 11441.313521188755, 11711.707207319885, 11732.12009099304, 11747.706307342385], "value": 7422.193057018922, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1543.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 133.0, 171.0, 158.0], "value": 126.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 99.0, 170.0, 142.0, 142.0, 127.0, 111.0, 138.0, 116.0], "value": 131.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 198.0, 196.0, 229.0, 256.0, 197.0, 195.0, 195.0, 199.0, 201.0], "value": 206.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 65.0, 85.0, 111.0, 86.0, 67.0, 58.0, 58.0, 77.0, 66.0], "value": 75.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 90.0, 60.0, 85.0, 102.0, 71.0, 91.0, 64.0, 90.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 86.0, 88.0, 106.0, 88.0, 87.0, 88.0, 86.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 157.0, 154.0, 95.0, 152.0, 112.0, 99.0, 97.0, 129.0, 170.0], "value": 126.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 86.0, 87.0, 95.0, 88.0, 90.0, 92.0, 102.0, 119.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 57.0, 58.0, 70.0, 64.0, 56.0, 60.0, 59.0, 59.0], "value": 59.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 70.0, 80.0, 72.0, 71.0, 68.0, 70.0, 70.0, 72.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 93.0, 86.0, 82.0, 84.0, 86.0, 81.0, 84.0, 83.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 132.0, 170.0, 151.0, 157.0, 161.0, 162.0, 153.0, 184.0, 149.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 147.0, 222.0, 163.0, 165.0, 163.0, 161.0, 166.0, 163.0, 166.0], "value": 169.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 66.0, 67.0, 72.0, 73.0, 88.0, 89.0, 85.0, 88.0], "value": 76.1, "unit": "score"}]}]} 09:33:23 INFO - Return code: 0 09:33:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:33:23 ERROR - # TBPL SUCCESS # 09:33:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:33:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:33:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:33:23 INFO - 2017-01-30 09:33:23.996 screenresolution[1946:22940] starting screenresolution argv=screenresolution get 09:33:24 INFO - 2017-01-30 09:33:24.000 screenresolution[1946:22940] Display 0: 1600x1200x32@60 09:33:24 INFO - 2017-01-30 09:33:24.007 screenresolution[1947:22943] starting screenresolution argv=screenresolution list 09:33:24 INFO - Available Modes on Display 0 09:33:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:33:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:33:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:33:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:33:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:33:24 INFO - Intel Iris: 09:33:24 INFO - Chipset Model: Intel Iris 09:33:24 INFO - Type: GPU 09:33:24 INFO - Bus: Built-In 09:33:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:33:24 INFO - Vendor: Intel (0x8086) 09:33:24 INFO - Device ID: 0x0a2e 09:33:24 INFO - Revision ID: 0x0009 09:33:24 INFO - Displays: 09:33:24 INFO - Display: 09:33:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:33:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:33:24 INFO - Display Serial Number: 1600x1200 60 09:33:24 INFO - Main Display: Yes 09:33:24 INFO - Mirror: Off 09:33:24 INFO - Online: Yes 09:33:24 INFO - Rotation: Supported 09:33:24 INFO - Return code: 0 09:33:24 INFO - Running post-action listener: _resource_record_post_action 09:33:24 INFO - [mozharness: 2017-01-30 17:33:24.253123Z] Finished run-tests step (success) 09:33:24 INFO - Running post-run listener: _resource_record_post_run 09:33:24 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 1540096; Write bytes: 503771136; Read time: 17; Write time: 1622 09:33:24 INFO - TinderboxPrint: CPU usage
54.2% 09:33:24 INFO - TinderboxPrint: I/O read bytes / time
1,540,096 / 17 09:33:24 INFO - TinderboxPrint: I/O write bytes / time
503,771,136 / 1,622 09:33:24 INFO - TinderboxPrint: CPU idle
2,189.6 (45.8%) 09:33:24 INFO - TinderboxPrint: CPU system
968.1 (20.2%) 09:33:24 INFO - TinderboxPrint: CPU user
1,628.0 (34.0%) 09:33:24 INFO - TinderboxPrint: Swap in / out
193,249,280 / 0 09:33:24 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 188344832; Write bytes: 188853760; Read time: 16736; Write time: 408 09:33:24 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 1486848; Write bytes: 307732992; Read time: 15; Write time: 1183 09:33:24 INFO - Running post-run listener: _upload_blobber_files 09:33:24 INFO - Blob upload gear active. 09:33:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:33:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:33:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:33:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:33:25 INFO - (blobuploader) - INFO - Open directory for files ... 09:33:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:33:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:33:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:25 INFO - (blobuploader) - INFO - Done attempting. 09:33:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:33:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:33:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:26 INFO - (blobuploader) - INFO - Done attempting. 09:33:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:33:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:33:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:29 INFO - (blobuploader) - INFO - Done attempting. 09:33:29 INFO - (blobuploader) - INFO - Iteration through files over. 09:33:29 INFO - Return code: 0 09:33:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:33:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:33:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4ca19dd2e712e4cbbdcdac805513eb46b031cb83581ba26ae5d04540e5cb9945e0af0a198d7e511997c80bfc784a1f8ed065b096fdbd743a5fa9b8b471720742", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d8952ccbcf3632962c16545400a29b6535a851248446435009b787a58c5ca10ebc41ee01b679a78f9e13198cb217e5bbad5c3729947b30b0c05998a7cb2e7e75", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c140ba2e376f9966411e7f3e0e5b7b9b7dd315f07bd01a0735e296f803af119428b010d877eb26958898be4cf02b48e95cf79e28cfd28c83d045c3189bfb4053"} 09:33:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:33:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:33:29 INFO - Contents: 09:33:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4ca19dd2e712e4cbbdcdac805513eb46b031cb83581ba26ae5d04540e5cb9945e0af0a198d7e511997c80bfc784a1f8ed065b096fdbd743a5fa9b8b471720742", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d8952ccbcf3632962c16545400a29b6535a851248446435009b787a58c5ca10ebc41ee01b679a78f9e13198cb217e5bbad5c3729947b30b0c05998a7cb2e7e75", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c140ba2e376f9966411e7f3e0e5b7b9b7dd315f07bd01a0735e296f803af119428b010d877eb26958898be4cf02b48e95cf79e28cfd28c83d045c3189bfb4053"} 09:33:29 INFO - Running post-run listener: copy_logs_to_upload_dir 09:33:29 INFO - Copying logs to upload dir... 09:33:29 INFO - mkdir: /builds/slave/test/build/upload/logs 09:33:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.485655 ========= master_lag: 1.79 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-01-30 09:33:30.918778) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-01-30 09:33:30.927681) ========= 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.B9vs46Y0qt/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.NZdV1DnBan/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4ca19dd2e712e4cbbdcdac805513eb46b031cb83581ba26ae5d04540e5cb9945e0af0a198d7e511997c80bfc784a1f8ed065b096fdbd743a5fa9b8b471720742", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d8952ccbcf3632962c16545400a29b6535a851248446435009b787a58c5ca10ebc41ee01b679a78f9e13198cb217e5bbad5c3729947b30b0c05998a7cb2e7e75", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c140ba2e376f9966411e7f3e0e5b7b9b7dd315f07bd01a0735e296f803af119428b010d877eb26958898be4cf02b48e95cf79e28cfd28c83d045c3189bfb4053"} build_url:https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011124 build_url: 'https://queue.taskcluster.net/v1/task/PSAwuQfWQbK53_4QgDn8jA/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4ca19dd2e712e4cbbdcdac805513eb46b031cb83581ba26ae5d04540e5cb9945e0af0a198d7e511997c80bfc784a1f8ed065b096fdbd743a5fa9b8b471720742", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d8952ccbcf3632962c16545400a29b6535a851248446435009b787a58c5ca10ebc41ee01b679a78f9e13198cb217e5bbad5c3729947b30b0c05998a7cb2e7e75", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c140ba2e376f9966411e7f3e0e5b7b9b7dd315f07bd01a0735e296f803af119428b010d877eb26958898be4cf02b48e95cf79e28cfd28c83d045c3189bfb4053"}' ========= master_lag: 1.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-01-30 09:33:31.978330) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 09:33:31.978654) ========= 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.B9vs46Y0qt/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.NZdV1DnBan/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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.005377 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-30 09:33:32.009641) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-30 09:33:32.009973) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-30 09:33:32.010498) ========= ========= Total master_lag: 2.93 =========