builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0025 starttime: 1476282695.86 results: success (0) buildid: 20161012051247 builduid: 8eefdbdd4b2043f0ad22a091a8ccd2a3 revision: 4756eb1a2f82a6fb2e7ab5e3708d14aa76816076 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 07:31:35.863345) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 07:31:35.863773) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 07:31:35.864042) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 07:31:35.888105) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 07:31:35.888601) ========= 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.4jBCMsXpTa/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.oQlTHkZACH/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-12 07:31:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 39.1M=0s 2016-10-12 07:31:36 (39.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131342 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 07:31:36.042928) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 07:31:36.043370) ========= 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.4jBCMsXpTa/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.oQlTHkZACH/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.021107 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 07:31:36.092087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 07:31:36.092464) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 4756eb1a2f82a6fb2e7ab5e3708d14aa76816076 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 4756eb1a2f82a6fb2e7ab5e3708d14aa76816076 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4jBCMsXpTa/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.oQlTHkZACH/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-12 07:31:36,165 truncating revision to first 12 chars 2016-10-12 07:31:36,166 Setting DEBUG logging. 2016-10-12 07:31:36,166 attempt 1/10 2016-10-12 07:31:36,166 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/4756eb1a2f82?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 07:31:37,543 unpacking tar archive at: larch-4756eb1a2f82/testing/mozharness/ program finished with exit code 0 elapsedTime=1.633817 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 07:31:37.748529) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 07:31:37.748960) ========= script_repo_revision: 4756eb1a2f82a6fb2e7ab5e3708d14aa76816076 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 07:31:37.749389) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 07:31:37.749653) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 07:31:37.769241) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-12 07:31:37.769658) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4jBCMsXpTa/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.oQlTHkZACH/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 07:31:37 INFO - MultiFileLogger online at 20161012 07:31:37 in /builds/slave/test 07:31:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 07:31:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:31:37 INFO - {'append_to_log': False, 07:31:37 INFO - 'base_work_dir': '/builds/slave/test', 07:31:37 INFO - 'blob_upload_branch': 'Larch', 07:31:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:31:37 INFO - 'branch': 'Larch', 07:31:37 INFO - 'buildbot_json_path': 'buildprops.json', 07:31:37 INFO - 'config_files': ('talos/mac_config.py',), 07:31:37 INFO - 'default_actions': ('clobber', 07:31:37 INFO - 'read-buildbot-config', 07:31:37 INFO - 'download-and-extract', 07:31:37 INFO - 'populate-webroot', 07:31:37 INFO - 'create-virtualenv', 07:31:37 INFO - 'install', 07:31:37 INFO - 'run-tests'), 07:31:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:31:37 INFO - 'download_minidump_stackwalk': True, 07:31:37 INFO - 'download_symbols': 'ondemand', 07:31:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:31:37 INFO - 'tooltool.py': '/tools/tooltool.py', 07:31:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:31:37 INFO - '/tools/misc-python/virtualenv.py')}, 07:31:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:31:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:31:37 INFO - 'installer_path': 'installer.exe', 07:31:37 INFO - 'log_level': 'info', 07:31:37 INFO - 'log_name': 'talos', 07:31:37 INFO - 'log_to_console': True, 07:31:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:31:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:31:37 INFO - 'opt_config_files': (), 07:31:37 INFO - 'pip_index': False, 07:31:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:31:37 INFO - 'cmd': ('bash', 07:31:37 INFO - '-c', 07:31:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:31:37 INFO - 'enabled': True, 07:31:37 INFO - 'halt_on_failure': False, 07:31:37 INFO - 'name': 'check_screen_resolution'},), 07:31:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:31:37 INFO - 'cmd': ('bash', 07:31:37 INFO - '-c', 07:31:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:31:37 INFO - 'enabled': True, 07:31:37 INFO - 'halt_on_failure': False, 07:31:37 INFO - 'name': 'check_screen_resolution'},), 07:31:37 INFO - 'run_cmd_checks_enabled': True, 07:31:37 INFO - 'sps_profile': False, 07:31:37 INFO - 'sps_profile_interval': 0, 07:31:37 INFO - 'suite': 'dromaeojs-e10s', 07:31:37 INFO - 'system_bits': '32', 07:31:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:31:37 INFO - 'title': 't-yosemite-r7-0025', 07:31:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:31:37 INFO - 'use_talos_json': True, 07:31:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:31:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:31:37 INFO - 'work_dir': 'build'} 07:31:37 INFO - [mozharness: 2016-10-12 14:31:37.966287Z] Running clobber step. 07:31:37 INFO - Running pre-action listener: _resource_record_pre_action 07:31:37 INFO - Running main action method: clobber 07:31:37 INFO - rmtree: /builds/slave/test/build 07:31:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:31:38 INFO - Running post-action listener: _resource_record_post_action 07:31:38 INFO - [mozharness: 2016-10-12 14:31:38.882357Z] Finished clobber step (success) 07:31:38 INFO - [mozharness: 2016-10-12 14:31:38.882467Z] Running read-buildbot-config step. 07:31:38 INFO - Running pre-action listener: _resource_record_pre_action 07:31:38 INFO - Running main action method: read_buildbot_config 07:31:38 INFO - Using buildbot properties: 07:31:38 INFO - { 07:31:38 INFO - "project": "", 07:31:38 INFO - "product": "firefox", 07:31:38 INFO - "script_repo_revision": "production", 07:31:38 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 07:31:38 INFO - "repository": "", 07:31:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 07:31:38 INFO - "buildid": "20161012051247", 07:31:38 INFO - "pgo_build": "False", 07:31:38 INFO - "basedir": "/builds/slave/test", 07:31:38 INFO - "buildnumber": 0, 07:31:38 INFO - "slavename": "t-yosemite-r7-0025", 07:31:38 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:31:38 INFO - "platform": "yosemite_r7", 07:31:38 INFO - "scheduler": "tests-larch-macosx64-talos", 07:31:38 INFO - "branch": "larch", 07:31:38 INFO - "repo_path": "projects/larch", 07:31:38 INFO - "revision": "4756eb1a2f82a6fb2e7ab5e3708d14aa76816076", 07:31:38 INFO - "stage_platform": "macosx64", 07:31:38 INFO - "builduid": "8eefdbdd4b2043f0ad22a091a8ccd2a3", 07:31:38 INFO - "slavebuilddir": "test" 07:31:38 INFO - } 07:31:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 07:31:38 INFO - Running post-action listener: _resource_record_post_action 07:31:38 INFO - [mozharness: 2016-10-12 14:31:38.885920Z] Finished read-buildbot-config step (success) 07:31:38 INFO - [mozharness: 2016-10-12 14:31:38.886022Z] Running download-and-extract step. 07:31:38 INFO - Running pre-action listener: _resource_record_pre_action 07:31:38 INFO - Running main action method: download_and_extract 07:31:38 INFO - mkdir: /builds/slave/test/build/tests 07:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:31:38 INFO - trying https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 07:31:38 INFO - Downloading https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:31:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 07:31:40 INFO - Downloaded 1199 bytes. 07:31:40 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:31:40 INFO - Using the following test package requirements: 07:31:40 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:31:40 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:31:40 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 07:31:40 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:31:40 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 07:31:40 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:31:40 INFO - u'jsshell-mac.zip'], 07:31:40 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:31:40 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 07:31:40 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:31:40 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:31:40 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 07:31:40 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:31:40 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 07:31:40 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:31:40 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 07:31:40 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:31:40 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 07:31:40 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:31:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:31:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:31:40 INFO - Fetch https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:31:40 INFO - Http code: 200 07:31:40 INFO - via: 1.1 f0ef92e52918ab5129ebd66f2f633cbb.cloudfront.net (CloudFront) 07:31:40 INFO - x-amz-cf-id: MQRkNFP6e5RIAJBeW2QJ2hkeXg7vEKVr3qvWwwsSTQjIQkYZbENGrA== 07:31:40 INFO - x-amz-version-id: ew.BVovNkNFCrWirFVzGxILJjuqnwZsD 07:31:41 INFO - Expected file size: 16508508 07:31:41 INFO - Obtained file size: 16508508 07:31:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:31:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:31:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:31:48 INFO - Fetch https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:31:48 INFO - Http code: 200 07:31:48 INFO - via: 1.1 bfdd5909914586f5bc4851846228c27f.cloudfront.net (CloudFront) 07:31:48 INFO - x-amz-cf-id: rdkvq7BLsnYX-E0DC6ENT6aTP82kMdGqxVTXLLunq9zRiCFL8R2Ekg== 07:31:48 INFO - x-amz-version-id: ew.BVovNkNFCrWirFVzGxILJjuqnwZsD 07:31:48 INFO - Expected file size: 16508508 07:31:48 INFO - Obtained file size: 16508508 07:31:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 07:31:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:31:55 INFO - Fetch https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 07:31:55 INFO - Http code: 200 07:31:55 INFO - via: 1.1 96255a5b0caf50bcafcd101c1ff13691.cloudfront.net (CloudFront) 07:31:55 INFO - x-amz-cf-id: QouBxw_UqT1w5eizQqKaBB6p8tG5vtuQumZ7KwEPIiGBWrMlS7OLtg== 07:31:55 INFO - x-amz-version-id: m5pAWezr_1W.v7_QeyxzhUaG7S_Kzs_V 07:31:56 INFO - Expected file size: 14409006 07:31:56 INFO - Obtained file size: 14409006 07:31:56 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')]} 07:31:56 INFO - trying https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:31:56 INFO - Downloading https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 07:31:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 07:31:59 INFO - Downloaded 111696945 bytes. 07:31:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:31:59 INFO - mkdir: /builds/slave/test/properties 07:31:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:31:59 INFO - Writing to file /builds/slave/test/properties/build_url 07:31:59 INFO - Contents: 07:31:59 INFO - build_url:https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:32:00 INFO - Running post-action listener: _resource_record_post_action 07:32:00 INFO - Running post-action listener: set_extra_try_arguments 07:32:00 INFO - [mozharness: 2016-10-12 14:32:00.080089Z] Finished download-and-extract step (success) 07:32:00 INFO - [mozharness: 2016-10-12 14:32:00.080211Z] Running populate-webroot step. 07:32:00 INFO - Running pre-action listener: _resource_record_pre_action 07:32:00 INFO - Running main action method: populate_webroot 07:32:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:32:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:32:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:32:00 INFO - '--tppagecycles', 07:32:00 INFO - '7'], 07:32:00 INFO - 'tests': ['tsvgm']}}, 07:32:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:32:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:32:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:32:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:32:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:32:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:32:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:00 INFO - 'talos_options': ['--disable-e10s'], 07:32:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:32:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:32:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:00 INFO - 'talos_options': ['--disable-e10s'], 07:32:00 INFO - 'tests': ['damp', 'tps']}, 07:32:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:00 INFO - 'tests': ['damp', 'tps']}, 07:32:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:32:00 INFO - 'tests': ['dromaeo_dom']}, 07:32:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:32:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:32:00 INFO - 'tests': ['basic_compositor_video']}, 07:32:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:32:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:32:00 INFO - 'tests': ['a11yr', 07:32:00 INFO - 'ts_paint', 07:32:00 INFO - 'tpaint', 07:32:00 INFO - 'sessionrestore', 07:32:00 INFO - 'sessionrestore_no_auto_restore', 07:32:00 INFO - 'tabpaint']}, 07:32:00 INFO - 'other-e10s': {'tests': ['a11yr', 07:32:00 INFO - 'ts_paint', 07:32:00 INFO - 'tpaint', 07:32:00 INFO - 'sessionrestore', 07:32:00 INFO - 'sessionrestore_no_auto_restore', 07:32:00 INFO - 'tabpaint']}, 07:32:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:32:00 INFO - 'ts_paint', 07:32:00 INFO - 'tpaint', 07:32:00 INFO - 'sessionrestore', 07:32:00 INFO - 'sessionrestore_no_auto_restore', 07:32:00 INFO - 'tabpaint']}, 07:32:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:32:00 INFO - 'ts_paint', 07:32:00 INFO - 'tpaint', 07:32:00 INFO - 'sessionrestore', 07:32:00 INFO - 'sessionrestore_no_auto_restore', 07:32:00 INFO - 'tabpaint']}, 07:32:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:32:00 INFO - 'tests': ['a11yr', 07:32:00 INFO - 'ts_paint', 07:32:00 INFO - 'tpaint', 07:32:00 INFO - 'sessionrestore', 07:32:00 INFO - 'sessionrestore_no_auto_restore', 07:32:00 INFO - 'tabpaint']}, 07:32:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:32:00 INFO - 'tests': ['a11yr', 07:32:00 INFO - 'ts_paint', 07:32:00 INFO - 'tpaint', 07:32:00 INFO - 'sessionrestore', 07:32:00 INFO - 'sessionrestore_no_auto_restore', 07:32:00 INFO - 'tabpaint']}, 07:32:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:32:00 INFO - 'tests': ['tsvgx', 07:32:00 INFO - 'tsvgr_opacity', 07:32:00 INFO - 'tart', 07:32:00 INFO - 'tscrollx', 07:32:00 INFO - 'cart']}, 07:32:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:32:00 INFO - 'tsvgr_opacity', 07:32:00 INFO - 'tart', 07:32:00 INFO - 'tscrollx', 07:32:00 INFO - 'cart']}, 07:32:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:00 INFO - 'talos_options': ['--disable-e10s'], 07:32:00 INFO - 'tests': ['tp5o']}, 07:32:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:00 INFO - 'tests': ['tp5o']}, 07:32:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:00 INFO - 'talos_options': ['--disable-e10s', 07:32:00 INFO - '--xperf_path', 07:32:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:32:00 INFO - 'tests': ['tp5n']}, 07:32:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:32:00 INFO - 'talos_options': ['--xperf_path', 07:32:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:32:00 INFO - 'tests': ['tp5n']}}, 07:32:00 INFO - 'talos.zip': {'path': '', 07:32:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:32:00 INFO - Running post-action listener: _resource_record_post_action 07:32:00 INFO - [mozharness: 2016-10-12 14:32:00.099135Z] Finished populate-webroot step (success) 07:32:00 INFO - [mozharness: 2016-10-12 14:32:00.099252Z] Running create-virtualenv step. 07:32:00 INFO - Running pre-action listener: _resource_record_pre_action 07:32:00 INFO - Running main action method: create_virtualenv 07:32:00 INFO - Creating virtualenv /builds/slave/test/build/venv 07:32:00 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 07:32:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:32:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:32:00 INFO - Using real prefix '/tools/python27' 07:32:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:32:00 INFO - Installing distribute.............................................................................................................................................................................................done. 07:32:03 INFO - Installing pip.................done. 07:32:03 INFO - Return code: 0 07:32:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:32:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:32:04 INFO - Reading from file tmpfile_stdout 07:32:04 INFO - Output received: 07:32:04 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) 07:32:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:32: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')]} 07:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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')]} 07:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e2d6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e24c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddbb595510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ead30>, '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 0x10df10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e2b20>, '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.4jBCMsXpTa/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.oQlTHkZACH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 07:32: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', 'pip>=1.5'] in /builds/slave/test/build 07:32: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 pip>=1.5 07:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jBCMsXpTa/Render', 07:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:04 INFO - 'HOME': '/Users/cltbld', 07:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:04 INFO - 'LOGNAME': 'cltbld', 07:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:04 INFO - 'MOZ_NO_REMOTE': '1', 07:32:04 INFO - 'NO_EM_RESTART': '1', 07:32:04 INFO - 'PAGER': '/bin/cat', 07:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:04 INFO - 'PWD': '/builds/slave/test', 07:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:04 INFO - 'SHELL': '/bin/bash', 07:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQlTHkZACH/Listeners', 07:32:04 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:04 INFO - 'USER': 'cltbld', 07:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:04 INFO - 'XPC_FLAGS': '0x0', 07:32:04 INFO - 'XPC_SERVICE_NAME': '0', 07:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:04 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 07:32:04 INFO - Cleaning up... 07:32:04 INFO - Return code: 0 07:32:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:32: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')]} 07:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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')]} 07:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32: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', '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 0x10e2d6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e24c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddbb595510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ead30>, '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 0x10df10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e2b20>, '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.4jBCMsXpTa/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.oQlTHkZACH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 07:32: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', 'psutil>=3.1.1'] in /builds/slave/test/build 07:32: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 psutil>=3.1.1 07:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jBCMsXpTa/Render', 07:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:04 INFO - 'HOME': '/Users/cltbld', 07:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:04 INFO - 'LOGNAME': 'cltbld', 07:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:04 INFO - 'MOZ_NO_REMOTE': '1', 07:32:04 INFO - 'NO_EM_RESTART': '1', 07:32:04 INFO - 'PAGER': '/bin/cat', 07:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:04 INFO - 'PWD': '/builds/slave/test', 07:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:04 INFO - 'SHELL': '/bin/bash', 07:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQlTHkZACH/Listeners', 07:32:04 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:04 INFO - 'USER': 'cltbld', 07:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:04 INFO - 'XPC_FLAGS': '0x0', 07:32:04 INFO - 'XPC_SERVICE_NAME': '0', 07:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:04 INFO - Downloading/unpacking psutil>=3.1.1 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:32:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:32:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:32:08 INFO - Installing collected packages: psutil 07:32:08 INFO - Running setup.py install for psutil 07:32:08 INFO - building 'psutil._psutil_osx' extension 07:32:08 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 07:32:08 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 07:32:08 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 07:32:08 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 07:32:08 INFO - building 'psutil._psutil_posix' extension 07:32:08 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 07:32:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:32:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:32:08 INFO - ^ 07:32:08 INFO - 1 warning generated. 07:32:08 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 07:32:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:32:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:32:08 INFO - Successfully installed psutil 07:32:08 INFO - Cleaning up... 07:32:08 INFO - Return code: 0 07:32:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:32:08 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')]} 07:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:08 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')]} 07:32:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:08 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 0x10e2d6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e24c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddbb595510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ead30>, '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 0x10df10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e2b20>, '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.4jBCMsXpTa/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.oQlTHkZACH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 07:32:08 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 07:32:08 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 07:32:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jBCMsXpTa/Render', 07:32:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:08 INFO - 'HOME': '/Users/cltbld', 07:32:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:08 INFO - 'LOGNAME': 'cltbld', 07:32:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:08 INFO - 'MOZ_NO_REMOTE': '1', 07:32:08 INFO - 'NO_EM_RESTART': '1', 07:32:08 INFO - 'PAGER': '/bin/cat', 07:32:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:08 INFO - 'PWD': '/builds/slave/test', 07:32:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:08 INFO - 'SHELL': '/bin/bash', 07:32:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQlTHkZACH/Listeners', 07:32:08 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:32:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:08 INFO - 'USER': 'cltbld', 07:32:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:08 INFO - 'XPC_FLAGS': '0x0', 07:32:08 INFO - 'XPC_SERVICE_NAME': '0', 07:32:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:32:09 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 07:32:09 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 07:32:09 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 07:32:09 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 07:32:09 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 07:32:09 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 07:32:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:32:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:32:11 INFO - Installing collected packages: mozsystemmonitor 07:32:11 INFO - Running setup.py install for mozsystemmonitor 07:32:11 INFO - Successfully installed mozsystemmonitor 07:32:11 INFO - Cleaning up... 07:32:12 INFO - Return code: 0 07:32:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:32:12 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')]} 07:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:12 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')]} 07:32:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:12 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 0x10e2d6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e24c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddbb595510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ead30>, '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 0x10df10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e2b20>, '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.4jBCMsXpTa/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.oQlTHkZACH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 07:32:12 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 07:32:12 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 07:32:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jBCMsXpTa/Render', 07:32:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:12 INFO - 'HOME': '/Users/cltbld', 07:32:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:12 INFO - 'LOGNAME': 'cltbld', 07:32:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:12 INFO - 'MOZ_NO_REMOTE': '1', 07:32:12 INFO - 'NO_EM_RESTART': '1', 07:32:12 INFO - 'PAGER': '/bin/cat', 07:32:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:12 INFO - 'PWD': '/builds/slave/test', 07:32:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:12 INFO - 'SHELL': '/bin/bash', 07:32:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQlTHkZACH/Listeners', 07:32:12 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:32:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:12 INFO - 'USER': 'cltbld', 07:32:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:12 INFO - 'XPC_FLAGS': '0x0', 07:32:12 INFO - 'XPC_SERVICE_NAME': '0', 07:32:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:12 INFO - Downloading/unpacking jsonschema==2.5.1 07:32:12 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 07:32:12 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 07:32:12 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 07:32:12 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 07:32:12 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 07:32:12 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 07:32:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:32:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32: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 07:32:15 INFO - Downloading functools32-3.2.3-2.tar.gz 07:32:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:32:15 INFO - warning: no files found matching '*.txt' 07:32:15 INFO - no previously-included directories found matching 'build' 07:32:15 INFO - no previously-included directories found matching 'dist' 07:32:15 INFO - no previously-included directories found matching '.git*' 07:32:15 INFO - Installing collected packages: jsonschema, functools32 07:32:15 INFO - Running setup.py install for functools32 07:32:15 INFO - warning: no files found matching '*.txt' 07:32:15 INFO - no previously-included directories found matching 'build' 07:32:15 INFO - no previously-included directories found matching 'dist' 07:32:15 INFO - no previously-included directories found matching '.git*' 07:32:15 INFO - Successfully installed jsonschema functools32 07:32:15 INFO - Cleaning up... 07:32:15 INFO - Return code: 0 07:32:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:32:15 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')]} 07:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:15 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')]} 07:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:15 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 0x10e2d6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e24c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddbb595510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ead30>, '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 0x10df10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e2b20>, '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.4jBCMsXpTa/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.oQlTHkZACH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 07:32:15 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 07:32:15 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 07:32:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jBCMsXpTa/Render', 07:32:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:15 INFO - 'HOME': '/Users/cltbld', 07:32:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:15 INFO - 'LOGNAME': 'cltbld', 07:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:15 INFO - 'MOZ_NO_REMOTE': '1', 07:32:15 INFO - 'NO_EM_RESTART': '1', 07:32:15 INFO - 'PAGER': '/bin/cat', 07:32:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:15 INFO - 'PWD': '/builds/slave/test', 07:32:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:15 INFO - 'SHELL': '/bin/bash', 07:32:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQlTHkZACH/Listeners', 07:32:15 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:32:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:15 INFO - 'USER': 'cltbld', 07:32:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:15 INFO - 'XPC_FLAGS': '0x0', 07:32:15 INFO - 'XPC_SERVICE_NAME': '0', 07:32:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:32:15 INFO - Cleaning up... 07:32:15 INFO - Return code: 0 07:32:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:32:15 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')]} 07:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:15 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')]} 07:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:15 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 0x10e2d6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e24c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddbb595510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ead30>, '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 0x10df10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e2b20>, '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.4jBCMsXpTa/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.oQlTHkZACH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 07:32:15 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 07:32:15 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 07:32:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jBCMsXpTa/Render', 07:32:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:15 INFO - 'HOME': '/Users/cltbld', 07:32:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:15 INFO - 'LOGNAME': 'cltbld', 07:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:15 INFO - 'MOZ_NO_REMOTE': '1', 07:32:15 INFO - 'NO_EM_RESTART': '1', 07:32:15 INFO - 'PAGER': '/bin/cat', 07:32:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:15 INFO - 'PWD': '/builds/slave/test', 07:32:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:15 INFO - 'SHELL': '/bin/bash', 07:32:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQlTHkZACH/Listeners', 07:32:15 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:32:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:15 INFO - 'USER': 'cltbld', 07:32:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:15 INFO - 'XPC_FLAGS': '0x0', 07:32:15 INFO - 'XPC_SERVICE_NAME': '0', 07:32:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:16 INFO - Downloading/unpacking blobuploader==1.2.4 07:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:32:18 INFO - Downloading blobuploader-1.2.4.tar.gz 07:32:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:32:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:32:18 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 07:32:18 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 07:32:18 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 07:32:18 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 07:32:18 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 07:32:18 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 07:32:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:32:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:32:19 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 07:32:19 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 07:32:19 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 07:32:19 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 07:32:19 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 07:32:19 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 07:32:19 INFO - Downloading docopt-0.6.1.tar.gz 07:32:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:32:19 INFO - Installing collected packages: blobuploader, requests, docopt 07:32:19 INFO - Running setup.py install for blobuploader 07:32:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:32:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:32:20 INFO - Running setup.py install for requests 07:32:20 INFO - Running setup.py install for docopt 07:32:20 INFO - Successfully installed blobuploader requests docopt 07:32:20 INFO - Cleaning up... 07:32:20 INFO - Return code: 0 07:32:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:20 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')]} 07:32:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:20 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')]} 07:32:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:20 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 0x10e2d6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e24c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddbb595510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ead30>, '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 0x10df10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e2b20>, '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.4jBCMsXpTa/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.oQlTHkZACH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 07:32:20 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 07:32:20 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 07:32:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jBCMsXpTa/Render', 07:32:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:20 INFO - 'HOME': '/Users/cltbld', 07:32:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:20 INFO - 'LOGNAME': 'cltbld', 07:32:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:20 INFO - 'MOZ_NO_REMOTE': '1', 07:32:20 INFO - 'NO_EM_RESTART': '1', 07:32:20 INFO - 'PAGER': '/bin/cat', 07:32:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:20 INFO - 'PWD': '/builds/slave/test', 07:32:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:20 INFO - 'SHELL': '/bin/bash', 07:32:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQlTHkZACH/Listeners', 07:32:20 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:32:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:20 INFO - 'USER': 'cltbld', 07:32:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:20 INFO - 'XPC_FLAGS': '0x0', 07:32:20 INFO - 'XPC_SERVICE_NAME': '0', 07:32:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:32:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Ij8KR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:32:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-qEhQwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:32:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Q_A3bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:32:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-kFGF21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:32:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-PoHVaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:32:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Pf6iAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:32:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-eJkYVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:32:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-sGVxun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:32:21 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-0fkIjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:32:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-QLlbtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:32:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-lBE3Aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:32:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-pesYIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:32:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-sRI1Bd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:32:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-iiXZ1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-teCh36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:32:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-HPgJNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:32:22 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Q1R8fc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:32:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:32:22 INFO - Running setup.py install for manifestparser 07:32:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:32:23 INFO - Running setup.py install for mozcrash 07:32:23 INFO - Running setup.py install for mozdebug 07:32:23 INFO - Running setup.py install for mozdevice 07:32:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:32:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:32:23 INFO - Running setup.py install for mozfile 07:32:23 INFO - Running setup.py install for mozhttpd 07:32:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:32:23 INFO - Running setup.py install for mozinfo 07:32:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:32:23 INFO - Running setup.py install for mozInstall 07:32:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:32:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:32:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:32:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:32:24 INFO - Running setup.py install for mozleak 07:32:24 INFO - Running setup.py install for mozlog 07:32:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:32:24 INFO - Running setup.py install for moznetwork 07:32:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:32:24 INFO - Running setup.py install for mozprocess 07:32:24 INFO - Running setup.py install for mozprofile 07:32:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:32:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:32:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:32:24 INFO - Running setup.py install for mozrunner 07:32:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:32:25 INFO - Running setup.py install for mozscreenshot 07:32:25 INFO - Running setup.py install for moztest 07:32:25 INFO - Running setup.py install for mozversion 07:32:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:32:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:32:25 INFO - Cleaning up... 07:32:25 INFO - Return code: 0 07:32:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:25 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')]} 07:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:25 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')]} 07:32:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:25 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 0x10e2d6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e24c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddbb595510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ead30>, '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 0x10df10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e2b20>, '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.4jBCMsXpTa/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.oQlTHkZACH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 07:32:25 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 07:32:25 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 07:32:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jBCMsXpTa/Render', 07:32:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:25 INFO - 'HOME': '/Users/cltbld', 07:32:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:25 INFO - 'LOGNAME': 'cltbld', 07:32:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:25 INFO - 'MOZ_NO_REMOTE': '1', 07:32:25 INFO - 'NO_EM_RESTART': '1', 07:32:25 INFO - 'PAGER': '/bin/cat', 07:32:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:25 INFO - 'PWD': '/builds/slave/test', 07:32:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:25 INFO - 'SHELL': '/bin/bash', 07:32:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQlTHkZACH/Listeners', 07:32:25 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:32:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:25 INFO - 'USER': 'cltbld', 07:32:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:25 INFO - 'XPC_FLAGS': '0x0', 07:32:25 INFO - 'XPC_SERVICE_NAME': '0', 07:32:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:32:25 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-lo0RWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:32:25 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)) 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:32:25 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-DXBSiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:32:25 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-U_g8Co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:32:26 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)) 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:32:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-8D0gKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:32:26 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)) 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:32:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-0LkeZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:32:26 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)) 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:32:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-hFnwey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:32:26 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)) 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:32:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-fYMKwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:32:26 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)) 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:32:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-oYaic_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:32:26 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)) 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:32:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-QXMm_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:32:26 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)) 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:32:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-sCnO9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:32:26 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)) 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:32:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-IcXZcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:32:26 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)) 07:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:32:26 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-83wQax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:32:27 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)) 07:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:32:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Q3Kqtp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:32:27 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)) 07:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:32:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-NsokSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-X1gDnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:32:27 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)) 07:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:32:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-mksHEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:32:27 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-XRqb63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:32:27 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)) 07:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:32:27 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)) 07:32:27 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)) 07:32:27 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)) 07:32:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:32:27 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 07:32:27 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 07:32:27 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 07:32:27 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 07:32:27 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 07:32:27 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 07:32:30 INFO - Downloading blessings-1.6.tar.gz 07:32:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:32:30 INFO - Installing collected packages: blessings 07:32:30 INFO - Running setup.py install for blessings 07:32:30 INFO - Successfully installed blessings 07:32:30 INFO - Cleaning up... 07:32:30 INFO - Return code: 0 07:32:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:32:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:32:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:32:30 INFO - Reading from file tmpfile_stdout 07:32:30 INFO - Current package versions: 07:32:30 INFO - blessings == 1.6 07:32:30 INFO - blobuploader == 1.2.4 07:32:30 INFO - docopt == 0.6.1 07:32:30 INFO - functools32 == 3.2.3-2 07:32:30 INFO - jsonschema == 2.5.1 07:32:30 INFO - manifestparser == 1.1 07:32:30 INFO - mozInstall == 1.12 07:32:30 INFO - mozcrash == 0.17 07:32:30 INFO - mozdebug == 0.1 07:32:30 INFO - mozdevice == 0.48 07:32:30 INFO - mozfile == 1.2 07:32:30 INFO - mozhttpd == 0.7 07:32:30 INFO - mozinfo == 0.9 07:32:30 INFO - mozleak == 0.1 07:32:30 INFO - mozlog == 3.4 07:32:30 INFO - moznetwork == 0.27 07:32:30 INFO - mozprocess == 0.23 07:32:30 INFO - mozprofile == 0.28 07:32:30 INFO - mozrunner == 6.12 07:32:30 INFO - mozscreenshot == 0.1 07:32:30 INFO - mozsystemmonitor == 0.3 07:32:30 INFO - moztest == 0.7 07:32:30 INFO - mozversion == 1.4 07:32:30 INFO - psutil == 3.1.1 07:32:30 INFO - requests == 1.2.3 07:32:30 INFO - wsgiref == 0.1.2 07:32:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:31 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')]} 07:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:31 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 0x10e2d6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e24c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddbb595510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ead30>, '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 0x10df10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e2b20>, '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.4jBCMsXpTa/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.oQlTHkZACH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 07:32:31 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 07:32:31 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 07:32:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jBCMsXpTa/Render', 07:32:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:31 INFO - 'HOME': '/Users/cltbld', 07:32:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:31 INFO - 'LOGNAME': 'cltbld', 07:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:31 INFO - 'MOZ_NO_REMOTE': '1', 07:32:31 INFO - 'NO_EM_RESTART': '1', 07:32:31 INFO - 'PAGER': '/bin/cat', 07:32:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:31 INFO - 'PWD': '/builds/slave/test', 07:32:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:31 INFO - 'SHELL': '/bin/bash', 07:32:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQlTHkZACH/Listeners', 07:32:31 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:32:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:31 INFO - 'USER': 'cltbld', 07:32:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:31 INFO - 'XPC_FLAGS': '0x0', 07:32:31 INFO - 'XPC_SERVICE_NAME': '0', 07:32:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:31 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)) 07:32:31 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)) 07:32:31 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)) 07:32:31 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)) 07:32:31 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)) 07:32:31 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)) 07:32:31 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)) 07:32:31 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)) 07:32:31 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)) 07:32:31 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)) 07:32:31 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)) 07:32:31 INFO - Cleaning up... 07:32:31 INFO - Return code: 0 07:32:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:32:31 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')]} 07:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:31 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')]} 07:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:32:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e2d6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e24c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddbb595510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0ead30>, '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 0x10df10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e2b20>, '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.4jBCMsXpTa/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.oQlTHkZACH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 07:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:32:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jBCMsXpTa/Render', 07:32:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:32:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:32:32 INFO - 'HOME': '/Users/cltbld', 07:32:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:32:32 INFO - 'LOGNAME': 'cltbld', 07:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:32:32 INFO - 'MOZ_NO_REMOTE': '1', 07:32:32 INFO - 'NO_EM_RESTART': '1', 07:32:32 INFO - 'PAGER': '/bin/cat', 07:32:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:32:32 INFO - 'PWD': '/builds/slave/test', 07:32:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:32:32 INFO - 'SHELL': '/bin/bash', 07:32:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQlTHkZACH/Listeners', 07:32:32 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:32:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:32:32 INFO - 'USER': 'cltbld', 07:32:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:32:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:32:32 INFO - 'XPC_FLAGS': '0x0', 07:32:32 INFO - 'XPC_SERVICE_NAME': '0', 07:32:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:32:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:32:32 INFO - Cleaning up... 07:32:32 INFO - Return code: 0 07:32:32 INFO - Running post-action listener: _resource_record_post_action 07:32:32 INFO - Running post-action listener: _start_resource_monitoring 07:32:32 INFO - Starting resource monitoring. 07:32:32 INFO - [mozharness: 2016-10-12 14:32:32.386137Z] Finished create-virtualenv step (success) 07:32:32 INFO - [mozharness: 2016-10-12 14:32:32.387163Z] Running install step. 07:32:32 INFO - Running pre-action listener: _resource_record_pre_action 07:32:32 INFO - Running main action method: install 07:32:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:32:32 INFO - Reading from file tmpfile_stdout 07:32:32 INFO - Detecting whether we're running mozinstall >=1.0... 07:32:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:32:32 INFO - Reading from file tmpfile_stdout 07:32:32 INFO - Output received: 07:32:32 INFO - Usage: mozinstall [options] installer 07:32:32 INFO - Options: 07:32:32 INFO - -h, --help show this help message and exit 07:32:32 INFO - -d DEST, --destination=DEST 07:32:32 INFO - Directory to install application into. [default: 07:32:32 INFO - "/builds/slave/test"] 07:32:32 INFO - --app=APP Application being installed. [default: firefox] 07:32:32 INFO - mkdir: /builds/slave/test/build/application 07:32:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:33:06 INFO - Reading from file tmpfile_stdout 07:33:06 INFO - Output received: 07:33:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:33:06 INFO - Running post-action listener: _resource_record_post_action 07:33:06 INFO - [mozharness: 2016-10-12 14:33:06.827043Z] Finished install step (success) 07:33:06 INFO - [mozharness: 2016-10-12 14:33:06.827178Z] Running run-tests step. 07:33:06 INFO - Running pre-action listener: _resource_record_pre_action 07:33:06 INFO - Running main action method: run_tests 07:33:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:33:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:33:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:33:06 INFO - 2016-10-12 07:33:06.841 screenresolution[2210:18787] starting screenresolution argv=screenresolution get 07:33:06 INFO - 2016-10-12 07:33:06.844 screenresolution[2210:18787] Display 0: 1600x1200x32@60 07:33:06 INFO - 2016-10-12 07:33:06.850 screenresolution[2211:18789] starting screenresolution argv=screenresolution list 07:33:06 INFO - Available Modes on Display 0 07:33:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:33:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:33:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:33:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:33:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:33:07 INFO - Intel Iris: 07:33:07 INFO - Chipset Model: Intel Iris 07:33:07 INFO - Type: GPU 07:33:07 INFO - Bus: Built-In 07:33:07 INFO - VRAM (Dynamic, Max): 1536 MB 07:33:07 INFO - Vendor: Intel (0x8086) 07:33:07 INFO - Device ID: 0x0a2e 07:33:07 INFO - Revision ID: 0x0009 07:33:07 INFO - Displays: 07:33:07 INFO - Display: 07:33:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:33:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:33:07 INFO - Display Serial Number: 1600x1200 60 07:33:07 INFO - Main Display: Yes 07:33:07 INFO - Mirror: Off 07:33:07 INFO - Online: Yes 07:33:07 INFO - Rotation: Supported 07:33:07 INFO - Return code: 0 07:33:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:33:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:33:07 INFO - Python 2.7.3 07:33:07 INFO - Return code: 0 07:33:07 INFO - grabbing minidump binary from tooltool 07:33: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')]} 07:33:07 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 0x10e0ead30>, '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 0x10df10db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e0e2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:33:07 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 07:33:07 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 07:33:07 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 07:33:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:33:07 INFO - Return code: 0 07:33:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:33:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:33:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:33:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:33:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:33:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--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 07:33:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0025 --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 07:33:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4jBCMsXpTa/Render', 07:33:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:33:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:33:07 INFO - 'HOME': '/Users/cltbld', 07:33:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:33:07 INFO - 'LOGNAME': 'cltbld', 07:33:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:33:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:33:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:33:07 INFO - 'MOZ_NO_REMOTE': '1', 07:33:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:33:07 INFO - 'NO_EM_RESTART': '1', 07:33:07 INFO - 'PAGER': '/bin/cat', 07:33:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:33:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:33:07 INFO - 'PWD': '/builds/slave/test', 07:33:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:33:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:33:07 INFO - 'SHELL': '/bin/bash', 07:33:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oQlTHkZACH/Listeners', 07:33:07 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 07:33:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:33:07 INFO - 'USER': 'cltbld', 07:33:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:33:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:33:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:33:07 INFO - 'XPC_FLAGS': '0x0', 07:33:07 INFO - 'XPC_SERVICE_NAME': '0', 07:33:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:33:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--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 07:33:07 INFO - mozversion application_buildid: 20161012051247 07:33:07 INFO - mozversion application_changeset: 4756eb1a2f82a6fb2e7ab5e3708d14aa76816076 07:33:07 INFO - mozversion application_display_name: Nightly 07:33:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:33:07 INFO - mozversion application_name: Firefox 07:33:07 INFO - mozversion application_remotingname: firefox 07:33:07 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 07:33:07 INFO - mozversion application_vendor: Mozilla 07:33:07 INFO - mozversion application_version: 52.0a1 07:33:07 INFO - mozversion platform_buildid: 20161012051247 07:33:07 INFO - mozversion platform_changeset: 4756eb1a2f82a6fb2e7ab5e3708d14aa76816076 07:33:07 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 07:33:07 INFO - mozversion platform_version: 52.0a1 07:33:07 INFO - using testdate: 1476282787 07:33:07 INFO - actual date: 1476282787 07:33:07 INFO - starting webserver on 'localhost:51717' 07:33:07 INFO - SUITE-START | Running 2 tests 07:33:07 INFO - TEST-START | dromaeo_css 07:33:07 INFO - operating with platform_type : mac_ 07:33:07 INFO - Initialising browser for dromaeo_css test... 07:33:07 INFO - TEST-INFO | started process 2221 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpHZKseK/profile http://localhost:51717/getInfo.html) 07:33:10 INFO - PROCESS | 2221 | 2016-10-12 07:33:10.316 plugin-container[2223:18943] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 07:33:10 INFO - PROCESS | 2221 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:10 INFO - PROCESS | 2221 | 2016-10-12 07:33:10.320 plugin-container[2223:18943] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 07:33:10 INFO - PROCESS | 2221 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:10 INFO - PROCESS | 2221 | 2016-10-12 07:33:10.320 plugin-container[2223:18943] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:33:10 INFO - PROCESS | 2221 | __metrics Screen width/height:1600/1200 07:33:10 INFO - PROCESS | 2221 | colorDepth:24 07:33:10 INFO - PROCESS | 2221 | Browser inner width/height: 1024/689 07:33:10 INFO - PROCESS | 2221 | __metrics 07:33:10 ERROR - PROCESS | 2221 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 07:33:11 INFO - TEST-INFO | 2221: exit 0 07:33:11 INFO - Browser initialized. 07:33:11 INFO - Running cycle 1/1 for dromaeo_css test... 07:33:11 INFO - TEST-INFO | started process 2224 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpHZKseK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:33:13 INFO - PROCESS | 2224 | 2016-10-12 07:33:13.704 plugin-container[2225:19066] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 07:33:13 INFO - PROCESS | 2224 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:13 INFO - PROCESS | 2224 | 2016-10-12 07:33:13.705 plugin-container[2225:19066] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 07:33:13 INFO - PROCESS | 2224 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:13 INFO - PROCESS | 2224 | 2016-10-12 07:33:13.705 plugin-container[2225:19066] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:33:23 INFO - PROCESS | 2224 | RSS: Main: 244584448 07:33:23 INFO - PROCESS | 2224 | 07:34:13 INFO - PROCESS | 2224 | 1476282853066 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:36:26 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:51717/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51717/tests/dromaeo/cssquery-ext.html) 07:36:28 INFO - PROCESS | 2224 | RSS: Main: 256212992 07:36:28 INFO - PROCESS | 2224 | 07:39:30 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:51717/tests/dromaeo/cssquery-ext.html (next: http://localhost:51717/tests/dromaeo/cssquery-jquery.html) 07:39:31 INFO - PROCESS | 2224 | RSS: Main: 270241792 07:39:31 INFO - PROCESS | 2224 | 07:42:33 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:51717/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51717/tests/dromaeo/cssquery-mootools.html) 07:42:34 INFO - PROCESS | 2224 | RSS: Main: 273252352 07:42:34 INFO - PROCESS | 2224 | 07:45:36 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:51717/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51717/tests/dromaeo/cssquery-prototype.html) 07:45:36 INFO - PROCESS | 2224 | RSS: Main: 274083840 07:45:36 INFO - PROCESS | 2224 | 07:48:39 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:51717/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51717/tests/dromaeo/cssquery-yui.html) 07:48:40 INFO - PROCESS | 2224 | RSS: Main: 279658496 07:48:40 INFO - PROCESS | 2224 | 07:51:43 INFO - PROCESS | 2224 | Cycle 1(1): loaded http://localhost:51717/tests/dromaeo/cssquery-yui.html (next: http://localhost:51717/tests/dromaeo/cssquery-dojo.html) 07:51:43 INFO - PROCESS | 2224 | RSS: Main: 281878528 07:51:43 INFO - PROCESS | 2224 | 07:51:43 INFO - PROCESS | 2224 | __start_tp_report 07:51:43 INFO - PROCESS | 2224 | _x_x_mozilla_page_load 07:51:43 INFO - PROCESS | 2224 | _x_x_mozilla_page_load_details 07:51:43 INFO - PROCESS | 2224 | |i|pagename|runs| 07:51:43 INFO - PROCESS | 2224 | |0;dojo.html;2888.3934373781503;3392.3045775616;3406.812625305609;3410.6930376266146;3446.5864096308446;5562.249096371988;5624.240727501786;5763.3660297367305;5791.2760904886945;5797.739101740422;4772.78522466489;5640.097584386499;5776.480116789488;5784.4504772046585;5801.796937107202;5162.690238585678;5296.947030529684;5309.017831701138;5344.679319240839;5442.8911421771545;5250.422453530108;5343.7328133593355;5374.838754837341;5392;5394.083005888989;5353.250544923714;5387.434319396466;5390.6226564140525;5394.05604019297;5396.703181325026;5171.50870667286;5197.66215196011;5205.297284866532;5294;5334.946650533484;5162.896742065138;5168.638195326329;5174.146265866128;5177.508136727005;5363.410024897258;5570.442955704397;5755.251817263771;5772.163036359704;5793.507551858097;5802.680852553108;5150.356205474316;6206.534509911775;6210.813675589694;6213.471854892339;6267.937320626781;6239.65681887496;6247.468965137969;6254.843628909268;6267.341929097471;6267.7179526920945;5945.881082378328;5971.790987315423;5994;6006.849828754316;6047.697615119315;5435.891282174334;6166.013437849922;6219.782307619212;6224.159738435344;6323.20959880015;6214.285357183981;6221.564490485713;6224.719887605069;6226.128342032119;6249.9062514062325;6212.968935155295;6215.782447614311;6244.313125556185;6254.843628909313;6273.215848018997;5451.400345961941;5830.854228644318;6158.938410615926;6240.5319601030105;6275.52933529978;6163.568550201443;6188.040853667689;6220.626762394271;6230.875382492325;6260.906086408708;4960.429550601655;4991.775370108354;4998.625103117281;5005.424376196784;5005.549500544896;4382.123575284892;4440.378347031354;4453.799579018952;4521.706089104197;4544.590986811203;5725.341585717612;5990.221271234712;6012.759489620366;6042.486388656926;6111.877762444727;6231.065340198916;6238.594491358047;6244.219472565929;6257.812265632038;6340.873182536324;5356.65181763192;5442.755076021588;6014.458698717136;6022.759089636365;6095.969520152459;4351.282038463618;4616.330632058332;4933.593925731149;5430.972845135828;5449.3188351456065;5204.609654276021;5233.712145832016;6281.466075383643;6288.968555157195;6322.557420980487;5718.342390625131;6064.636121832704;6139.048447490646;6161.722722477589;6238.532110091852;6193.690315484298;6242.5942313749465;6245.469135123383;6339;6450.903236451551;6174.351693072163;6187;6189.133521307111;6212.751489940351;6216.751329946752;6108.8581424937165;6139.338817383189;6140.995089002448;6265.467435267871;6266.154069200599;6106.399996025681;6170.197874276315;6180.690965451619;6190.473809726043;6200.007998720183;6117.113018612187;6123.693815309306;6129.35641757609;6170.753169873155;6179.443850053517;5119.308893299357;5916.645001299764;5930.258717660293;5969.313528944227;6036.396360363929;6070.423309785563;6088.452039316483;6120.60216085953;6141.087130295835;6147.600405973776;5942.58401911862;5993.760249589967;6001.669908155093;6003.909941350796;6011.519078473799 07:51:43 INFO - PROCESS | 2224 | |1;ext.html;489.4860396583587;499.25361584431283;502.2842449509448;526.1739069660632;555.8554775758302;37663.81168094159;40204;40244.79877600615;40253.396199057;40381.99045023872;40631.39052914209;40780.18439631213;41216.175676486455;41323.38014929771;41329.17341653165;9404.62381504739;9547.236221102296;9565.473898935556;9585.616575336979;9611.519424028806;9459.385139965914;9522.761930951747;9527.475988820612;9545.331826772128;9552.37909535878;9526.190273826698;9580.616775329014;9585.472798996052;9604.951975240114;9608.471534065624;9508.904910950905;9574;9585.904140958572;9604.51977401127;9614.95192524033;9456.952715236448;9520.714378568655;9543.570539325747;9605.087516685904;9606.375585586984;38911.41632875509;39293;39336.803315983234;39468.60531394707;39538.802305988575;3218.4846342993037;3295.07737833407;3321.586340398079;3397.3035527717007;3491.5635545556806;30051;30264.092077237718;30470.085897423112;30650.846745766128;31610.209744756336;3230.370077834823;3263.151580589064;3271.3947919634707;3282.688144626257;3354.882579109744;446257;447069;447155;449635.7518212421;450353.74823125673;60385;60509.69745151246;60723.392766071775;60848;60875.69562152161;63189;64063.35936640574;64167;64202.03696944549;64405;14651.780223296446;15269.618259543378;16303.755443668355;16435.83564164367;16440.588985275226;12555.372231388394;12592.748145037049;12600.4329805159;12671.429785659668;12717.918976886884;17252.868275410045;18557.90721046386;18778;18800.247990080516;18933.33733319327;3170.7499812692176;3266.7876588022177;6388.6166829989415;6498.480121590262;7995.842162075688;869.2741560796806;955.7515046088067;1002.2983911262146;1038.6364772329775;1069.93006993007;23754.406139846298;28673.56989645157;28919;28953.565696514568;29414.411711765646;1072.1958531101673;1103.8840921703265;1154.8980854844272;1160.7040204747905;1175.7707247086908;15031.797456203478;15567.221638918008;15624.76562851558;15705;15876.364945402054;580857;623663.6450453247;641887;647621;648683;45418;57132.14301785477;57413;57461.391939927445;57642;1362.3256488038485;1374.2647683489486;1379.4344318829233;1394.2889126545535;1395.3651088754593;28778.424431510834;29539;29550.96571619983;30157.69842301549;30331;49670.75164624154;49845.75077124591;50053.74973125111;50172.74913625408;50219.74890125526;593236.0338198282;594269;594985;598296.0255596257;600235;26311.21066368012;26416.471670566865;26721.93112275487;26740.465190696465;26808.8659556701;46460.53539464562;47017.29474057823;47421.288680669124;47454.52545474501;47670.28494572515;37861;38424.42363364496;38708.61291387163;39080;39495.407568885916;31052.22369440782;38138.61861381351;38453.423198651486;39449.80275098721;39748;37004.81497592495;37237.62762372341;38136.42795358016;38328.61671383362;38394.23211535809;30470.67227557704;33232;33714.662853371156;34097.659023410444;34407;23138.537229255657;24055.639165412184;25292.74707252904;25431.982720690965;25967 07:51:43 INFO - PROCESS | 2224 | |2;jquery.html;359.51824555096164;362.70983213429236;368.4510079980828;376.4917361561892;397.6043836382798;17939.551511212227;18267.08664566773;19080.61838763224;19261.807381926177;19978.60042799145;11553.364564948924;14620.634484137901;16070.517884463485;18954.14706338215;19356.806431935678;5097.05704444677;5354.27717258171;5365.463453654643;5370.570354371661;5372.462753724616;3843;5212.113940630102;5491.807786727445;6062.42406971337;6477.54657173998;6119.112728654365;6462.935370646303;6477.060826180201;6481.675916204194;6481.805545833632;4952;5918;5924.052151655757;5932.555058370596;5950.702464876739;5460.836174914719;5642.915356269659;5662.886742265173;5689.943100568983;5734.627249228828;10861.40262285574;12842.67893302675;13077;13081.86918130816;15553.92223038889;2103.5477372365017;2191.6274233380204;2278.0659929428853;2288.107638021173;2329.883505824702;9894.505274736235;10025.298229123964;10149.543270552844;10391.79216415675;10430.843537346946;1622.294301978643;1777.9733304000451;1823.3891646298505;1867.1571714006643;1901.8383437407838;119899;122306.38846805798;124091.75908240893;126844;127138.36430817879;10134.594616215269;10796.406197659047;10893.455327233607;11037.9513946175;11613.30320180792;14226.28868556568;14337.713245735236;14479.986400952042;14485.275736213358;14490.782638260434;8200.958995205105;8417.611094169515;8492;8568.828623427496;8581.656733730706;7130.215676275606;8227.053888802864;8377.916220837713;8459.407841451162;8519.764634128016;3707.3141468828353;4495.190865644216;4503.572160644799;4515.819367225275;4579.198640237945;1737.9913100434417;1817.5728703754608;1824.0059167753607;1838.8528917686554;1907.5898681783483;697.2852825853439;817.9386546009073;882.3382463152635;929.7443203119196;938.2775263047552;17188.31246750116;18919.864808111557;19195.944223067865;19779.307724229584;20258.290959816335;840;891.3270480787094;911.0752586125088;911.1571796088591;947.7535840681472;7824.061112666516;8598.957005214934;9031.412958157698;10422.530986105643;10726.034656880918;461531.69234154286;461893;464545;553063;554534;80730;111188.88811111785;133604;137124.62875371118;140939.2953035228;960.0687333286841;990.7671697151163;996.1930836022844;996.9152622027063;999.7950420163849;18518.092705432802;18524.814751852307;18913.71629425587;18914.243430262635;18952.905235473736;42126.68303243229;44317.89205269765;49065;49397.76505587389;50979;71712;78947;79225.20774792178;79898.6005069971;80484.3903121946;7587.658555365132;7750;7961.6019199040975;8078.878816817635;8170.305524030524;8985.820283594423;11411.429428528374;11537.480813363587;12608.117431779687;12827.973762099196;10804.297720648132;10915.727106822393;11025.558977641125;11132.165087618623;11251.381174035507;10723.142148628247;10752.784944301227;10956.616518421817;10968.725781855199;11173.608923687632;10649;10895.782084358427;11283.605073822377;11311.472951151818;11324.036587194416;6640.667966601747;7686.46194766361;7853.56805375687;7978;8038.276555110068;8941.910580894108;10925.945370273097;11118.277311974696;11128.833067504156;11236.719082023015 07:51:43 INFO - PROCESS | 2224 | |3;mootools.html;1405.9156450612963;1482.562644020014;1614.9583518630484;1800.2888858900742;1804.0709034847048;7259.2377800331;8247.969003874516;8326.91673083269;8894.510801905893;8900.554972251386;6376.139221205136;6863.691133898974;7735.72924947627;8388.867502887108;8591;2312.2485192312483;2319.663648770927;2319.9420014499606;2323.186884590397;2324.4305145239396;2276.9544609107807;2300.1374484568287;2327.080803082781;2338.1933233034574;2347.7769611886843;2316.6872472216205;2320.9883950580224;2333.3233362324904;2333.7199536055696;2335.241046659834;2301.0795681727277;2303.884805759714;2304.366299267698;2317.478567322356;2330.0330362899376;1961.9803801961941;2304.1704986204954;2308.284431826139;2309.642005489135;2314.93055208344;8699.608517616722;8708;8752.168543988311;8756.474611523328;8770;5060.620453465968;5140.228965655144;5180.689158650455;5218;5231.816886408996;3063.632364116298;3437.570303712036;3490.6509349065145;3583.498310236569;3646.835892384849;955.3408148377597;959.9520023998842;969.0116081596732;1067.5889782433742;1096.2600244834784;9161.908380916173;9239;9330.440173589606;10106.545205465773;10739.463026848627;4326.329418940069;4420.31485119807;4483.148201841705;4541.909161816745;4542.091581683677;8159.918400815915;8466.814645949573;8870.290376769843;8981.955090224506;8993.370464067584;4333.241682705514;5122.282880396748;5153.3558305211845;5163.225516172529;5163.380394352702;4228.9154216915485;4368.584984426538;4656.254999200111;4774.997250577418;4839.927401088987;3613.8735144269826;3647.8176091195332;3762.1159027628487;3794.658480736747;3795.449659799314;1146.7649131927935;1155.9017483513828;1159.5014143918197;1162.8197629367462;1164.831099490586;830.464350493928;901.8647202919615;904.9502277374676;920.6731610278389;952.7618095476131;5438;5799.942000580025;5834.5624078194305;5984.371640977722;6130.2030736004035;385.93053250414636;418.6734347209182;430.2556577121535;433.1661551513387;441.3908805847909;3721.776693398405;3779.168582911755;3885.038055781812;3938.8424463021156;4052.63526282636;9193.724188274362;10187.235957303232;10256.28206025586;10525.84211236832;10570;9015.459072455673;10380.584776609003;10535.315204511682;10542.736431589272;10653.627123050655;7574.545527268381;7844.333231675371;8057.556834374049;8067.919320806835;8190.7133250335955;5525.806596769255;5783.421657834183;5988.670623115596;6020.698965051817;6035.698215089315;9649.9035009649;9827.55775990068;10011.649592264235;10013.699589012342;10050.849237261593;7886.329661978796;9619.615215391306;9895.50522473859;9931.155851752683;9967;7379.594122323324;7451.18037015917;7481.4014878808475;7526.5860377679755;7569.962150189213;8099.8380032397845;8101.10887802353;8110.31062359706;8172;8177.550234737147;6228.937710622836;7200.747973820891;7331.890021649786;7398.96300518494;7464.328210460867;7183.317584829642;7212.305978425813;7217.963910180415;7248.60132692707;7313.926860731537;6839.487038472035;6846.110005699227;6873.931260687529;6946.479014074066;6951.582905025714;4946.307516947702;4996.125678006261;4996.125678006407;5051.964347308866;5078.9238161426865;4910.852674419773;4966.205407134841;5044.47033061538;5155.994581056808;5162.380514338303 07:51:43 INFO - PROCESS | 2224 | |4;prototype.html;298.74755831322534;299.9865006074728;311.66807350172064;317.9205198700325;320.8050013698973;5099.898002039957;5418.9458105418935;5627.324721033477;5695.629784064038;5722.19750778505;5438.184272359148;5544.251526043983;5599.384067752554;5599.664020158783;5611.775528978836;4285.485741710999;4288.670512141231;4340.609345158927;4343.913121737563;4376.912461750763;3573.785572865623;4199.4120823084795;4305.892352691177;4333.306670932651;4339.654707040811;3858.614138586147;3859.652631263186;4192.329227323628;4220.6412454941365;4264.765437900915;4195.538490766014;4206.369044643313;4234.724742891717;4244.427002354673;4283.464566929134;3427.8002699055382;3483.4600636901323;3691.7231207659534;3692.3538380783257;3696.4270538066376;4537.795799189044;5475.370332411784;5500.22996780451;5500.4499550045075;5517.834464966018;4217.662586993033;4303.35449682547;4828.5412885775795;4898.779554920037;4901.946081592475;5400.972995135038;5770.038187016435;5916.585838991272;5934.43622855828;6011.939880601226;3690.5202323698018;4003.919921601552;4013.6387725104883;4022.7787471689044;4035.899102522431;5853.88292234153;6024.367441418675;6240.875182496325;6293.87412251757;6300.842478938017;3341.031100980737;3415.077928959167;3925.1168487090176;4004.478298246706;4010.4184893190322;3096.504559270551;3450.6894379505966;4049.8380064797666;4094.508658960884;4103.2409004334295;4772.976135119302;4796.880077998008;4801.4958429365115;4812.831550895702;4840.080384726891;4952.8761780955765;5396.92218495874;5577.103784713217;5973.163757074013;6154.015357542861;1145.9171083326344;1196.8803119687961;1384.6469150366593;1417.3196865504615;1419.842828095099;791.204839136664;791.9071681080072;846.4878748357278;862.5816479007676;865.6323009644747;3854.5116669414224;4055.8986025349013;4176.644985176234;4201.054762678373;4696.9060618787435;4612.60144724748;4621.584057434847;5124.718140502308;5227.137522308776;5232.947670523322;4553.5357570914375;4562.5665561771575;4669.392978912788;4769.618430525549;4811.797050737316;2918.7810914181523;3018.079485756821;3104.7270619046535;3243.7187311012017;3271.9836400817844;5552.944470555323;6373.1714877066615;6393.369690728834;6411;6441.967790161019;5722.655176033629;6029.698515074228;6240.500759939194;6307.148534947815;6331.556791024676;6153.446189842935;6325.557210995278;6370;6397.7440902364315;6399.232092148972;5160.793568257228;5443.727813609383;5808.572941964261;5846.707664616667;6209.689515524296;4546.658735672918;5978.850528736642;6040.244969378828;6045.637261764308;6119.632822030692;6027.969860150672;6175.622385381571;6227.688615569294;6374.426301632875;6380.393862583047;3429.37652695693;3465.0990742406652;3843.404272337792;4013.1973605279645;4030.3954406838034;5656.519195868397;6168.784092556739;6181.474574661204;6181.660008699565;6197.783077592263;5256.658317209369;5687.374388817144;5777.91333129995;5908;5925.911111333419;4707.59985401231;5182.948170518397;5296.390915044819;5856.443637854397;5969.641821490724;5562.805301814417;5767.769289228551;5818.505427038749;5836.533077353886;5877.764889404376;5647.305808257588;5745.12204262113;5911.793087241926;5913.019138588621;5986.371430999857;4760.904781904412;5663.688497132697;5839.445252700986;5862.032764593794;5876.44173803488 07:51:43 INFO - PROCESS | 2224 | |5;yui.html;706.1103010207138;708.3483195460176;743.7582785594683;774.6436639145993;796.6016991504248;15138.167400792952;15721.528354149379;16052;16082.758758618631;16083.59791005224;14259.643508912282;14387.136771793674;14411.063280886736;14417.062890912084;14417.927910360433;657.7204688007589;687.3538873458932;793.8729803231485;818.664347617477;824.381713714714;841.949483031017;842.1241908415271;842.6081871929551;846.4963346808639;851.6423102297019;840.9698119803253;897.0939351255247;898.3442087276293;902.4359775140537;904.669795524632;814.2346194577078;888.2405543260484;891.1044400377643;892.7500299916034;893.8659201119884;586.4253032028594;642.2966851297822;665.1452883045312;666.0009985022466;666.0708311904865;14806.555803325917;14822.703545929022;14825.481108161162;14858;14888.330025148894;14289.857101428957;14406.063605865585;14407.351669174808;14429.92785036068;14605.196714180716;11716.472758725793;11784.175107742461;11828.763424731544;11883.821742673867;12093.818592721116;3722.789251255885;3777.829997650085;3805.3911374179997;3822.0827001519433;3909.2376986487648;116212;118134;119386;121177;122041;10972.780544389068;11098.44507774608;11109.888901111048;11143.888561114447;11196.104311655048;22685;22770.31689049331;22896.65655015176;22933;22958.885205573864;7714.305712485903;7762.301392874556;7775.727849525352;8024.317932975646;8133.552654604054;4339.7830108494445;4342.240107981091;4403;4437.156940181355;4523.592876641118;2902.042326032404;2954.5863579098946;3194.2653189766484;3337.4993750937556;3428.919890234568;532.2309263114762;607.2955371768727;618.4619381138349;633.2306247908721;652.4356431686557;596.8776400837818;614.4776939601393;633.4742164003866;654.5974225851068;658.940695337422;8964.551772411483;9197.540122993823;9476.147146756824;9550.044995500395;9942.950285248528;538.7306346826587;614.265952187141;622.0544771946616;677.867815775919;681.7579759185517;3869.8258578364043;4327.545607711145;4690.835820746258;4809.098616268613;4833.081714474239;86166;113596.72806543823;135489;151504;153196;102301.48849255707;131140.3442982779;131255.68744312433;131719.3307163005;132354.33822831017;96369.62706305245;97465.5126724376;107923;109814.45092774485;111429;8909.821803564022;8954.462732236087;9044.14080662336;9254.4910029949;9396.906030939603;64855.35144648682;65972.34027659662;68954.65522672555;70552.64723676349;73427;76448.61775691085;82794.58602706948;91755;97451;102679;12772.297523636289;13386.397612107285;13520.526781562598;14439;14678.212636052624;14847.703045938804;14946.252687365806;15009.414418218797;15262.23688815577;15712.685746285239;13742.587722368346;13954.23251721165;13978.930105349815;14013.579592612237;14132.717345652823;13961.301934903417;13992.860071399156;14225.217613030969;14461.276936153361;14657.780133297796;13592.184468931895;13727;13817.955025813651;13896.027278090436;14197.858021419654;10940.12479001662;11310.038646715122;11446.141539384746;11685.707857303636;12092.818607720716;9357.344985850925;10461.738456538647;10937.945310273397;11473.885261147281;11650.76698466043 07:51:43 INFO - PROCESS | 2224 | __end_tp_report 07:51:43 INFO - PROCESS | 2224 | __start_cc_report 07:51:43 INFO - PROCESS | 2224 | _x_x_mozilla_cycle_collect,374 07:51:43 INFO - PROCESS | 2224 | __end_cc_report 07:51:43 INFO - PROCESS | 2224 | __startTimestamp1476283903393__endTimestamp 07:51:43 INFO - PROCESS | 2224 | 07:51:43 INFO - PROCESS | 2224 | ------- Summary: start ------- 07:51:43 INFO - PROCESS | 2224 | Number of tests: 6 07:51:43 INFO - PROCESS | 2224 | 07:51:43 INFO - PROCESS | 2224 | [#0] dojo.html Cycles:165 Average:5729.45 Median:6006.85 stddev:656.07 (10.9%) stddev-sans-first:619.07 07:51:43 INFO - PROCESS | 2224 | Values: 2888.4 3392.3 3406.8 3410.7 3446.6 5562.2 5624.2 5763.4 5791.3 5797.7 4772.8 5640.1 5776.5 5784.5 5801.8 5162.7 5296.9 5309.0 5344.7 5442.9 5250.4 5343.7 5374.8 5392.0 5394.1 5353.3 5387.4 5390.6 5394.1 5396.7 5171.5 5197.7 5205.3 5294.0 5334.9 5162.9 5168.6 5174.1 5177.5 5363.4 5570.4 5755.3 5772.2 5793.5 5802.7 5150.4 6206.5 6210.8 6213.5 6267.9 6239.7 6247.5 6254.8 6267.3 6267.7 5945.9 5971.8 5994.0 6006.8 6047.7 5435.9 6166.0 6219.8 6224.2 6323.2 6214.3 6221.6 6224.7 6226.1 6249.9 6213.0 6215.8 6244.3 6254.8 6273.2 5451.4 5830.9 6158.9 6240.5 6275.5 6163.6 6188.0 6220.6 6230.9 6260.9 4960.4 4991.8 4998.6 5005.4 5005.5 4382.1 4440.4 4453.8 4521.7 4544.6 5725.3 5990.2 6012.8 6042.5 6111.9 6231.1 6238.6 6244.2 6257.8 6340.9 5356.7 5442.8 6014.5 6022.8 6096.0 4351.3 4616.3 4933.6 5431.0 5449.3 5204.6 5233.7 6281.5 6289.0 6322.6 5718.3 6064.6 6139.0 6161.7 6238.5 6193.7 6242.6 6245.5 6339.0 6450.9 6174.4 6187.0 6189.1 6212.8 6216.8 6108.9 6139.3 6141.0 6265.5 6266.2 6106.4 6170.2 6180.7 6190.5 6200.0 6117.1 6123.7 6129.4 6170.8 6179.4 5119.3 5916.6 5930.3 5969.3 6036.4 6070.4 6088.5 6120.6 6141.1 6147.6 5942.6 5993.8 6001.7 6003.9 6011.5 07:51:43 INFO - PROCESS | 2224 | 07:51:43 INFO - PROCESS | 2224 | [#1] ext.html Cycles:180 Average:68968.80 Median:39393.30 stddev:150601.52 (382.3%) stddev-sans-first:150936.23 07:51:43 INFO - PROCESS | 2224 | Values: 489.5 499.3 502.3 526.2 555.9 37663.8 40204.0 40244.8 40253.4 40382.0 40631.4 40780.2 41216.2 41323.4 41329.2 9404.6 9547.2 9565.5 9585.6 9611.5 9459.4 9522.8 9527.5 9545.3 9552.4 9526.2 9580.6 9585.5 9605.0 9608.5 9508.9 9574.0 9585.9 9604.5 9615.0 9457.0 9520.7 9543.6 9605.1 9606.4 38911.4 39293.0 39336.8 39468.6 39538.8 3218.5 3295.1 3321.6 3397.3 3491.6 30051.0 30264.1 30470.1 30650.8 31610.2 3230.4 3263.2 3271.4 3282.7 3354.9 446257.0 447069.0 447155.0 449635.8 450353.7 60385.0 60509.7 60723.4 60848.0 60875.7 63189.0 64063.4 64167.0 64202.0 64405.0 14651.8 15269.6 16303.8 16435.8 16440.6 12555.4 12592.7 12600.4 12671.4 12717.9 17252.9 18557.9 18778.0 18800.2 18933.3 3170.7 3266.8 6388.6 6498.5 7995.8 869.3 955.8 1002.3 1038.6 1069.9 23754.4 28673.6 28919.0 28953.6 29414.4 1072.2 1103.9 1154.9 1160.7 1175.8 15031.8 15567.2 15624.8 15705.0 15876.4 580857.0 623663.6 641887.0 647621.0 648683.0 45418.0 57132.1 57413.0 57461.4 57642.0 1362.3 1374.3 1379.4 1394.3 1395.4 28778.4 29539.0 29551.0 30157.7 30331.0 49670.8 49845.8 50053.7 50172.7 50219.7 593236.0 594269.0 594985.0 598296.0 600235.0 26311.2 26416.5 26721.9 26740.5 26808.9 46460.5 47017.3 47421.3 47454.5 47670.3 37861.0 38424.4 38708.6 39080.0 39495.4 31052.2 38138.6 38453.4 39449.8 39748.0 37004.8 37237.6 38136.4 38328.6 38394.2 30470.7 33232.0 33714.7 34097.7 34407.0 23138.5 24055.6 25292.7 25432.0 25967.0 07:51:43 INFO - PROCESS | 2224 | 07:51:43 INFO - PROCESS | 2224 | [#2] jquery.html Cycles:180 Average:31361.55 Median:2037.88 stddev:84829.95 (4162.7%) stddev-sans-first:85035.98 07:51:43 INFO - PROCESS | 2224 | Values: 359.5 362.7 368.5 376.5 397.6 17939.6 18267.1 19080.6 19261.8 19978.6 11553.4 14620.6 16070.5 18954.1 19356.8 5097.1 5354.3 5365.5 5370.6 5372.5 3843.0 5212.1 5491.8 6062.4 6477.5 6119.1 6462.9 6477.1 6481.7 6481.8 4952.0 5918.0 5924.1 5932.6 5950.7 5460.8 5642.9 5662.9 5689.9 5734.6 10861.4 12842.7 13077.0 13081.9 15553.9 2103.5 2191.6 2278.1 2288.1 2329.9 9894.5 10025.3 10149.5 10391.8 10430.8 1622.3 1778.0 1823.4 1867.2 1901.8 119899.0 122306.4 124091.8 126844.0 127138.4 10134.6 10796.4 10893.5 11038.0 11613.3 14226.3 14337.7 14480.0 14485.3 14490.8 8201.0 8417.6 8492.0 8568.8 8581.7 7130.2 8227.1 8377.9 8459.4 8519.8 3707.3 4495.2 4503.6 4515.8 4579.2 1738.0 1817.6 1824.0 1838.9 1907.6 697.3 817.9 882.3 929.7 938.3 17188.3 18919.9 19195.9 19779.3 20258.3 840.0 891.3 911.1 911.2 947.8 7824.1 8599.0 9031.4 10422.5 10726.0 461531.7 461893.0 464545.0 553063.0 554534.0 80730.0 111188.9 133604.0 137124.6 140939.3 960.1 990.8 996.2 996.9 999.8 18518.1 18524.8 18913.7 18914.2 18952.9 42126.7 44317.9 49065.0 49397.8 50979.0 71712.0 78947.0 79225.2 79898.6 80484.4 7587.7 7750.0 7961.6 8078.9 8170.3 8985.8 11411.4 11537.5 12608.1 12828.0 10804.3 10915.7 11025.6 11132.2 11251.4 10723.1 10752.8 10956.6 10968.7 11173.6 10649.0 10895.8 11283.6 11311.5 11324.0 6640.7 7686.5 7853.6 7978.0 8038.3 8941.9 10925.9 11118.3 11128.8 11236.7 07:51:43 INFO - PROCESS | 2224 | 07:51:43 INFO - PROCESS | 2224 | [#3] mootools.html Cycles:180 Average:5451.46 Median:5131.26 stddev:3002.95 (58.5%) stddev-sans-first:2995.99 07:51:43 INFO - PROCESS | 2224 | Values: 1405.9 1482.6 1615.0 1800.3 1804.1 7259.2 8248.0 8326.9 8894.5 8900.6 6376.1 6863.7 7735.7 8388.9 8591.0 2312.2 2319.7 2319.9 2323.2 2324.4 2277.0 2300.1 2327.1 2338.2 2347.8 2316.7 2321.0 2333.3 2333.7 2335.2 2301.1 2303.9 2304.4 2317.5 2330.0 1962.0 2304.2 2308.3 2309.6 2314.9 8699.6 8708.0 8752.2 8756.5 8770.0 5060.6 5140.2 5180.7 5218.0 5231.8 3063.6 3437.6 3490.7 3583.5 3646.8 955.3 960.0 969.0 1067.6 1096.3 9161.9 9239.0 9330.4 10106.5 10739.5 4326.3 4420.3 4483.1 4541.9 4542.1 8159.9 8466.8 8870.3 8982.0 8993.4 4333.2 5122.3 5153.4 5163.2 5163.4 4228.9 4368.6 4656.3 4775.0 4839.9 3613.9 3647.8 3762.1 3794.7 3795.4 1146.8 1155.9 1159.5 1162.8 1164.8 830.5 901.9 905.0 920.7 952.8 5438.0 5799.9 5834.6 5984.4 6130.2 385.9 418.7 430.3 433.2 441.4 3721.8 3779.2 3885.0 3938.8 4052.6 9193.7 10187.2 10256.3 10525.8 10570.0 9015.5 10380.6 10535.3 10542.7 10653.6 7574.5 7844.3 8057.6 8067.9 8190.7 5525.8 5783.4 5988.7 6020.7 6035.7 9649.9 9827.6 10011.6 10013.7 10050.8 7886.3 9619.6 9895.5 9931.2 9967.0 7379.6 7451.2 7481.4 7526.6 7570.0 8099.8 8101.1 8110.3 8172.0 8177.6 6228.9 7200.7 7331.9 7399.0 7464.3 7183.3 7212.3 7218.0 7248.6 7313.9 6839.5 6846.1 6873.9 6946.5 6951.6 4946.3 4996.1 4996.1 5052.0 5078.9 4910.9 4966.2 5044.5 5156.0 5162.4 07:51:43 INFO - PROCESS | 2224 | 07:51:43 INFO - PROCESS | 2224 | [#4] prototype.html Cycles:180 Average:4698.36 Median:5205.04 stddev:1500.54 (28.8%) stddev-sans-first:1467.96 07:51:43 INFO - PROCESS | 2224 | Values: 298.7 300.0 311.7 317.9 320.8 5099.9 5418.9 5627.3 5695.6 5722.2 5438.2 5544.3 5599.4 5599.7 5611.8 4285.5 4288.7 4340.6 4343.9 4376.9 3573.8 4199.4 4305.9 4333.3 4339.7 3858.6 3859.7 4192.3 4220.6 4264.8 4195.5 4206.4 4234.7 4244.4 4283.5 3427.8 3483.5 3691.7 3692.4 3696.4 4537.8 5475.4 5500.2 5500.4 5517.8 4217.7 4303.4 4828.5 4898.8 4901.9 5401.0 5770.0 5916.6 5934.4 6011.9 3690.5 4003.9 4013.6 4022.8 4035.9 5853.9 6024.4 6240.9 6293.9 6300.8 3341.0 3415.1 3925.1 4004.5 4010.4 3096.5 3450.7 4049.8 4094.5 4103.2 4773.0 4796.9 4801.5 4812.8 4840.1 4952.9 5396.9 5577.1 5973.2 6154.0 1145.9 1196.9 1384.6 1417.3 1419.8 791.2 791.9 846.5 862.6 865.6 3854.5 4055.9 4176.6 4201.1 4696.9 4612.6 4621.6 5124.7 5227.1 5232.9 4553.5 4562.6 4669.4 4769.6 4811.8 2918.8 3018.1 3104.7 3243.7 3272.0 5552.9 6373.2 6393.4 6411.0 6442.0 5722.7 6029.7 6240.5 6307.1 6331.6 6153.4 6325.6 6370.0 6397.7 6399.2 5160.8 5443.7 5808.6 5846.7 6209.7 4546.7 5978.9 6040.2 6045.6 6119.6 6028.0 6175.6 6227.7 6374.4 6380.4 3429.4 3465.1 3843.4 4013.2 4030.4 5656.5 6168.8 6181.5 6181.7 6197.8 5256.7 5687.4 5777.9 5908.0 5925.9 4707.6 5182.9 5296.4 5856.4 5969.6 5562.8 5767.8 5818.5 5836.5 5877.8 5647.3 5745.1 5911.8 5913.0 5986.4 4760.9 5663.7 5839.4 5862.0 5876.4 07:51:43 INFO - PROCESS | 2224 | 07:51:43 INFO - PROCESS | 2224 | [#5] yui.html Cycles:180 Average:24560.47 Median:3383.21 stddev:38367.57 (1134.1%) stddev-sans-first:38433.39 07:51:43 INFO - PROCESS | 2224 | Values: 706.1 708.3 743.8 774.6 796.6 15138.2 15721.5 16052.0 16082.8 16083.6 14259.6 14387.1 14411.1 14417.1 14417.9 657.7 687.4 793.9 818.7 824.4 841.9 842.1 842.6 846.5 851.6 841.0 897.1 898.3 902.4 904.7 814.2 888.2 891.1 892.8 893.9 586.4 642.3 665.1 666.0 666.1 14806.6 14822.7 14825.5 14858.0 14888.3 14289.9 14406.1 14407.4 14429.9 14605.2 11716.5 11784.2 11828.8 11883.8 12093.8 3722.8 3777.8 3805.4 3822.1 3909.2 116212.0 118134.0 119386.0 121177.0 122041.0 10972.8 11098.4 11109.9 11143.9 11196.1 22685.0 22770.3 22896.7 22933.0 22958.9 7714.3 7762.3 7775.7 8024.3 8133.6 4339.8 4342.2 4403.0 4437.2 4523.6 2902.0 2954.6 3194.3 3337.5 3428.9 532.2 607.3 618.5 633.2 652.4 596.9 614.5 633.5 654.6 658.9 8964.6 9197.5 9476.1 9550.0 9943.0 538.7 614.3 622.1 677.9 681.8 3869.8 4327.5 4690.8 4809.1 4833.1 86166.0 113596.7 135489.0 151504.0 153196.0 102301.5 131140.3 131255.7 131719.3 132354.3 96369.6 97465.5 107923.0 109814.5 111429.0 8909.8 8954.5 9044.1 9254.5 9396.9 64855.4 65972.3 68954.7 70552.6 73427.0 76448.6 82794.6 91755.0 97451.0 102679.0 12772.3 13386.4 13520.5 14439.0 14678.2 14847.7 14946.3 15009.4 15262.2 15712.7 13742.6 13954.2 13978.9 14013.6 14132.7 13961.3 13992.9 14225.2 14461.3 14657.8 13592.2 13727.0 13818.0 13896.0 14197.9 10940.1 11310.0 11446.1 11685.7 12092.8 9357.3 10461.7 10937.9 11473.9 11650.8 07:51:43 INFO - PROCESS | 2224 | -------- Summary: end -------- 07:51:43 INFO - PROCESS | 2224 | 07:51:43 INFO - TEST-INFO | 2224: exit 0 07:51:44 INFO - TEST-OK | dromaeo_css | took 1116589ms 07:51:44 INFO - TEST-START | kraken 07:51:44 INFO - operating with platform_type : mac_ 07:51:44 INFO - Initialising browser for kraken test... 07:51:44 INFO - TEST-INFO | started process 2228 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpxOEjPQ/profile http://localhost:51717/getInfo.html) 07:51:46 INFO - PROCESS | 2228 | 2016-10-12 07:51:46.568 plugin-container[2229:20950] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 07:51:46 INFO - PROCESS | 2228 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:51:46 INFO - PROCESS | 2228 | 2016-10-12 07:51:46.571 plugin-container[2229:20950] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8753, name = 'com.apple.CFPasteboardClient' 07:51:46 INFO - PROCESS | 2228 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:51:46 INFO - PROCESS | 2228 | 2016-10-12 07:51:46.571 plugin-container[2229:20950] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:51:46 INFO - PROCESS | 2228 | __metrics Screen width/height:1600/1200 07:51:46 INFO - PROCESS | 2228 | colorDepth:24 07:51:46 INFO - PROCESS | 2228 | Browser inner width/height: 1024/689 07:51:46 INFO - PROCESS | 2228 | __metrics 07:51:46 ERROR - PROCESS | 2228 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 07:51:47 INFO - TEST-INFO | 2228: exit 0 07:51:47 INFO - Browser initialized. 07:51:47 INFO - Running cycle 1/1 for kraken test... 07:51:47 INFO - TEST-INFO | started process 2230 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpxOEjPQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:51:49 INFO - PROCESS | 2230 | 2016-10-12 07:51:49.830 plugin-container[2231:21066] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 07:51:49 INFO - PROCESS | 2230 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:51:49 INFO - PROCESS | 2230 | 2016-10-12 07:51:49.831 plugin-container[2231:21066] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1f, name = 'com.apple.CFPasteboardClient' 07:51:49 INFO - PROCESS | 2230 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:51:49 INFO - PROCESS | 2230 | 2016-10-12 07:51:49.831 plugin-container[2231:21066] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:51:59 INFO - PROCESS | 2230 | RSS: Main: 244387840 07:51:59 INFO - PROCESS | 2230 | 07:52:02 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:51717/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51717/tests/kraken/driver.html?testName=audio-beat-detection) 07:52:03 INFO - PROCESS | 2230 | RSS: Main: 256299008 07:52:03 INFO - PROCESS | 2230 | 07:52:05 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:51717/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51717/tests/kraken/driver.html?testName=audio-dft) 07:52:06 INFO - PROCESS | 2230 | RSS: Main: 263786496 07:52:06 INFO - PROCESS | 2230 | 07:52:10 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:51717/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51717/tests/kraken/driver.html?testName=audio-fft) 07:52:10 INFO - PROCESS | 2230 | RSS: Main: 263147520 07:52:10 INFO - PROCESS | 2230 | 07:52:12 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:51717/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51717/tests/kraken/driver.html?testName=audio-oscillator) 07:52:13 INFO - PROCESS | 2230 | RSS: Main: 264679424 07:52:13 INFO - PROCESS | 2230 | 07:52:15 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:51717/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51717/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:52:15 INFO - PROCESS | 2230 | RSS: Main: 264896512 07:52:15 INFO - PROCESS | 2230 | 07:52:18 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:51717/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51717/tests/kraken/driver.html?testName=imaging-darkroom) 07:52:19 INFO - PROCESS | 2230 | RSS: Main: 265621504 07:52:19 INFO - PROCESS | 2230 | 07:52:22 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:51717/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51717/tests/kraken/driver.html?testName=imaging-desaturate) 07:52:23 INFO - PROCESS | 2230 | RSS: Main: 265752576 07:52:23 INFO - PROCESS | 2230 | 07:52:26 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:51717/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51717/tests/kraken/driver.html?testName=json-parse-financial) 07:52:26 INFO - PROCESS | 2230 | RSS: Main: 265797632 07:52:26 INFO - PROCESS | 2230 | 07:52:28 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:51717/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51717/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:52:29 INFO - PROCESS | 2230 | RSS: Main: 265404416 07:52:29 INFO - PROCESS | 2230 | 07:52:31 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:51717/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51717/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:52:31 INFO - PROCESS | 2230 | RSS: Main: 265416704 07:52:31 INFO - PROCESS | 2230 | 07:52:34 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:51717/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51717/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:52:34 INFO - PROCESS | 2230 | RSS: Main: 265420800 07:52:34 INFO - PROCESS | 2230 | 07:52:37 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:51717/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51717/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:52:38 INFO - PROCESS | 2230 | RSS: Main: 265699328 07:52:38 INFO - PROCESS | 2230 | 07:52:41 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:51717/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51717/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:52:42 INFO - PROCESS | 2230 | RSS: Main: 265510912 07:52:42 INFO - PROCESS | 2230 | 07:52:43 INFO - PROCESS | 2230 | Cycle 1(1): loaded http://localhost:51717/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51717/tests/kraken/driver.html?testName=ai-astar) 07:52:44 INFO - PROCESS | 2230 | RSS: Main: 267235328 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | __start_tp_report 07:52:44 INFO - PROCESS | 2230 | _x_x_mozilla_page_load 07:52:44 INFO - PROCESS | 2230 | _x_x_mozilla_page_load_details 07:52:44 INFO - PROCESS | 2230 | |i|pagename|runs| 07:52:44 INFO - PROCESS | 2230 | |0;ai-astar;118;118;117;117;117;126;172;163;170;174 07:52:44 INFO - PROCESS | 2230 | |1;audio-beat-detection;151;112;113;151;113;117;115;116;140;115 07:52:44 INFO - PROCESS | 2230 | |2;audio-dft;190;277;262;248;275;244;197;198;196;197 07:52:44 INFO - PROCESS | 2230 | |3;audio-fft;74;59;60;84;60;57;84;83;77;62 07:52:44 INFO - PROCESS | 2230 | |4;audio-oscillator;91;71;89;76;92;102;124;100;72;87 07:52:44 INFO - PROCESS | 2230 | |5;imaging-gaussian-blur;137;134;88;86;88;86;89;86;91;90 07:52:44 INFO - PROCESS | 2230 | |6;imaging-darkroom;105;98;98;160;175;109;98;100;117;173 07:52:44 INFO - PROCESS | 2230 | |7;imaging-desaturate;120;102;142;154;112;91;94;89;98;91 07:52:44 INFO - PROCESS | 2230 | |8;json-parse-financial;57;57;56;57;58;58;59;70;79;57 07:52:44 INFO - PROCESS | 2230 | |9;json-stringify-tinderbox;69;82;65;68;65;74;102;102;64;97 07:52:44 INFO - PROCESS | 2230 | |10;stanford-crypto-aes;92;73;77;76;102;109;111;110;75;110 07:52:44 INFO - PROCESS | 2230 | |11;stanford-crypto-ccm;148;152;146;151;148;160;161;156;210;147 07:52:44 INFO - PROCESS | 2230 | |12;stanford-crypto-pbkdf2;174;211;174;176;240;222;174;237;233;172 07:52:44 INFO - PROCESS | 2230 | |13;stanford-crypto-sha256-iterative;70;69;71;69;68;65;65;73;91;93 07:52:44 INFO - PROCESS | 2230 | __end_tp_report 07:52:44 INFO - PROCESS | 2230 | __start_cc_report 07:52:44 INFO - PROCESS | 2230 | _x_x_mozilla_cycle_collect,969 07:52:44 INFO - PROCESS | 2230 | __end_cc_report 07:52:44 INFO - PROCESS | 2230 | __startTimestamp1476283964206__endTimestamp 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | ------- Summary: start ------- 07:52:44 INFO - PROCESS | 2230 | Number of tests: 14 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | [#0] ai-astar Cycles:10 Average:139.20 Median:144.50 stddev:26.57 (18.4%) stddev-sans-first:27.05 07:52:44 INFO - PROCESS | 2230 | Values: 118.0 118.0 117.0 117.0 117.0 126.0 172.0 163.0 170.0 174.0 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:116.50 stddev:16.24 (13.9%) stddev-sans-first:14.06 07:52:44 INFO - PROCESS | 2230 | Values: 151.0 112.0 113.0 151.0 113.0 117.0 115.0 116.0 140.0 115.0 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | [#2] audio-dft Cycles:10 Average:228.40 Median:246.00 stddev:36.07 (14.7%) stddev-sans-first:35.48 07:52:44 INFO - PROCESS | 2230 | Values: 190.0 277.0 262.0 248.0 275.0 244.0 197.0 198.0 196.0 197.0 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | [#3] audio-fft Cycles:10 Average:70.00 Median:75.50 stddev:11.45 (15.2%) stddev-sans-first:12.05 07:52:44 INFO - PROCESS | 2230 | Values: 74.0 59.0 60.0 84.0 60.0 57.0 84.0 83.0 77.0 62.0 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:81.50 stddev:15.97 (19.6%) stddev-sans-first:16.93 07:52:44 INFO - PROCESS | 2230 | Values: 91.0 71.0 89.0 76.0 92.0 102.0 124.0 100.0 72.0 87.0 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | [#5] imaging-gaussian-blur Cycles:10 Average:97.50 Median:88.00 stddev:20.11 (22.9%) stddev-sans-first:15.44 07:52:44 INFO - PROCESS | 2230 | Values: 137.0 134.0 88.0 86.0 88.0 86.0 89.0 86.0 91.0 90.0 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | [#6] imaging-darkroom Cycles:10 Average:123.30 Median:174.00 stddev:32.54 (18.7%) stddev-sans-first:33.84 07:52:44 INFO - PROCESS | 2230 | Values: 105.0 98.0 98.0 160.0 175.0 109.0 98.0 100.0 117.0 173.0 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | [#7] imaging-desaturate Cycles:10 Average:109.30 Median:90.00 stddev:22.82 (25.4%) stddev-sans-first:23.87 07:52:44 INFO - PROCESS | 2230 | Values: 120.0 102.0 142.0 154.0 112.0 91.0 94.0 89.0 98.0 91.0 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:58.00 stddev:7.57 (13.0%) stddev-sans-first:7.90 07:52:44 INFO - PROCESS | 2230 | Values: 57.0 57.0 56.0 57.0 58.0 58.0 59.0 70.0 79.0 57.0 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | [#9] json-stringify-tinderbox Cycles:10 Average:78.80 Median:68.50 stddev:15.82 (23.1%) stddev-sans-first:16.38 07:52:44 INFO - PROCESS | 2230 | Values: 69.0 82.0 65.0 68.0 65.0 74.0 102.0 102.0 64.0 97.0 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | [#10] stanford-crypto-aes Cycles:10 Average:93.50 Median:74.00 stddev:16.69 (22.6%) stddev-sans-first:17.69 07:52:44 INFO - PROCESS | 2230 | Values: 92.0 73.0 77.0 76.0 102.0 109.0 111.0 110.0 75.0 110.0 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | [#11] stanford-crypto-ccm Cycles:10 Average:157.90 Median:154.00 stddev:19.06 (12.4%) stddev-sans-first:19.88 07:52:44 INFO - PROCESS | 2230 | Values: 148.0 152.0 146.0 151.0 148.0 160.0 161.0 156.0 210.0 147.0 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.30 Median:216.50 stddev:29.88 (13.8%) stddev-sans-first:30.01 07:52:44 INFO - PROCESS | 2230 | Values: 174.0 211.0 174.0 176.0 240.0 222.0 174.0 237.0 233.0 172.0 07:52:44 INFO - PROCESS | 2230 | 07:52:44 INFO - PROCESS | 2230 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.40 Median:70.50 stddev:10.11 (14.3%) stddev-sans-first:10.65 07:52:44 INFO - PROCESS | 2230 | Values: 70.0 69.0 71.0 69.0 68.0 65.0 65.0 73.0 91.0 93.0 07:52:44 INFO - PROCESS | 2230 | -------- Summary: end -------- 07:52:44 INFO - PROCESS | 2230 | 07:52:45 INFO - TEST-INFO | 2230: exit 0 07:52:45 INFO - TEST-OK | kraken | took 61060ms 07:52:45 INFO - SUITE-END | took 1177s 07:52:45 INFO - Completed test suite (00:19:37) 07:52:45 INFO - javascript benchmark 07:52:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7229.251413814196, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2888.3934373781503, 3392.3045775616, 3406.812625305609, 3410.6930376266146, 3446.5864096308446, 5562.249096371988, 5624.240727501786, 5763.3660297367305, 5791.2760904886945, 5797.739101740422, 4772.78522466489, 5640.097584386499, 5776.480116789488, 5784.4504772046585, 5801.796937107202, 5162.690238585678, 5296.947030529684, 5309.017831701138, 5344.679319240839, 5442.8911421771545, 5250.422453530108, 5343.7328133593355, 5374.838754837341, 5392.0, 5394.083005888989, 5353.250544923714, 5387.434319396466, 5390.6226564140525, 5394.05604019297, 5396.703181325026, 5171.50870667286, 5197.66215196011, 5205.297284866532, 5294.0, 5334.946650533484, 5162.896742065138, 5168.638195326329, 5174.146265866128, 5177.508136727005, 5363.410024897258, 5570.442955704397, 5755.251817263771, 5772.163036359704, 5793.507551858097, 5802.680852553108, 5150.356205474316, 6206.534509911775, 6210.813675589694, 6213.471854892339, 6267.937320626781, 6239.65681887496, 6247.468965137969, 6254.843628909268, 6267.341929097471, 6267.7179526920945, 5945.881082378328, 5971.790987315423, 5994.0, 6006.849828754316, 6047.697615119315, 5435.891282174334, 6166.013437849922, 6219.782307619212, 6224.159738435344, 6323.20959880015, 6214.285357183981, 6221.564490485713, 6224.719887605069, 6226.128342032119, 6249.9062514062325, 6212.968935155295, 6215.782447614311, 6244.313125556185, 6254.843628909313, 6273.215848018997, 5451.400345961941, 5830.854228644318, 6158.938410615926, 6240.5319601030105, 6275.52933529978, 6163.568550201443, 6188.040853667689, 6220.626762394271, 6230.875382492325, 6260.906086408708, 4960.429550601655, 4991.775370108354, 4998.625103117281, 5005.424376196784, 5005.549500544896, 4382.123575284892, 4440.378347031354, 4453.799579018952, 4521.706089104197, 4544.590986811203, 5725.341585717612, 5990.221271234712, 6012.759489620366, 6042.486388656926, 6111.877762444727, 6231.065340198916, 6238.594491358047, 6244.219472565929, 6257.812265632038, 6340.873182536324, 5356.65181763192, 5442.755076021588, 6014.458698717136, 6022.759089636365, 6095.969520152459, 4351.282038463618, 4616.330632058332, 4933.593925731149, 5430.972845135828, 5449.3188351456065, 5204.609654276021, 5233.712145832016, 6281.466075383643, 6288.968555157195, 6322.557420980487, 5718.342390625131, 6064.636121832704, 6139.048447490646, 6161.722722477589, 6238.532110091852, 6193.690315484298, 6242.5942313749465, 6245.469135123383, 6339.0, 6450.903236451551, 6174.351693072163, 6187.0, 6189.133521307111, 6212.751489940351, 6216.751329946752, 6108.8581424937165, 6139.338817383189, 6140.995089002448, 6265.467435267871, 6266.154069200599, 6106.399996025681, 6170.197874276315, 6180.690965451619, 6190.473809726043, 6200.007998720183, 6117.113018612187, 6123.693815309306, 6129.35641757609, 6170.753169873155, 6179.443850053517, 5119.308893299357, 5916.645001299764, 5930.258717660293, 5969.313528944227, 6036.396360363929, 6070.423309785563, 6088.452039316483, 6120.60216085953, 6141.087130295835, 6147.600405973776, 5942.58401911862, 5993.760249589967, 6001.669908155093, 6003.909941350796, 6011.519078473799], "value": 5688.699481834307, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.4860396583587, 499.25361584431283, 502.2842449509448, 526.1739069660632, 555.8554775758302, 37663.81168094159, 40204.0, 40244.79877600615, 40253.396199057, 40381.99045023872, 40631.39052914209, 40780.18439631213, 41216.175676486455, 41323.38014929771, 41329.17341653165, 9404.62381504739, 9547.236221102296, 9565.473898935556, 9585.616575336979, 9611.519424028806, 9459.385139965914, 9522.761930951747, 9527.475988820612, 9545.331826772128, 9552.37909535878, 9526.190273826698, 9580.616775329014, 9585.472798996052, 9604.951975240114, 9608.471534065624, 9508.904910950905, 9574.0, 9585.904140958572, 9604.51977401127, 9614.95192524033, 9456.952715236448, 9520.714378568655, 9543.570539325747, 9605.087516685904, 9606.375585586984, 38911.41632875509, 39293.0, 39336.803315983234, 39468.60531394707, 39538.802305988575, 3218.4846342993037, 3295.07737833407, 3321.586340398079, 3397.3035527717007, 3491.5635545556806, 30051.0, 30264.092077237718, 30470.085897423112, 30650.846745766128, 31610.209744756336, 3230.370077834823, 3263.151580589064, 3271.3947919634707, 3282.688144626257, 3354.882579109744, 446257.0, 447069.0, 447155.0, 449635.7518212421, 450353.74823125673, 60385.0, 60509.69745151246, 60723.392766071775, 60848.0, 60875.69562152161, 63189.0, 64063.35936640574, 64167.0, 64202.03696944549, 64405.0, 14651.780223296446, 15269.618259543378, 16303.755443668355, 16435.83564164367, 16440.588985275226, 12555.372231388394, 12592.748145037049, 12600.4329805159, 12671.429785659668, 12717.918976886884, 17252.868275410045, 18557.90721046386, 18778.0, 18800.247990080516, 18933.33733319327, 3170.7499812692176, 3266.7876588022177, 6388.6166829989415, 6498.480121590262, 7995.842162075688, 869.2741560796806, 955.7515046088067, 1002.2983911262146, 1038.6364772329775, 1069.93006993007, 23754.406139846298, 28673.56989645157, 28919.0, 28953.565696514568, 29414.411711765646, 1072.1958531101673, 1103.8840921703265, 1154.8980854844272, 1160.7040204747905, 1175.7707247086908, 15031.797456203478, 15567.221638918008, 15624.76562851558, 15705.0, 15876.364945402054, 580857.0, 623663.6450453247, 641887.0, 647621.0, 648683.0, 45418.0, 57132.14301785477, 57413.0, 57461.391939927445, 57642.0, 1362.3256488038485, 1374.2647683489486, 1379.4344318829233, 1394.2889126545535, 1395.3651088754593, 28778.424431510834, 29539.0, 29550.96571619983, 30157.69842301549, 30331.0, 49670.75164624154, 49845.75077124591, 50053.74973125111, 50172.74913625408, 50219.74890125526, 593236.0338198282, 594269.0, 594985.0, 598296.0255596257, 600235.0, 26311.21066368012, 26416.471670566865, 26721.93112275487, 26740.465190696465, 26808.8659556701, 46460.53539464562, 47017.29474057823, 47421.288680669124, 47454.52545474501, 47670.28494572515, 37861.0, 38424.42363364496, 38708.61291387163, 39080.0, 39495.407568885916, 31052.22369440782, 38138.61861381351, 38453.423198651486, 39449.80275098721, 39748.0, 37004.81497592495, 37237.62762372341, 38136.42795358016, 38328.61671383362, 38394.23211535809, 30470.67227557704, 33232.0, 33714.662853371156, 34097.659023410444, 34407.0, 23138.537229255657, 24055.639165412184, 25292.74707252904, 25431.982720690965, 25967.0], "value": 19645.550893769818, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.51824555096164, 362.70983213429236, 368.4510079980828, 376.4917361561892, 397.6043836382798, 17939.551511212227, 18267.08664566773, 19080.61838763224, 19261.807381926177, 19978.60042799145, 11553.364564948924, 14620.634484137901, 16070.517884463485, 18954.14706338215, 19356.806431935678, 5097.05704444677, 5354.27717258171, 5365.463453654643, 5370.570354371661, 5372.462753724616, 3843.0, 5212.113940630102, 5491.807786727445, 6062.42406971337, 6477.54657173998, 6119.112728654365, 6462.935370646303, 6477.060826180201, 6481.675916204194, 6481.805545833632, 4952.0, 5918.0, 5924.052151655757, 5932.555058370596, 5950.702464876739, 5460.836174914719, 5642.915356269659, 5662.886742265173, 5689.943100568983, 5734.627249228828, 10861.40262285574, 12842.67893302675, 13077.0, 13081.86918130816, 15553.92223038889, 2103.5477372365017, 2191.6274233380204, 2278.0659929428853, 2288.107638021173, 2329.883505824702, 9894.505274736235, 10025.298229123964, 10149.543270552844, 10391.79216415675, 10430.843537346946, 1622.294301978643, 1777.9733304000451, 1823.3891646298505, 1867.1571714006643, 1901.8383437407838, 119899.0, 122306.38846805798, 124091.75908240893, 126844.0, 127138.36430817879, 10134.594616215269, 10796.406197659047, 10893.455327233607, 11037.9513946175, 11613.30320180792, 14226.28868556568, 14337.713245735236, 14479.986400952042, 14485.275736213358, 14490.782638260434, 8200.958995205105, 8417.611094169515, 8492.0, 8568.828623427496, 8581.656733730706, 7130.215676275606, 8227.053888802864, 8377.916220837713, 8459.407841451162, 8519.764634128016, 3707.3141468828353, 4495.190865644216, 4503.572160644799, 4515.819367225275, 4579.198640237945, 1737.9913100434417, 1817.5728703754608, 1824.0059167753607, 1838.8528917686554, 1907.5898681783483, 697.2852825853439, 817.9386546009073, 882.3382463152635, 929.7443203119196, 938.2775263047552, 17188.31246750116, 18919.864808111557, 19195.944223067865, 19779.307724229584, 20258.290959816335, 840.0, 891.3270480787094, 911.0752586125088, 911.1571796088591, 947.7535840681472, 7824.061112666516, 8598.957005214934, 9031.412958157698, 10422.530986105643, 10726.034656880918, 461531.69234154286, 461893.0, 464545.0, 553063.0, 554534.0, 80730.0, 111188.88811111785, 133604.0, 137124.62875371118, 140939.2953035228, 960.0687333286841, 990.7671697151163, 996.1930836022844, 996.9152622027063, 999.7950420163849, 18518.092705432802, 18524.814751852307, 18913.71629425587, 18914.243430262635, 18952.905235473736, 42126.68303243229, 44317.89205269765, 49065.0, 49397.76505587389, 50979.0, 71712.0, 78947.0, 79225.20774792178, 79898.6005069971, 80484.3903121946, 7587.658555365132, 7750.0, 7961.6019199040975, 8078.878816817635, 8170.305524030524, 8985.820283594423, 11411.429428528374, 11537.480813363587, 12608.117431779687, 12827.973762099196, 10804.297720648132, 10915.727106822393, 11025.558977641125, 11132.165087618623, 11251.381174035507, 10723.142148628247, 10752.784944301227, 10956.616518421817, 10968.725781855199, 11173.608923687632, 10649.0, 10895.782084358427, 11283.605073822377, 11311.472951151818, 11324.036587194416, 6640.667966601747, 7686.46194766361, 7853.56805375687, 7978.0, 8038.276555110068, 8941.910580894108, 10925.945370273097, 11118.277311974696, 11128.833067504156, 11236.719082023015], "value": 8989.639163555681, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1405.9156450612963, 1482.562644020014, 1614.9583518630484, 1800.2888858900742, 1804.0709034847048, 7259.2377800331, 8247.969003874516, 8326.91673083269, 8894.510801905893, 8900.554972251386, 6376.139221205136, 6863.691133898974, 7735.72924947627, 8388.867502887108, 8591.0, 2312.2485192312483, 2319.663648770927, 2319.9420014499606, 2323.186884590397, 2324.4305145239396, 2276.9544609107807, 2300.1374484568287, 2327.080803082781, 2338.1933233034574, 2347.7769611886843, 2316.6872472216205, 2320.9883950580224, 2333.3233362324904, 2333.7199536055696, 2335.241046659834, 2301.0795681727277, 2303.884805759714, 2304.366299267698, 2317.478567322356, 2330.0330362899376, 1961.9803801961941, 2304.1704986204954, 2308.284431826139, 2309.642005489135, 2314.93055208344, 8699.608517616722, 8708.0, 8752.168543988311, 8756.474611523328, 8770.0, 5060.620453465968, 5140.228965655144, 5180.689158650455, 5218.0, 5231.816886408996, 3063.632364116298, 3437.570303712036, 3490.6509349065145, 3583.498310236569, 3646.835892384849, 955.3408148377597, 959.9520023998842, 969.0116081596732, 1067.5889782433742, 1096.2600244834784, 9161.908380916173, 9239.0, 9330.440173589606, 10106.545205465773, 10739.463026848627, 4326.329418940069, 4420.31485119807, 4483.148201841705, 4541.909161816745, 4542.091581683677, 8159.918400815915, 8466.814645949573, 8870.290376769843, 8981.955090224506, 8993.370464067584, 4333.241682705514, 5122.282880396748, 5153.3558305211845, 5163.225516172529, 5163.380394352702, 4228.9154216915485, 4368.584984426538, 4656.254999200111, 4774.997250577418, 4839.927401088987, 3613.8735144269826, 3647.8176091195332, 3762.1159027628487, 3794.658480736747, 3795.449659799314, 1146.7649131927935, 1155.9017483513828, 1159.5014143918197, 1162.8197629367462, 1164.831099490586, 830.464350493928, 901.8647202919615, 904.9502277374676, 920.6731610278389, 952.7618095476131, 5438.0, 5799.942000580025, 5834.5624078194305, 5984.371640977722, 6130.2030736004035, 385.93053250414636, 418.6734347209182, 430.2556577121535, 433.1661551513387, 441.3908805847909, 3721.776693398405, 3779.168582911755, 3885.038055781812, 3938.8424463021156, 4052.63526282636, 9193.724188274362, 10187.235957303232, 10256.28206025586, 10525.84211236832, 10570.0, 9015.459072455673, 10380.584776609003, 10535.315204511682, 10542.736431589272, 10653.627123050655, 7574.545527268381, 7844.333231675371, 8057.556834374049, 8067.919320806835, 8190.7133250335955, 5525.806596769255, 5783.421657834183, 5988.670623115596, 6020.698965051817, 6035.698215089315, 9649.9035009649, 9827.55775990068, 10011.649592264235, 10013.699589012342, 10050.849237261593, 7886.329661978796, 9619.615215391306, 9895.50522473859, 9931.155851752683, 9967.0, 7379.594122323324, 7451.18037015917, 7481.4014878808475, 7526.5860377679755, 7569.962150189213, 8099.8380032397845, 8101.10887802353, 8110.31062359706, 8172.0, 8177.550234737147, 6228.937710622836, 7200.747973820891, 7331.890021649786, 7398.96300518494, 7464.328210460867, 7183.317584829642, 7212.305978425813, 7217.963910180415, 7248.60132692707, 7313.926860731537, 6839.487038472035, 6846.110005699227, 6873.931260687529, 6946.479014074066, 6951.582905025714, 4946.307516947702, 4996.125678006261, 4996.125678006407, 5051.964347308866, 5078.9238161426865, 4910.852674419773, 4966.205407134841, 5044.47033061538, 5155.994581056808, 5162.380514338303], "value": 4331.462261962664, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.74755831322534, 299.9865006074728, 311.66807350172064, 317.9205198700325, 320.8050013698973, 5099.898002039957, 5418.9458105418935, 5627.324721033477, 5695.629784064038, 5722.19750778505, 5438.184272359148, 5544.251526043983, 5599.384067752554, 5599.664020158783, 5611.775528978836, 4285.485741710999, 4288.670512141231, 4340.609345158927, 4343.913121737563, 4376.912461750763, 3573.785572865623, 4199.4120823084795, 4305.892352691177, 4333.306670932651, 4339.654707040811, 3858.614138586147, 3859.652631263186, 4192.329227323628, 4220.6412454941365, 4264.765437900915, 4195.538490766014, 4206.369044643313, 4234.724742891717, 4244.427002354673, 4283.464566929134, 3427.8002699055382, 3483.4600636901323, 3691.7231207659534, 3692.3538380783257, 3696.4270538066376, 4537.795799189044, 5475.370332411784, 5500.22996780451, 5500.4499550045075, 5517.834464966018, 4217.662586993033, 4303.35449682547, 4828.5412885775795, 4898.779554920037, 4901.946081592475, 5400.972995135038, 5770.038187016435, 5916.585838991272, 5934.43622855828, 6011.939880601226, 3690.5202323698018, 4003.919921601552, 4013.6387725104883, 4022.7787471689044, 4035.899102522431, 5853.88292234153, 6024.367441418675, 6240.875182496325, 6293.87412251757, 6300.842478938017, 3341.031100980737, 3415.077928959167, 3925.1168487090176, 4004.478298246706, 4010.4184893190322, 3096.504559270551, 3450.6894379505966, 4049.8380064797666, 4094.508658960884, 4103.2409004334295, 4772.976135119302, 4796.880077998008, 4801.4958429365115, 4812.831550895702, 4840.080384726891, 4952.8761780955765, 5396.92218495874, 5577.103784713217, 5973.163757074013, 6154.015357542861, 1145.9171083326344, 1196.8803119687961, 1384.6469150366593, 1417.3196865504615, 1419.842828095099, 791.204839136664, 791.9071681080072, 846.4878748357278, 862.5816479007676, 865.6323009644747, 3854.5116669414224, 4055.8986025349013, 4176.644985176234, 4201.054762678373, 4696.9060618787435, 4612.60144724748, 4621.584057434847, 5124.718140502308, 5227.137522308776, 5232.947670523322, 4553.5357570914375, 4562.5665561771575, 4669.392978912788, 4769.618430525549, 4811.797050737316, 2918.7810914181523, 3018.079485756821, 3104.7270619046535, 3243.7187311012017, 3271.9836400817844, 5552.944470555323, 6373.1714877066615, 6393.369690728834, 6411.0, 6441.967790161019, 5722.655176033629, 6029.698515074228, 6240.500759939194, 6307.148534947815, 6331.556791024676, 6153.446189842935, 6325.557210995278, 6370.0, 6397.7440902364315, 6399.232092148972, 5160.793568257228, 5443.727813609383, 5808.572941964261, 5846.707664616667, 6209.689515524296, 4546.658735672918, 5978.850528736642, 6040.244969378828, 6045.637261764308, 6119.632822030692, 6027.969860150672, 6175.622385381571, 6227.688615569294, 6374.426301632875, 6380.393862583047, 3429.37652695693, 3465.0990742406652, 3843.404272337792, 4013.1973605279645, 4030.3954406838034, 5656.519195868397, 6168.784092556739, 6181.474574661204, 6181.660008699565, 6197.783077592263, 5256.658317209369, 5687.374388817144, 5777.91333129995, 5908.0, 5925.911111333419, 4707.59985401231, 5182.948170518397, 5296.390915044819, 5856.443637854397, 5969.641821490724, 5562.805301814417, 5767.769289228551, 5818.505427038749, 5836.533077353886, 5877.764889404376, 5647.305808257588, 5745.12204262113, 5911.793087241926, 5913.019138588621, 5986.371430999857, 4760.904781904412, 5663.688497132697, 5839.445252700986, 5862.032764593794, 5876.44173803488], "value": 4211.9063562729625, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.1103010207138, 708.3483195460176, 743.7582785594683, 774.6436639145993, 796.6016991504248, 15138.167400792952, 15721.528354149379, 16052.0, 16082.758758618631, 16083.59791005224, 14259.643508912282, 14387.136771793674, 14411.063280886736, 14417.062890912084, 14417.927910360433, 657.7204688007589, 687.3538873458932, 793.8729803231485, 818.664347617477, 824.381713714714, 841.949483031017, 842.1241908415271, 842.6081871929551, 846.4963346808639, 851.6423102297019, 840.9698119803253, 897.0939351255247, 898.3442087276293, 902.4359775140537, 904.669795524632, 814.2346194577078, 888.2405543260484, 891.1044400377643, 892.7500299916034, 893.8659201119884, 586.4253032028594, 642.2966851297822, 665.1452883045312, 666.0009985022466, 666.0708311904865, 14806.555803325917, 14822.703545929022, 14825.481108161162, 14858.0, 14888.330025148894, 14289.857101428957, 14406.063605865585, 14407.351669174808, 14429.92785036068, 14605.196714180716, 11716.472758725793, 11784.175107742461, 11828.763424731544, 11883.821742673867, 12093.818592721116, 3722.789251255885, 3777.829997650085, 3805.3911374179997, 3822.0827001519433, 3909.2376986487648, 116212.0, 118134.0, 119386.0, 121177.0, 122041.0, 10972.780544389068, 11098.44507774608, 11109.888901111048, 11143.888561114447, 11196.104311655048, 22685.0, 22770.31689049331, 22896.65655015176, 22933.0, 22958.885205573864, 7714.305712485903, 7762.301392874556, 7775.727849525352, 8024.317932975646, 8133.552654604054, 4339.7830108494445, 4342.240107981091, 4403.0, 4437.156940181355, 4523.592876641118, 2902.042326032404, 2954.5863579098946, 3194.2653189766484, 3337.4993750937556, 3428.919890234568, 532.2309263114762, 607.2955371768727, 618.4619381138349, 633.2306247908721, 652.4356431686557, 596.8776400837818, 614.4776939601393, 633.4742164003866, 654.5974225851068, 658.940695337422, 8964.551772411483, 9197.540122993823, 9476.147146756824, 9550.044995500395, 9942.950285248528, 538.7306346826587, 614.265952187141, 622.0544771946616, 677.867815775919, 681.7579759185517, 3869.8258578364043, 4327.545607711145, 4690.835820746258, 4809.098616268613, 4833.081714474239, 86166.0, 113596.72806543823, 135489.0, 151504.0, 153196.0, 102301.48849255707, 131140.3442982779, 131255.68744312433, 131719.3307163005, 132354.33822831017, 96369.62706305245, 97465.5126724376, 107923.0, 109814.45092774485, 111429.0, 8909.821803564022, 8954.462732236087, 9044.14080662336, 9254.4910029949, 9396.906030939603, 64855.35144648682, 65972.34027659662, 68954.65522672555, 70552.64723676349, 73427.0, 76448.61775691085, 82794.58602706948, 91755.0, 97451.0, 102679.0, 12772.297523636289, 13386.397612107285, 13520.526781562598, 14439.0, 14678.212636052624, 14847.703045938804, 14946.252687365806, 15009.414418218797, 15262.23688815577, 15712.685746285239, 13742.587722368346, 13954.23251721165, 13978.930105349815, 14013.579592612237, 14132.717345652823, 13961.301934903417, 13992.860071399156, 14225.217613030969, 14461.276936153361, 14657.780133297796, 13592.184468931895, 13727.0, 13817.955025813651, 13896.027278090436, 14197.858021419654, 10940.12479001662, 11310.038646715122, 11446.141539384746, 11685.707857303636, 12092.818607720716, 9357.344985850925, 10461.738456538647, 10937.945310273397, 11473.885261147281, 11650.76698466043], "value": 7787.208798424509, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1648.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 117.0, 117.0, 117.0, 126.0, 172.0, 163.0, 170.0, 174.0], "value": 139.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 112.0, 113.0, 151.0, 113.0, 117.0, 115.0, 116.0, 140.0, 115.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 277.0, 262.0, 248.0, 275.0, 244.0, 197.0, 198.0, 196.0, 197.0], "value": 228.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 59.0, 60.0, 84.0, 60.0, 57.0, 84.0, 83.0, 77.0, 62.0], "value": 70.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 89.0, 76.0, 92.0, 102.0, 124.0, 100.0, 72.0, 87.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 134.0, 88.0, 86.0, 88.0, 86.0, 89.0, 86.0, 91.0, 90.0], "value": 97.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 98.0, 98.0, 160.0, 175.0, 109.0, 98.0, 100.0, 117.0, 173.0], "value": 123.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 102.0, 142.0, 154.0, 112.0, 91.0, 94.0, 89.0, 98.0, 91.0], "value": 109.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 56.0, 57.0, 58.0, 58.0, 59.0, 70.0, 79.0, 57.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 82.0, 65.0, 68.0, 65.0, 74.0, 102.0, 102.0, 64.0, 97.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 73.0, 77.0, 76.0, 102.0, 109.0, 111.0, 110.0, 75.0, 110.0], "value": 93.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 152.0, 146.0, 151.0, 148.0, 160.0, 161.0, 156.0, 210.0, 147.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 211.0, 174.0, 176.0, 240.0, 222.0, 174.0, 237.0, 233.0, 172.0], "value": 201.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 71.0, 69.0, 68.0, 65.0, 65.0, 73.0, 91.0, 93.0], "value": 73.4, "unit": "score"}]}]} 07:52:45 INFO - Return code: 0 07:52:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:52:45 ERROR - # TBPL SUCCESS # 07:52:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:52:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:52:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:52:45 INFO - 2016-10-12 07:52:45.508 screenresolution[2235:21459] starting screenresolution argv=screenresolution get 07:52:45 INFO - 2016-10-12 07:52:45.510 screenresolution[2235:21459] Display 0: 1600x1200x32@60 07:52:45 INFO - 2016-10-12 07:52:45.516 screenresolution[2236:21462] starting screenresolution argv=screenresolution list 07:52:45 INFO - Available Modes on Display 0 07:52:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:52:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:52:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:52:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:52:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:52:45 INFO - Intel Iris: 07:52:45 INFO - Chipset Model: Intel Iris 07:52:45 INFO - Type: GPU 07:52:45 INFO - Bus: Built-In 07:52:45 INFO - VRAM (Dynamic, Max): 1536 MB 07:52:45 INFO - Vendor: Intel (0x8086) 07:52:45 INFO - Device ID: 0x0a2e 07:52:45 INFO - Revision ID: 0x0009 07:52:45 INFO - Displays: 07:52:45 INFO - Display: 07:52:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:52:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:52:45 INFO - Display Serial Number: 1600x1200 60 07:52:45 INFO - Main Display: Yes 07:52:45 INFO - Mirror: Off 07:52:45 INFO - Online: Yes 07:52:45 INFO - Rotation: Supported 07:52:45 INFO - Return code: 0 07:52:45 INFO - Running post-action listener: _resource_record_post_action 07:52:45 INFO - [mozharness: 2016-10-12 14:52:45.756890Z] Finished run-tests step (success) 07:52:45 INFO - Running post-run listener: _resource_record_post_run 07:52:45 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 503808; Write bytes: 706695168; Read time: 5; Write time: 2055 07:52:45 INFO - TinderboxPrint: CPU usage
54.7% 07:52:45 INFO - TinderboxPrint: I/O read bytes / time
503,808 / 5 07:52:45 INFO - TinderboxPrint: I/O write bytes / time
706,695,168 / 2,055 07:52:45 INFO - TinderboxPrint: CPU idle
2,198.5 (45.3%) 07:52:45 INFO - TinderboxPrint: CPU system
985.2 (20.3%) 07:52:45 INFO - TinderboxPrint: CPU user
1,666.1 (34.4%) 07:52:45 INFO - TinderboxPrint: Swap in / out
407,089,152 / 0 07:52:45 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 401390080; Write bytes: 394560000; Read time: 29709; Write time: 765 07:52:45 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 503808; Write bytes: 294956544; Read time: 5; Write time: 1250 07:52:46 INFO - Running post-run listener: _upload_blobber_files 07:52:46 INFO - Blob upload gear active. 07:52:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:52:46 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 07:52:46 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:52:46 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:52:46 INFO - (blobuploader) - INFO - Open directory for files ... 07:52:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:52:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:52:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:52:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:47 INFO - (blobuploader) - INFO - Done attempting. 07:52:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:52:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:52:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:52:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:48 INFO - (blobuploader) - INFO - Done attempting. 07:52:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:52:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:52:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:52:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:52:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:52:50 INFO - (blobuploader) - INFO - Done attempting. 07:52:50 INFO - (blobuploader) - INFO - Iteration through files over. 07:52:50 INFO - Return code: 0 07:52:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:52:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:52:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b8ce008a3ebd9d38f08f36e140ff9a620b174d48984373ac22719ac2b24a6fc440907460b47c74a6549e1430718268342e3a7e75a7c470b55b70135485485024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7a143570a851adb39fa90ddb6ad3a48635906e319fda4f6f84cd5a954584ca6b59023b3a974b8da7db00d582003e6bfc64d121dc28c33a3516d30bc3bff88801", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fe179f280f0b6a1986107b880a1942428aaa27ebaba42867fe53829e54e7ed27c18ef983bbc73e9c3601764c36166f8980723ffdb92448ec2ec01858ededfb8a"} 07:52:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:52:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:52:50 INFO - Contents: 07:52:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b8ce008a3ebd9d38f08f36e140ff9a620b174d48984373ac22719ac2b24a6fc440907460b47c74a6549e1430718268342e3a7e75a7c470b55b70135485485024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7a143570a851adb39fa90ddb6ad3a48635906e319fda4f6f84cd5a954584ca6b59023b3a974b8da7db00d582003e6bfc64d121dc28c33a3516d30bc3bff88801", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fe179f280f0b6a1986107b880a1942428aaa27ebaba42867fe53829e54e7ed27c18ef983bbc73e9c3601764c36166f8980723ffdb92448ec2ec01858ededfb8a"} 07:52:50 INFO - Running post-run listener: copy_logs_to_upload_dir 07:52:50 INFO - Copying logs to upload dir... 07:52:50 INFO - mkdir: /builds/slave/test/build/upload/logs 07:52:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.971969 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-12 07:52:50.784174) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 07:52:50.787646) ========= 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.4jBCMsXpTa/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.oQlTHkZACH/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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/Larch/sha512/b8ce008a3ebd9d38f08f36e140ff9a620b174d48984373ac22719ac2b24a6fc440907460b47c74a6549e1430718268342e3a7e75a7c470b55b70135485485024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7a143570a851adb39fa90ddb6ad3a48635906e319fda4f6f84cd5a954584ca6b59023b3a974b8da7db00d582003e6bfc64d121dc28c33a3516d30bc3bff88801", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fe179f280f0b6a1986107b880a1942428aaa27ebaba42867fe53829e54e7ed27c18ef983bbc73e9c3601764c36166f8980723ffdb92448ec2ec01858ededfb8a"} build_url:https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011205 build_url: 'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b8ce008a3ebd9d38f08f36e140ff9a620b174d48984373ac22719ac2b24a6fc440907460b47c74a6549e1430718268342e3a7e75a7c470b55b70135485485024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7a143570a851adb39fa90ddb6ad3a48635906e319fda4f6f84cd5a954584ca6b59023b3a974b8da7db00d582003e6bfc64d121dc28c33a3516d30bc3bff88801", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fe179f280f0b6a1986107b880a1942428aaa27ebaba42867fe53829e54e7ed27c18ef983bbc73e9c3601764c36166f8980723ffdb92448ec2ec01858ededfb8a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 07:52:50.819739) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 07:52:50.820049) ========= 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.4jBCMsXpTa/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.oQlTHkZACH/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.005333 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 07:52:50.868085) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 07:52:50.868394) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 07:52:50.868700) ========= ========= Total master_lag: 0.18 =========