builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0170 starttime: 1476794539.18 results: success (0) buildid: 20161018014548 builduid: a510ff49493d41bc90ecb890818b21e9 revision: dc89484d4b45abf442162e5ea2dd46f9de40197d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 05:42:19.175745) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 05:42:19.176183) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 05:42:19.176453) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 05:42:19.199200) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:42:19.199542) ========= 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.kskTAnsDIm/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.oba2rbehHu/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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-18 05:42:19-- 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% 151M=0s 2016-10-18 05:42:19 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124276 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:42:19.348405) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:42:19.348749) ========= 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.kskTAnsDIm/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.oba2rbehHu/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.023031 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:42:19.397799) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 05:42:19.398157) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev dc89484d4b45abf442162e5ea2dd46f9de40197d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev dc89484d4b45abf442162e5ea2dd46f9de40197d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kskTAnsDIm/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.oba2rbehHu/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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-18 05:42:19,474 truncating revision to first 12 chars 2016-10-18 05:42:19,474 Setting DEBUG logging. 2016-10-18 05:42:19,474 attempt 1/10 2016-10-18 05:42:19,474 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/dc89484d4b45?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 05:42:20,805 unpacking tar archive at: fx-team-dc89484d4b45/testing/mozharness/ program finished with exit code 0 elapsedTime=1.588987 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 05:42:21.011200) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 05:42:21.011474) ========= script_repo_revision: dc89484d4b45abf442162e5ea2dd46f9de40197d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 05:42:21.011831) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 05:42:21.015127) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 05:42:21.032966) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-18 05:42:21.033237) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kskTAnsDIm/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.oba2rbehHu/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 05:42:21 INFO - MultiFileLogger online at 20161018 05:42:21 in /builds/slave/test 05:42:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:42:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:42:21 INFO - {'append_to_log': False, 05:42:21 INFO - 'base_work_dir': '/builds/slave/test', 05:42:21 INFO - 'blob_upload_branch': 'Fx-Team', 05:42:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:42:21 INFO - 'branch': 'Fx-Team', 05:42:21 INFO - 'buildbot_json_path': 'buildprops.json', 05:42:21 INFO - 'config_files': ('talos/mac_config.py',), 05:42:21 INFO - 'default_actions': ('clobber', 05:42:21 INFO - 'read-buildbot-config', 05:42:21 INFO - 'download-and-extract', 05:42:21 INFO - 'populate-webroot', 05:42:21 INFO - 'create-virtualenv', 05:42:21 INFO - 'install', 05:42:21 INFO - 'run-tests'), 05:42:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:42:21 INFO - 'download_minidump_stackwalk': True, 05:42:21 INFO - 'download_symbols': 'ondemand', 05:42:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:42:21 INFO - 'tooltool.py': '/tools/tooltool.py', 05:42:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:42:21 INFO - '/tools/misc-python/virtualenv.py')}, 05:42:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:42:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:42:21 INFO - 'installer_path': 'installer.exe', 05:42:21 INFO - 'log_level': 'info', 05:42:21 INFO - 'log_name': 'talos', 05:42:21 INFO - 'log_to_console': True, 05:42:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:42:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:42:21 INFO - 'opt_config_files': (), 05:42:21 INFO - 'pip_index': False, 05:42:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:42:21 INFO - 'cmd': ('bash', 05:42:21 INFO - '-c', 05:42:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:42:21 INFO - 'enabled': True, 05:42:21 INFO - 'halt_on_failure': False, 05:42:21 INFO - 'name': 'check_screen_resolution'},), 05:42:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:42:21 INFO - 'cmd': ('bash', 05:42:21 INFO - '-c', 05:42:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:42:21 INFO - 'enabled': True, 05:42:21 INFO - 'halt_on_failure': False, 05:42:21 INFO - 'name': 'check_screen_resolution'},), 05:42:21 INFO - 'run_cmd_checks_enabled': True, 05:42:21 INFO - 'sps_profile': False, 05:42:21 INFO - 'sps_profile_interval': 0, 05:42:21 INFO - 'suite': 'dromaeojs-e10s', 05:42:21 INFO - 'system_bits': '32', 05:42:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:42:21 INFO - 'title': 't-yosemite-r7-0170', 05:42:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:42:21 INFO - 'use_talos_json': True, 05:42:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:42:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:42:21 INFO - 'work_dir': 'build'} 05:42:21 INFO - [mozharness: 2016-10-18 12:42:21.232238Z] Running clobber step. 05:42:21 INFO - Running pre-action listener: _resource_record_pre_action 05:42:21 INFO - Running main action method: clobber 05:42:21 INFO - rmtree: /builds/slave/test/build 05:42:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:42:21 INFO - Running post-action listener: _resource_record_post_action 05:42:21 INFO - [mozharness: 2016-10-18 12:42:21.878473Z] Finished clobber step (success) 05:42:21 INFO - [mozharness: 2016-10-18 12:42:21.878610Z] Running read-buildbot-config step. 05:42:21 INFO - Running pre-action listener: _resource_record_pre_action 05:42:21 INFO - Running main action method: read_buildbot_config 05:42:21 INFO - Using buildbot properties: 05:42:21 INFO - { 05:42:21 INFO - "project": "", 05:42:21 INFO - "product": "firefox", 05:42:21 INFO - "script_repo_revision": "production", 05:42:21 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:42:21 INFO - "repository": "", 05:42:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:42:21 INFO - "buildid": "20161018014548", 05:42:21 INFO - "pgo_build": "False", 05:42:21 INFO - "basedir": "/builds/slave/test", 05:42:21 INFO - "buildnumber": 44, 05:42:21 INFO - "slavename": "t-yosemite-r7-0170", 05:42:21 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:42:21 INFO - "platform": "yosemite_r7", 05:42:21 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 05:42:21 INFO - "branch": "fx-team", 05:42:21 INFO - "repo_path": "integration/fx-team", 05:42:21 INFO - "revision": "dc89484d4b45abf442162e5ea2dd46f9de40197d", 05:42:21 INFO - "stage_platform": "macosx64", 05:42:21 INFO - "builduid": "a510ff49493d41bc90ecb890818b21e9", 05:42:21 INFO - "slavebuilddir": "test" 05:42:21 INFO - } 05:42:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:42:21 INFO - Running post-action listener: _resource_record_post_action 05:42:21 INFO - [mozharness: 2016-10-18 12:42:21.882035Z] Finished read-buildbot-config step (success) 05:42:21 INFO - [mozharness: 2016-10-18 12:42:21.882160Z] Running download-and-extract step. 05:42:21 INFO - Running pre-action listener: _resource_record_pre_action 05:42:21 INFO - Running main action method: download_and_extract 05:42:21 INFO - mkdir: /builds/slave/test/build/tests 05:42:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:21 INFO - trying https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:42:21 INFO - Downloading https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/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 05:42:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/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 05:42:22 INFO - Downloaded 1199 bytes. 05:42:22 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:42:22 INFO - Using the following test package requirements: 05:42:22 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:42:22 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:42:22 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:42:22 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:42:22 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:42:22 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:42:22 INFO - u'jsshell-mac.zip'], 05:42:22 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:42:22 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:42:22 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:42:22 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:42:22 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:42:22 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:42:22 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:42:22 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:42:22 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:42:22 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:42:22 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:42:22 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:42:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:42:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:42:22 INFO - Fetch https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:42:23 INFO - Http code: 200 05:42:23 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 05:42:23 INFO - x-amz-cf-id: xmzjQuPvsLA-YrGWyqZJyTWSEE7JHroV-saldmvmDDMm9Bnc3z0iOg== 05:42:23 INFO - x-amz-version-id: kDgvfsD8fssO0YDAoCFx0t_UHW.zJIi3 05:42:23 INFO - Expected file size: 16483637 05:42:23 INFO - Obtained file size: 16483637 05:42:30 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 05:42:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:42:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:42:30 INFO - Fetch https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:42:31 INFO - Http code: 200 05:42:31 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 05:42:31 INFO - x-amz-cf-id: DhU0CW26kv0yXjdYjqY0ucoFRfzHR5JFzN2XnZevFveww4trn88OQQ== 05:42:31 INFO - x-amz-version-id: kDgvfsD8fssO0YDAoCFx0t_UHW.zJIi3 05:42:31 INFO - Expected file size: 16483637 05:42:31 INFO - Obtained file size: 16483637 05:42:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:42:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:42:38 INFO - Fetch https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:42:38 INFO - Http code: 200 05:42:38 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 05:42:38 INFO - x-amz-cf-id: PKCJZUXf1zpbE8VuHhb_q_FnpN2B5cIZd1nQQLHEcJkmdQBI74CplA== 05:42:38 INFO - x-amz-version-id: t0qyrCXphe5s91tHK7mOdK6qyEiDAELH 05:42:39 INFO - Expected file size: 14409028 05:42:39 INFO - Obtained file size: 14409028 05:42:39 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')]} 05:42:39 INFO - trying https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:42:39 INFO - Downloading https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:42:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/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 05:42:42 INFO - Downloaded 111608335 bytes. 05:42:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:42:42 INFO - mkdir: /builds/slave/test/properties 05:42:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:42:42 INFO - Writing to file /builds/slave/test/properties/build_url 05:42:42 INFO - Contents: 05:42:42 INFO - build_url:https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:42:43 INFO - Running post-action listener: _resource_record_post_action 05:42:43 INFO - Running post-action listener: set_extra_try_arguments 05:42:43 INFO - [mozharness: 2016-10-18 12:42:43.016495Z] Finished download-and-extract step (success) 05:42:43 INFO - [mozharness: 2016-10-18 12:42:43.016627Z] Running populate-webroot step. 05:42:43 INFO - Running pre-action listener: _resource_record_pre_action 05:42:43 INFO - Running main action method: populate_webroot 05:42:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:42:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:42:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:42:43 INFO - '--tppagecycles', 05:42:43 INFO - '7'], 05:42:43 INFO - 'tests': ['tsvgm']}}, 05:42:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:42:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:42:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:42:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:42:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:42:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:42:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:43 INFO - 'talos_options': ['--disable-e10s'], 05:42:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:42:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:42:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:43 INFO - 'talos_options': ['--disable-e10s'], 05:42:43 INFO - 'tests': ['damp', 'tps']}, 05:42:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:43 INFO - 'tests': ['damp', 'tps']}, 05:42:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:42:43 INFO - 'tests': ['dromaeo_dom']}, 05:42:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:42:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:42:43 INFO - 'tests': ['basic_compositor_video']}, 05:42:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:42:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:42:43 INFO - 'tests': ['a11yr', 05:42:43 INFO - 'ts_paint', 05:42:43 INFO - 'tpaint', 05:42:43 INFO - 'sessionrestore', 05:42:43 INFO - 'sessionrestore_no_auto_restore', 05:42:43 INFO - 'tabpaint']}, 05:42:43 INFO - 'other-e10s': {'tests': ['a11yr', 05:42:43 INFO - 'ts_paint', 05:42:43 INFO - 'tpaint', 05:42:43 INFO - 'sessionrestore', 05:42:43 INFO - 'sessionrestore_no_auto_restore', 05:42:43 INFO - 'tabpaint']}, 05:42:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:42:43 INFO - 'ts_paint', 05:42:43 INFO - 'tpaint', 05:42:43 INFO - 'sessionrestore', 05:42:43 INFO - 'sessionrestore_no_auto_restore', 05:42:43 INFO - 'tabpaint']}, 05:42:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:42:43 INFO - 'ts_paint', 05:42:43 INFO - 'tpaint', 05:42:43 INFO - 'sessionrestore', 05:42:43 INFO - 'sessionrestore_no_auto_restore', 05:42:43 INFO - 'tabpaint']}, 05:42:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:42:43 INFO - 'tests': ['a11yr', 05:42:43 INFO - 'ts_paint', 05:42:43 INFO - 'tpaint', 05:42:43 INFO - 'sessionrestore', 05:42:43 INFO - 'sessionrestore_no_auto_restore', 05:42:43 INFO - 'tabpaint']}, 05:42:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:42:43 INFO - 'tests': ['a11yr', 05:42:43 INFO - 'ts_paint', 05:42:43 INFO - 'tpaint', 05:42:43 INFO - 'sessionrestore', 05:42:43 INFO - 'sessionrestore_no_auto_restore', 05:42:43 INFO - 'tabpaint']}, 05:42:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:42:43 INFO - 'tests': ['tsvgx', 05:42:43 INFO - 'tsvgr_opacity', 05:42:43 INFO - 'tart', 05:42:43 INFO - 'tscrollx', 05:42:43 INFO - 'cart']}, 05:42:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:42:43 INFO - 'tsvgr_opacity', 05:42:43 INFO - 'tart', 05:42:43 INFO - 'tscrollx', 05:42:43 INFO - 'cart']}, 05:42:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:43 INFO - 'talos_options': ['--disable-e10s'], 05:42:43 INFO - 'tests': ['tp5o']}, 05:42:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:43 INFO - 'tests': ['tp5o']}, 05:42:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:43 INFO - 'talos_options': ['--disable-e10s', 05:42:43 INFO - '--xperf_path', 05:42:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:42:43 INFO - 'tests': ['tp5n']}, 05:42:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:42:43 INFO - 'talos_options': ['--xperf_path', 05:42:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:42:43 INFO - 'tests': ['tp5n']}}, 05:42:43 INFO - 'talos.zip': {'path': '', 05:42:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:42:43 INFO - Running post-action listener: _resource_record_post_action 05:42:43 INFO - [mozharness: 2016-10-18 12:42:43.036544Z] Finished populate-webroot step (success) 05:42:43 INFO - [mozharness: 2016-10-18 12:42:43.036650Z] Running create-virtualenv step. 05:42:43 INFO - Running pre-action listener: _resource_record_pre_action 05:42:43 INFO - Running main action method: create_virtualenv 05:42:43 INFO - Creating virtualenv /builds/slave/test/build/venv 05:42:43 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 05:42:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:42:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:42:43 INFO - Using real prefix '/tools/python27' 05:42:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:42:43 INFO - Installing distribute.............................................................................................................................................................................................done. 05:42:46 INFO - Installing pip.................done. 05:42:46 INFO - Return code: 0 05:42:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:42:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:42:46 INFO - Reading from file tmpfile_stdout 05:42:46 INFO - Output received: 05:42:46 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) 05:42:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:42:46 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')]} 05:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:46 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')]} 05:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:47 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 0x108ec4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4c14b7fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cd8d30>, '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 0x108afedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cd0b20>, '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.kskTAnsDIm/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.oba2rbehHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 05:42:47 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 05:42:47 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 05:42:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kskTAnsDIm/Render', 05:42:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:47 INFO - 'HOME': '/Users/cltbld', 05:42:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:47 INFO - 'LOGNAME': 'cltbld', 05:42:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:47 INFO - 'MOZ_NO_REMOTE': '1', 05:42:47 INFO - 'NO_EM_RESTART': '1', 05:42:47 INFO - 'PAGER': '/bin/cat', 05:42:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:47 INFO - 'PWD': '/builds/slave/test', 05:42:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:47 INFO - 'SHELL': '/bin/bash', 05:42:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oba2rbehHu/Listeners', 05:42:47 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:42:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:47 INFO - 'USER': 'cltbld', 05:42:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:47 INFO - 'XPC_FLAGS': '0x0', 05:42:47 INFO - 'XPC_SERVICE_NAME': '0', 05:42:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:47 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 05:42:47 INFO - Cleaning up... 05:42:47 INFO - Return code: 0 05:42:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:42:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:47 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 0x108ec4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4c14b7fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cd8d30>, '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 0x108afedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cd0b20>, '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.kskTAnsDIm/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.oba2rbehHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 05:42:47 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 05:42:47 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 05:42:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kskTAnsDIm/Render', 05:42:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:47 INFO - 'HOME': '/Users/cltbld', 05:42:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:47 INFO - 'LOGNAME': 'cltbld', 05:42:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:47 INFO - 'MOZ_NO_REMOTE': '1', 05:42:47 INFO - 'NO_EM_RESTART': '1', 05:42:47 INFO - 'PAGER': '/bin/cat', 05:42:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:47 INFO - 'PWD': '/builds/slave/test', 05:42:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:47 INFO - 'SHELL': '/bin/bash', 05:42:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oba2rbehHu/Listeners', 05:42:47 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:42:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:47 INFO - 'USER': 'cltbld', 05:42:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:47 INFO - 'XPC_FLAGS': '0x0', 05:42:47 INFO - 'XPC_SERVICE_NAME': '0', 05:42:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:47 INFO - Downloading/unpacking psutil>=3.1.1 05:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:42:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:42:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:42:50 INFO - Installing collected packages: psutil 05:42:50 INFO - Running setup.py install for psutil 05:42:51 INFO - building 'psutil._psutil_osx' extension 05:42:51 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 05:42:51 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 05:42:51 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 05:42:51 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 05:42:51 INFO - building 'psutil._psutil_posix' extension 05:42:51 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 05:42:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:42:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:42:51 INFO - ^ 05:42:51 INFO - 1 warning generated. 05:42:51 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 05:42:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:42:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:42:51 INFO - Successfully installed psutil 05:42:51 INFO - Cleaning up... 05:42:51 INFO - Return code: 0 05:42:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:42:51 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')]} 05:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:51 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')]} 05:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:51 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 0x108ec4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4c14b7fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cd8d30>, '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 0x108afedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cd0b20>, '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.kskTAnsDIm/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.oba2rbehHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 05:42:51 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 05:42:51 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 05:42:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kskTAnsDIm/Render', 05:42:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:51 INFO - 'HOME': '/Users/cltbld', 05:42:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:51 INFO - 'LOGNAME': 'cltbld', 05:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:51 INFO - 'MOZ_NO_REMOTE': '1', 05:42:51 INFO - 'NO_EM_RESTART': '1', 05:42:51 INFO - 'PAGER': '/bin/cat', 05:42:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:51 INFO - 'PWD': '/builds/slave/test', 05:42:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:51 INFO - 'SHELL': '/bin/bash', 05:42:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oba2rbehHu/Listeners', 05:42:51 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:42:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:51 INFO - 'USER': 'cltbld', 05:42:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:51 INFO - 'XPC_FLAGS': '0x0', 05:42:51 INFO - 'XPC_SERVICE_NAME': '0', 05:42:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:42:52 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 05:42:52 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 05:42:52 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 05:42:52 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 05:42:52 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 05:42:52 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 05:42:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:42:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:42:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:42:54 INFO - Installing collected packages: mozsystemmonitor 05:42:54 INFO - Running setup.py install for mozsystemmonitor 05:42:54 INFO - Successfully installed mozsystemmonitor 05:42:54 INFO - Cleaning up... 05:42:54 INFO - Return code: 0 05:42:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:42:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:54 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 0x108ec4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4c14b7fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cd8d30>, '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 0x108afedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cd0b20>, '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.kskTAnsDIm/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.oba2rbehHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 05:42:54 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 05:42:54 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 05:42:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kskTAnsDIm/Render', 05:42:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:54 INFO - 'HOME': '/Users/cltbld', 05:42:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:54 INFO - 'LOGNAME': 'cltbld', 05:42:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:54 INFO - 'MOZ_NO_REMOTE': '1', 05:42:54 INFO - 'NO_EM_RESTART': '1', 05:42:54 INFO - 'PAGER': '/bin/cat', 05:42:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:54 INFO - 'PWD': '/builds/slave/test', 05:42:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:54 INFO - 'SHELL': '/bin/bash', 05:42:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oba2rbehHu/Listeners', 05:42:54 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:42:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:54 INFO - 'USER': 'cltbld', 05:42:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:54 INFO - 'XPC_FLAGS': '0x0', 05:42:54 INFO - 'XPC_SERVICE_NAME': '0', 05:42:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:55 INFO - Downloading/unpacking jsonschema==2.5.1 05:42:55 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 05:42:55 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 05:42:55 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 05:42:55 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 05:42:55 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 05:42:55 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 05:42:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:42:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:42:57 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 05:42:57 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 05:42:57 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 05:42:57 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 05:42:57 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 05:42:57 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 05:42:58 INFO - Downloading functools32-3.2.3-2.tar.gz 05:42:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:42:58 INFO - warning: no files found matching '*.txt' 05:42:58 INFO - no previously-included directories found matching 'build' 05:42:58 INFO - no previously-included directories found matching 'dist' 05:42:58 INFO - no previously-included directories found matching '.git*' 05:42:58 INFO - Installing collected packages: jsonschema, functools32 05:42:58 INFO - Running setup.py install for functools32 05:42:58 INFO - warning: no files found matching '*.txt' 05:42:58 INFO - no previously-included directories found matching 'build' 05:42:58 INFO - no previously-included directories found matching 'dist' 05:42:58 INFO - no previously-included directories found matching '.git*' 05:42:58 INFO - Successfully installed jsonschema functools32 05:42:58 INFO - Cleaning up... 05:42:58 INFO - Return code: 0 05:42:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:42:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:58 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 0x108ec4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4c14b7fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cd8d30>, '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 0x108afedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cd0b20>, '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.kskTAnsDIm/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.oba2rbehHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 05:42:58 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 05:42:58 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 05:42:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kskTAnsDIm/Render', 05:42:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:58 INFO - 'HOME': '/Users/cltbld', 05:42:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:58 INFO - 'LOGNAME': 'cltbld', 05:42:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:58 INFO - 'MOZ_NO_REMOTE': '1', 05:42:58 INFO - 'NO_EM_RESTART': '1', 05:42:58 INFO - 'PAGER': '/bin/cat', 05:42:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:58 INFO - 'PWD': '/builds/slave/test', 05:42:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:58 INFO - 'SHELL': '/bin/bash', 05:42:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oba2rbehHu/Listeners', 05:42:58 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:42:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:58 INFO - 'USER': 'cltbld', 05:42:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:58 INFO - 'XPC_FLAGS': '0x0', 05:42:58 INFO - 'XPC_SERVICE_NAME': '0', 05:42:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:42:58 INFO - Cleaning up... 05:42:58 INFO - Return code: 0 05:42:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:42:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:58 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 0x108ec4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4c14b7fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cd8d30>, '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 0x108afedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cd0b20>, '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.kskTAnsDIm/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.oba2rbehHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 05:42:58 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 05:42:58 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 05:42:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kskTAnsDIm/Render', 05:42:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:58 INFO - 'HOME': '/Users/cltbld', 05:42:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:58 INFO - 'LOGNAME': 'cltbld', 05:42:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:58 INFO - 'MOZ_NO_REMOTE': '1', 05:42:58 INFO - 'NO_EM_RESTART': '1', 05:42:58 INFO - 'PAGER': '/bin/cat', 05:42:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:58 INFO - 'PWD': '/builds/slave/test', 05:42:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:58 INFO - 'SHELL': '/bin/bash', 05:42:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oba2rbehHu/Listeners', 05:42:58 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:42:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:58 INFO - 'USER': 'cltbld', 05:42:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:58 INFO - 'XPC_FLAGS': '0x0', 05:42:58 INFO - 'XPC_SERVICE_NAME': '0', 05:42:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:58 INFO - Downloading/unpacking blobuploader==1.2.4 05:42:58 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 05:42:58 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 05:42:58 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 05:42:58 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 05:42:58 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 05:42:58 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 05:43:01 INFO - Downloading blobuploader-1.2.4.tar.gz 05:43:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:43:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:43:01 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 05:43:01 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 05:43:01 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 05:43:01 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 05:43:01 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 05:43:01 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 05:43:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:43:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:43:02 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 05:43:02 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 05:43:02 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 05:43:02 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 05:43:02 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 05:43:02 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 05:43:02 INFO - Downloading docopt-0.6.1.tar.gz 05:43:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:43:02 INFO - Installing collected packages: blobuploader, requests, docopt 05:43:02 INFO - Running setup.py install for blobuploader 05:43:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:43:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:43:02 INFO - Running setup.py install for requests 05:43:03 INFO - Running setup.py install for docopt 05:43:03 INFO - Successfully installed blobuploader requests docopt 05:43:03 INFO - Cleaning up... 05:43:03 INFO - Return code: 0 05:43:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:43:03 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')]} 05:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:03 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')]} 05:43:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:03 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 0x108ec4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4c14b7fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cd8d30>, '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 0x108afedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cd0b20>, '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.kskTAnsDIm/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.oba2rbehHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 05:43:03 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 05:43:03 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 05:43:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kskTAnsDIm/Render', 05:43:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:03 INFO - 'HOME': '/Users/cltbld', 05:43:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:03 INFO - 'LOGNAME': 'cltbld', 05:43:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:03 INFO - 'MOZ_NO_REMOTE': '1', 05:43:03 INFO - 'NO_EM_RESTART': '1', 05:43:03 INFO - 'PAGER': '/bin/cat', 05:43:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:03 INFO - 'PWD': '/builds/slave/test', 05:43:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:03 INFO - 'SHELL': '/bin/bash', 05:43:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oba2rbehHu/Listeners', 05:43:03 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:43:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:03 INFO - 'USER': 'cltbld', 05:43:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:03 INFO - 'XPC_FLAGS': '0x0', 05:43:03 INFO - 'XPC_SERVICE_NAME': '0', 05:43:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:43:03 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-RJEoXM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:43:04 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-LLpbss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:43:04 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-EfaPHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:43:04 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-I5gtno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:43:04 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-9Puy5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:43:04 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-QRKQ9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:43:04 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-awd3Vn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:43:04 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-iSSlmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:43:04 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-8kad6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:43:04 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-G32o3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:43:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:43:04 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-qSw_R2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:43:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-y0rFzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:43:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-kRfp9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:43:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-luqR4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-r3v4gV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:43:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-2YdGQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:43:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:43:05 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-zJCPAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:43:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:43:05 INFO - Running setup.py install for manifestparser 05:43:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:43:05 INFO - Running setup.py install for mozcrash 05:43:06 INFO - Running setup.py install for mozdebug 05:43:06 INFO - Running setup.py install for mozdevice 05:43:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Running setup.py install for mozfile 05:43:06 INFO - Running setup.py install for mozhttpd 05:43:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Running setup.py install for mozinfo 05:43:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Running setup.py install for mozInstall 05:43:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:43:06 INFO - Running setup.py install for mozleak 05:43:07 INFO - Running setup.py install for mozlog 05:43:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:43:07 INFO - Running setup.py install for moznetwork 05:43:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:43:07 INFO - Running setup.py install for mozprocess 05:43:07 INFO - Running setup.py install for mozprofile 05:43:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:43:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:43:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:43:07 INFO - Running setup.py install for mozrunner 05:43:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:43:07 INFO - Running setup.py install for mozscreenshot 05:43:08 INFO - Running setup.py install for moztest 05:43:08 INFO - Running setup.py install for mozversion 05:43:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:43:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:43:08 INFO - Cleaning up... 05:43:08 INFO - Return code: 0 05:43:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:09 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 0x108ec4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4c14b7fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cd8d30>, '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 0x108afedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cd0b20>, '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.kskTAnsDIm/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.oba2rbehHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 05:43:09 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 05:43:09 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 05:43:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kskTAnsDIm/Render', 05:43:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:09 INFO - 'HOME': '/Users/cltbld', 05:43:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:09 INFO - 'LOGNAME': 'cltbld', 05:43:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:09 INFO - 'MOZ_NO_REMOTE': '1', 05:43:09 INFO - 'NO_EM_RESTART': '1', 05:43:09 INFO - 'PAGER': '/bin/cat', 05:43:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:09 INFO - 'PWD': '/builds/slave/test', 05:43:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:09 INFO - 'SHELL': '/bin/bash', 05:43:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oba2rbehHu/Listeners', 05:43:09 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:43:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:09 INFO - 'USER': 'cltbld', 05:43:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:09 INFO - 'XPC_FLAGS': '0x0', 05:43:09 INFO - 'XPC_SERVICE_NAME': '0', 05:43:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:43:09 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-j_mpfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:43:09 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)) 05:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:43:09 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-ReaiG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:43:09 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)) 05:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:43:09 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-OsmlSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:43:09 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)) 05:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:43:09 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-yCpG3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:43:09 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)) 05:43:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:43:09 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-NGRzww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:43:10 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)) 05:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:43:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-w4bsYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:43:10 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)) 05:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:43:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-_b_vLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:43:10 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)) 05:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:43:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-tZrg3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:43:10 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)) 05:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:43:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-YQe6wE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:43:10 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)) 05:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:43:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-a_LPsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:43:10 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)) 05:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:43:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-JUGiE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:43:10 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)) 05:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:43:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-OMmiuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:43:10 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)) 05:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:43:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-mUn77A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:43:10 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)) 05:43:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:43:10 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-SRGzMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:43:11 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)) 05:43:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-llM1TB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:43:11 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)) 05:43:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:43:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-oxI36e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:43:11 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)) 05:43:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:43:11 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-VKqEod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:43:11 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)) 05:43:11 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)) 05:43:11 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)) 05:43:11 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)) 05:43:11 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)) 05:43:11 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)) 05:43:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:43:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:14 INFO - Downloading blessings-1.6.tar.gz 05:43:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:43:14 INFO - Installing collected packages: blessings 05:43:14 INFO - Running setup.py install for blessings 05:43:14 INFO - Successfully installed blessings 05:43:14 INFO - Cleaning up... 05:43:14 INFO - Return code: 0 05:43:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:43:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:43:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:43:14 INFO - Reading from file tmpfile_stdout 05:43:14 INFO - Current package versions: 05:43:14 INFO - blessings == 1.6 05:43:14 INFO - blobuploader == 1.2.4 05:43:14 INFO - docopt == 0.6.1 05:43:14 INFO - functools32 == 3.2.3-2 05:43:14 INFO - jsonschema == 2.5.1 05:43:14 INFO - manifestparser == 1.1 05:43:14 INFO - mozInstall == 1.12 05:43:14 INFO - mozcrash == 0.17 05:43:14 INFO - mozdebug == 0.1 05:43:14 INFO - mozdevice == 0.48 05:43:14 INFO - mozfile == 1.2 05:43:14 INFO - mozhttpd == 0.7 05:43:14 INFO - mozinfo == 0.9 05:43:14 INFO - mozleak == 0.1 05:43:14 INFO - mozlog == 3.4 05:43:14 INFO - moznetwork == 0.27 05:43:14 INFO - mozprocess == 0.23 05:43:14 INFO - mozprofile == 0.28 05:43:14 INFO - mozrunner == 6.12 05:43:14 INFO - mozscreenshot == 0.1 05:43:14 INFO - mozsystemmonitor == 0.3 05:43:14 INFO - moztest == 0.7 05:43:14 INFO - mozversion == 1.4 05:43:14 INFO - psutil == 3.1.1 05:43:14 INFO - requests == 1.2.3 05:43:14 INFO - wsgiref == 0.1.2 05:43:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:43:14 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')]} 05:43:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:14 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')]} 05:43:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:14 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 0x108ec4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4c14b7fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cd8d30>, '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 0x108afedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cd0b20>, '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.kskTAnsDIm/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.oba2rbehHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 05:43:14 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 05:43:14 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 05:43:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kskTAnsDIm/Render', 05:43:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:14 INFO - 'HOME': '/Users/cltbld', 05:43:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:14 INFO - 'LOGNAME': 'cltbld', 05:43:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:14 INFO - 'MOZ_NO_REMOTE': '1', 05:43:14 INFO - 'NO_EM_RESTART': '1', 05:43:14 INFO - 'PAGER': '/bin/cat', 05:43:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:14 INFO - 'PWD': '/builds/slave/test', 05:43:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:14 INFO - 'SHELL': '/bin/bash', 05:43:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oba2rbehHu/Listeners', 05:43:14 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:43:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:14 INFO - 'USER': 'cltbld', 05:43:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:14 INFO - 'XPC_FLAGS': '0x0', 05:43:14 INFO - 'XPC_SERVICE_NAME': '0', 05:43:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:15 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)) 05:43:15 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)) 05:43:15 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)) 05:43:15 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)) 05:43:15 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)) 05:43:15 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)) 05:43:15 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)) 05:43:15 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)) 05:43:15 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)) 05:43:15 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)) 05:43:15 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)) 05:43:15 INFO - Cleaning up... 05:43:15 INFO - Return code: 0 05:43:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:43: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')]} 05:43:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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')]} 05:43:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108ec4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108e3a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4c14b7fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cd8d30>, '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 0x108afedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cd0b20>, '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.kskTAnsDIm/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.oba2rbehHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 05:43: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', 'jsonschema'] in /builds/slave/test/build 05:43: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 jsonschema 05:43:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kskTAnsDIm/Render', 05:43:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:15 INFO - 'HOME': '/Users/cltbld', 05:43:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:15 INFO - 'LOGNAME': 'cltbld', 05:43:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:15 INFO - 'MOZ_NO_REMOTE': '1', 05:43:15 INFO - 'NO_EM_RESTART': '1', 05:43:15 INFO - 'PAGER': '/bin/cat', 05:43:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:15 INFO - 'PWD': '/builds/slave/test', 05:43:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:15 INFO - 'SHELL': '/bin/bash', 05:43:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oba2rbehHu/Listeners', 05:43:15 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:43:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:15 INFO - 'USER': 'cltbld', 05:43:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:15 INFO - 'XPC_FLAGS': '0x0', 05:43:15 INFO - 'XPC_SERVICE_NAME': '0', 05:43:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:43:15 INFO - Cleaning up... 05:43:15 INFO - Return code: 0 05:43:15 INFO - Running post-action listener: _resource_record_post_action 05:43:15 INFO - Running post-action listener: _start_resource_monitoring 05:43:15 INFO - Starting resource monitoring. 05:43:15 INFO - [mozharness: 2016-10-18 12:43:15.431416Z] Finished create-virtualenv step (success) 05:43:15 INFO - [mozharness: 2016-10-18 12:43:15.432256Z] Running install step. 05:43:15 INFO - Running pre-action listener: _resource_record_pre_action 05:43:15 INFO - Running main action method: install 05:43:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:43:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:43:15 INFO - Reading from file tmpfile_stdout 05:43:15 INFO - Detecting whether we're running mozinstall >=1.0... 05:43:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:43:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:43:15 INFO - Reading from file tmpfile_stdout 05:43:15 INFO - Output received: 05:43:15 INFO - Usage: mozinstall [options] installer 05:43:15 INFO - Options: 05:43:15 INFO - -h, --help show this help message and exit 05:43:15 INFO - -d DEST, --destination=DEST 05:43:15 INFO - Directory to install application into. [default: 05:43:15 INFO - "/builds/slave/test"] 05:43:15 INFO - --app=APP Application being installed. [default: firefox] 05:43:15 INFO - mkdir: /builds/slave/test/build/application 05:43:15 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'] 05:43:15 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 05:43:49 INFO - Reading from file tmpfile_stdout 05:43:49 INFO - Output received: 05:43:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:43:49 INFO - Running post-action listener: _resource_record_post_action 05:43:49 INFO - [mozharness: 2016-10-18 12:43:49.913228Z] Finished install step (success) 05:43:49 INFO - [mozharness: 2016-10-18 12:43:49.913368Z] Running run-tests step. 05:43:49 INFO - Running pre-action listener: _resource_record_pre_action 05:43:49 INFO - Running main action method: run_tests 05:43:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:43:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:43:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:43:49 INFO - 2016-10-18 05:43:49.928 screenresolution[6525:113427] starting screenresolution argv=screenresolution get 05:43:49 INFO - 2016-10-18 05:43:49.930 screenresolution[6525:113427] Display 0: 1600x1200x32@60 05:43:49 INFO - 2016-10-18 05:43:49.936 screenresolution[6526:113433] starting screenresolution argv=screenresolution list 05:43:49 INFO - Available Modes on Display 0 05:43:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:43:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:43:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:43:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:43:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:43:50 INFO - Intel Iris: 05:43:50 INFO - Chipset Model: Intel Iris 05:43:50 INFO - Type: GPU 05:43:50 INFO - Bus: Built-In 05:43:50 INFO - VRAM (Dynamic, Max): 1536 MB 05:43:50 INFO - Vendor: Intel (0x8086) 05:43:50 INFO - Device ID: 0x0a2e 05:43:50 INFO - Revision ID: 0x0009 05:43:50 INFO - Displays: 05:43:50 INFO - Display: 05:43:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:43:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:43:50 INFO - Display Serial Number: 1600x1200 60 05:43:50 INFO - Main Display: Yes 05:43:50 INFO - Mirror: Off 05:43:50 INFO - Online: Yes 05:43:50 INFO - Rotation: Supported 05:43:50 INFO - Return code: 0 05:43:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:43:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:43:50 INFO - Python 2.7.3 05:43:50 INFO - Return code: 0 05:43:50 INFO - grabbing minidump binary from tooltool 05:43:50 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')]} 05:43:50 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 0x108cd8d30>, '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 0x108afedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108cd0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:43:50 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 05:43:50 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 05:43:50 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 05:43:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:43:50 INFO - Return code: 0 05:43:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:43:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:43:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:43:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:43:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:43:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--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 05:43:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0170 --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 05:43:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kskTAnsDIm/Render', 05:43:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:43:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:43:50 INFO - 'HOME': '/Users/cltbld', 05:43:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:43:50 INFO - 'LOGNAME': 'cltbld', 05:43:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:43:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:43:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:50 INFO - 'MOZ_NO_REMOTE': '1', 05:43:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:43:50 INFO - 'NO_EM_RESTART': '1', 05:43:50 INFO - 'PAGER': '/bin/cat', 05:43:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:43:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:43:50 INFO - 'PWD': '/builds/slave/test', 05:43:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:43:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:43:50 INFO - 'SHELL': '/bin/bash', 05:43:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oba2rbehHu/Listeners', 05:43:50 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 05:43:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:43:50 INFO - 'USER': 'cltbld', 05:43:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:43:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:43:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:50 INFO - 'XPC_FLAGS': '0x0', 05:43:50 INFO - 'XPC_SERVICE_NAME': '0', 05:43:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:43:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--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 05:43:50 INFO - mozversion application_buildid: 20161018014548 05:43:50 INFO - mozversion application_changeset: dc89484d4b45abf442162e5ea2dd46f9de40197d 05:43:50 INFO - mozversion application_display_name: Nightly 05:43:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:43:50 INFO - mozversion application_name: Firefox 05:43:50 INFO - mozversion application_remotingname: firefox 05:43:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:43:50 INFO - mozversion application_vendor: Mozilla 05:43:50 INFO - mozversion application_version: 52.0a1 05:43:50 INFO - mozversion platform_buildid: 20161018014548 05:43:50 INFO - mozversion platform_changeset: dc89484d4b45abf442162e5ea2dd46f9de40197d 05:43:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:43:50 INFO - mozversion platform_version: 52.0a1 05:43:50 INFO - using testdate: 1476794630 05:43:50 INFO - actual date: 1476794630 05:43:50 INFO - starting webserver on 'localhost:58316' 05:43:50 INFO - SUITE-START | Running 2 tests 05:43:50 INFO - TEST-START | dromaeo_css 05:43:50 INFO - operating with platform_type : mac_ 05:43:50 INFO - Initialising browser for dromaeo_css test... 05:43:50 INFO - TEST-INFO | started process 6536 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmprbUKop/profile http://localhost:58316/getInfo.html) 05:43:53 INFO - PROCESS | 6536 | 2016-10-18 05:43:53.536 plugin-container[6538:113591] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 05:43:53 INFO - PROCESS | 6536 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:43:53 INFO - PROCESS | 6536 | 2016-10-18 05:43:53.539 plugin-container[6538:113591] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x901f, name = 'com.apple.CFPasteboardClient' 05:43:53 INFO - PROCESS | 6536 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:43:53 INFO - PROCESS | 6536 | 2016-10-18 05:43:53.539 plugin-container[6538:113591] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:43:53 INFO - PROCESS | 6536 | __metrics Screen width/height:1600/1200 05:43:53 INFO - PROCESS | 6536 | colorDepth:24 05:43:53 INFO - PROCESS | 6536 | Browser inner width/height: 1024/689 05:43:53 INFO - PROCESS | 6536 | __metrics 05:43:53 ERROR - PROCESS | 6536 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 05:43:54 INFO - TEST-INFO | 6536: exit 0 05:43:54 INFO - Browser initialized. 05:43:54 INFO - Running cycle 1/1 for dromaeo_css test... 05:43:54 INFO - TEST-INFO | started process 6540 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmprbUKop/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:43:56 INFO - PROCESS | 6540 | 2016-10-18 05:43:56.805 plugin-container[6541:113713] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 05:43:56 INFO - PROCESS | 6540 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:43:56 INFO - PROCESS | 6540 | 2016-10-18 05:43:56.806 plugin-container[6541:113713] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 05:43:56 INFO - PROCESS | 6540 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:43:56 INFO - PROCESS | 6540 | 2016-10-18 05:43:56.806 plugin-container[6541:113713] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:44:06 INFO - PROCESS | 6540 | RSS: Main: 236748800 05:44:06 INFO - PROCESS | 6540 | 05:44:56 INFO - PROCESS | 6540 | 1476794696116 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] 05:47:09 INFO - PROCESS | 6540 | Cycle 1(1): loaded http://localhost:58316/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58316/tests/dromaeo/cssquery-ext.html) 05:47:11 INFO - PROCESS | 6540 | RSS: Main: 249237504 05:47:11 INFO - PROCESS | 6540 | 05:50:13 INFO - PROCESS | 6540 | Cycle 1(1): loaded http://localhost:58316/tests/dromaeo/cssquery-ext.html (next: http://localhost:58316/tests/dromaeo/cssquery-jquery.html) 05:50:14 INFO - PROCESS | 6540 | RSS: Main: 264736768 05:50:14 INFO - PROCESS | 6540 | 05:53:16 INFO - PROCESS | 6540 | Cycle 1(1): loaded http://localhost:58316/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58316/tests/dromaeo/cssquery-mootools.html) 05:53:16 INFO - PROCESS | 6540 | RSS: Main: 269524992 05:53:16 INFO - PROCESS | 6540 | 05:56:18 INFO - PROCESS | 6540 | Cycle 1(1): loaded http://localhost:58316/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58316/tests/dromaeo/cssquery-prototype.html) 05:56:19 INFO - PROCESS | 6540 | RSS: Main: 269234176 05:56:19 INFO - PROCESS | 6540 | 05:59:22 INFO - PROCESS | 6540 | Cycle 1(1): loaded http://localhost:58316/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58316/tests/dromaeo/cssquery-yui.html) 05:59:22 INFO - PROCESS | 6540 | RSS: Main: 274620416 05:59:22 INFO - PROCESS | 6540 | 06:02:25 INFO - PROCESS | 6540 | Cycle 1(1): loaded http://localhost:58316/tests/dromaeo/cssquery-yui.html (next: http://localhost:58316/tests/dromaeo/cssquery-dojo.html) 06:02:25 INFO - PROCESS | 6540 | RSS: Main: 277229568 06:02:25 INFO - PROCESS | 6540 | 06:02:25 INFO - PROCESS | 6540 | __start_tp_report 06:02:25 INFO - PROCESS | 6540 | _x_x_mozilla_page_load 06:02:25 INFO - PROCESS | 6540 | _x_x_mozilla_page_load_details 06:02:25 INFO - PROCESS | 6540 | |i|pagename|runs| 06:02:25 INFO - PROCESS | 6540 | |0;dojo.html;3324.9002529924096;3364.478505831596;3377.54403155574;3387.728981681466;3403.3703764803527;5648.604597678164;5765.740541675625;5784.363719990798;5785.074388097905;5795.21764561784;5710.371859095496;5843.503302219306;5856.9750293698535;5873.148393482942;5897.528197744181;5169.147090730044;5225.320708307915;5300.893982120355;5332.986732520828;5359.490848369418;5228.73856307185;5311.123664595337;5348.812791552277;5360.356757189143;5388.191771234307;4064.593540645941;4945.999999999982;5144.588432925357;5166.405863325728;5179.922301165486;5241.9213711794355;5266.104762190437;5284.313039304866;5301.628885977983;5307.230451584499;5173.767180476888;5219.8434046978655;5225.242339860699;5232.372115346145;5241.794387290906;5623;5632.915506267409;5696.829095127153;5712.971435142797;5794.101914203306;6223.906641400383;6255.812325630193;6303.653299068549;6304.7793327233785;6317.873642527124;5329.893402131975;6230.722701846156;6259.812205633838;6267.874642507124;6273.215848018997;5746;5799.927013502515;5958.404159584094;5962.970185149046;6010.789622363196;6197;6228.408301211378;6264;6321.747130114836;6332;6170.259568851767;6190.535709821781;6218.098375735493;6219.533534984895;6241.438270555672;4967.503249675004;4985.476524964826;6274.749010039639;6303.527235457267;6370.55406121567;5061.342025536656;5167.534921856975;5808.128780682931;6216.44052035319;6222.62664240147;6236.594621349688;6266.310705822356;6301.52738544611;6306.306306306303;6308.968455157695;4958.553730164303;4977.875553111201;4982;4984.825531106467;5011.949880501221;3451.3442445935193;4431.180231657154;4448.911021779546;4474.843380481668;4492.910141797146;4991.775370108354;5728.1407788831175;5884.882302353929;5890.410958904161;5951.464368206882;5164.225366195101;6220.066989951453;6260.530460215502;6273.7176827042895;6286.937130628727;5687.8009269676195;5701.971490142523;5863.29640443141;5896.705164741831;5905.40945905406;5398.082326004589;5411.134218525017;5439.673619582838;5445.673259604437;5505.953868765002;5304.071787437106;6249.9375006251175;6331.145295385156;6345.93654063472;6383.425491705769;5879.617824841372;6147.770445910746;6259.906101408574;6273.780417685359;6273.780417685359;6200.224971878515;6233.968830156002;6234.563580549318;6287.30839607652;6404.679766011774;5232.529072383504;6242.500599951902;6247.656378899204;6269.905951410824;6271.341509141657;6174.413430724254;6227.470664993526;6258.12386265914;6262.780802671885;6267.905981410191;4882.487338829372;6079.088136779518;6081.361457046946;6101.459346768484;6144.662043587646;6098.664573448503;6154.630212548961;6177.289611694712;6179.81460556184;6198.101275315144;4836.250381190921;5846.5615078870105;5918.201042859159;6016.939830601813;6024.4017593122835;6084.574079814371;6096.786612468542;6119.837693858789;6206.627602343874;6211.3478084800445;5921.644701317935;6056.424639659225;6065.505059577285;6086.330503644507;6112.480439162721 06:02:25 INFO - PROCESS | 6540 | |1;ext.html;462.7478024476658;464.2015732939343;491.8171796828626;519.3793416866844;546.2107255016498;38298.61701382989;40499.59500404995;41287.79356103219;41331;41435.02779666437;41554;41664;41881.16237675244;42061.57938420615;42288.788556057174;9420.528973551312;9572.234221262317;9601.423914565148;9620.42277463351;9626.470544120071;9438.103380178873;9488.762780930463;9570.617175312978;9571.95214023929;9573.329866909318;9554.426734395924;9558.952205238964;9574.856377154349;9592.952035239814;9624.422534647909;9487.667931622394;9551.665691700826;9572.18636415902;9575.850897892262;9583.137517623447;9462;9520.666776662852;9544.761380965461;9563.426194428357;9599.712008639752;39730.60269397298;39927;39947.600523994675;40112.19775604501;40202.39696404556;3367.6295607483157;3399.507071474647;3507.2459421224557;3567.4648802679544;4118.9588104118875;29931.70068299311;30229.395412091853;31007.534886976715;31049.689503104906;31533;3232.8868489602987;3330.6835850594157;3338.7162091222276;3381.8140002299865;3385.0691059958463;307512;332886;344379;354942.22528887447;355246;57775.066991961234;58031.70984145137;58213.41786582165;58226;58232.41767582355;50083.74958125186;53020;60716;60892.39107608956;61090;15042.172680502677;15242.847571524364;15506.534803955674;15536.84463155354;15589.454369097028;11769.470373833197;12181.817272740916;12182.147249692436;12201.694957625952;12221.69445763863;12689.87310126887;12950.222986620833;14879.553613391616;15670.608234794221;15766.68466630661;6822.420094292041;6977.232504424509;6998.440124790005;7005.299470052954;7087.751928682472;792.7978365516872;833.2167762303416;999.7600575861886;1019.5259204469809;1036.549101141006;27869.58195627067;29694;29880.55179172314;30137.09588712342;30278.84860575683;1026.0252759878147;1036.4817591204398;1037.7094413564214;1043.1654676258963;1085.039739830256;15285.770713439308;15406.075635461908;15435.459758908384;15495.922520387327;15800.130992795277;639814;643424;644813;648172;648245;56340.87318253612;56461.87076258452;56586;56622;56935.71532142313;1329.6210579984659;1370.1915869636962;1374.6219789557952;1379.8689124533153;1385.0858433433884;29057.41885162327;29266.853665731534;29278.56082158812;29893;30048.549271761378;48099.5190048095;48188.5181148198;48334.758326208146;48711.75644121757;48762.26856597225;587726;587824;589352;592061;597136;37985;39037.60962390454;39414.01464963399;39466.60533394744;39527.40708889312;46196.769016156046;46273.76863115663;46697.53302466932;46780.7660961693;46790.76604616955;39098.4135237966;39168.2166356677;39316.410253846785;40609.390859137726;40934.59065409427;38336.80831595936;38465.615343846206;38565.80717096396;38652.80673596614;39769.40345894756;38444;39590;40357.798211008754;40519.59480405158;40580.59419405768;30233.69766302309;32897.142402925085;34558;35170;35435.46846797249;25602.87198563995;25641.487170256863;25644.61533076968;25855.224343269732;26285.60067597763 06:02:25 INFO - PROCESS | 6540 | |2;jquery.html;338.2440246050078;346.1743741177293;367.1463846556756;375.44059351566165;386.23139951496495;19709.01454927253;20266.189352425903;20927.47681307968;20947.68578471324;21074.367768966957;19704.21183152672;19922.900385498087;20605.07277172527;20616.866072366014;20641.483962900937;5743.282089738793;5823.184754134425;5992.400759923995;6028.095785632168;6146.354632763562;3931.2923673738715;6137.969310153465;6190.473809726156;6190.84522886927;6196.628202307854;4111.773852438115;5851.15158302046;6145.5390845686525;6184.505239580845;6222.284437289702;5906.438888305604;6076.118962750398;6079.331273559905;6109.14471973924;6201.255849298091;5891.263592050994;5899.351071382144;5980.970095149496;5984.431479009487;5988.28140623128;13098.934505327508;13783.931080344635;21943.46395752298;25608.615870761954;25795.613065804027;2376.857388556692;2495.538325409787;2507.3731567108225;2817.8168419052695;2873.41095075509;9822.165115965152;9933.25500587459;10455.790884182274;13579.660508487366;14508.202048887308;1858.8196495225493;1872.092035362848;1949.0741897598668;1994.5312851479891;2027.2195204846241;106394.93605063928;118152;119438;122157;122781;11321.603743868925;11336.263142895685;11455.369954652408;11621.07031437483;11698.35659038744;12233.898949094624;14483.493077742229;14560.271986400849;14595.562133136023;14611.196384198758;7920.801979950432;8137.430379873471;8187.754367368989;8245.13426090264;8285.751427457186;6527.445167160749;7848.803779905434;7872.291493765589;7880.369570434352;7957.442978991531;3742.438634204891;4561.338605902126;4565.269556870885;4600.723956562617;4735.621150307967;1448.992755036218;1525.9771103433457;1962.7840937496865;1969.4047821264535;2003.4290227285153;819.2176471469733;910.1990248581207;946.9819943560562;993.4039576254189;1062.9574817007388;19934.102965366594;21447.8927605361;21484.677729834064;21568.352949411543;21598.89200553987;914.2092090341806;933.9533023348805;941.1012483078644;943.5848226780195;984.1290457944627;11077.11383089351;11319.264247823865;11347.148963827582;11398.08815294791;11416.600418985296;576804;586517;590113;594368;605297;131634;132757;133420;138619;142666;950.6815216902415;997.6109317366233;1009.1926458832904;1019.4749703902496;1022.3865680591585;18669;19608.90195549013;19620.80379196247;19708.21167153298;19739.506512337306;43309.350359744;51796.741016294676;53392.66518337073;54309.45690543044;56235.87528249494;79397;79439;80051.79922301053;80105;80344.59827700823;6905.930940690529;7915.485493442908;7932.215251568351;7965.960170199112;8080.636371363185;10714.678559643224;12933.54732584355;13118.409671564994;13166.40751166219;13294.734105318033;10052.69841904744;10309.278350515391;11210.83183752228;11225.494852731483;11281.943590281997;10865.945670271598;10997.505112270128;11077;11338.546458141582;11364.14768892353;11186.04918581897;11494.367809770209;11641.126915481203;11664.325737392694;11726.94136529312;6492.935070649233;6502.252240992158;7469.962650186714;7593.5823529704185;7621.923780762343;9373.25013998892;9880.654177104054;11121.221514493907;11433;11577.305361678325 06:02:25 INFO - PROCESS | 6540 | |3;mootools.html;1293.0173068467964;1487.5016869348767;1512.3723654683306;1799.8830076045065;1947.9610407791836;6950.582965022102;7942.880856787153;8455.154484551542;8981.59582818773;9045.90954090459;8514.787130321745;8800.77998050049;8812.118788121184;8866.157715017063;8899.955500222506;2013.5268211970176;2348.3776799148195;2360.9173678921243;2382.797462215705;2399.9760002400017;1966.6263409952135;2332.3003099070297;2389.5937690592555;2414.263649586875;2439.3413778279855;2386.0932845518764;2386.677798497198;2397.6043952747864;2401.5076909233567;2404.915827946022;2350.471143992588;2360.1267531013636;2361.49227915998;2371.3834403055243;2383.4637206628545;2355.6466530020634;2359.752226016261;2363.019346971005;2364.4679947011955;2367.5620010297976;8819.162179592993;8982.550872456351;8989.595468203946;9003.369764116514;9012;5259.579233661298;5269.499397557264;5310.60170487215;5363.06146424378;5363.892722145535;3451.4132597458242;3570.8571657133684;3609.9097522561883;3660.890173294779;3663.523741913534;1034.7620047389144;1064.8562444070028;1071.8285074388061;1074.7957888001333;1093.5625749700184;9891.258155638356;10775.622853200179;11063.004329610374;11565.653030409101;11699.590514331958;4678.953210467852;4723.291506274018;4778.776524490898;4838.782254798542;4876.463589005207;9853.211743060681;9972.950135249277;9977.700668979942;9990.050945160197;10009.599616015423;5286;5287.339082614673;5293.8882834605165;5294.947050529446;5300.787968481295;4385.166818304512;4607.35497030416;4921.064997650435;4936.703797772145;4936.777844996984;3439.6216416194197;3939.9015024624614;3941.940870886939;4086.1010577672864;4134.91730165401;1258.0250306012913;1313.2842600782596;1336.3184775764237;1340.1490053815703;1340.4370164530922;768.7847402727245;790.7943934576937;793.9595240232662;807.9313258373104;918.6266531535264;4941.579965702884;4953.90092198154;5919.112133180058;6125.9693701531205;6136.079588061737;383.33874067233796;385.6875930496307;437.29595351484085;441.62682533259346;459.9356090147382;3622.692071173927;3982.482277303932;4016.477857878457;4077.26609210345;4105.85629502966;9241.12209340125;10449;10693.465326733633;10710.678679639623;10865.619703310345;10646.307989980627;10787;10793.67618971432;10796.67609971702;10817.350958942488;8072.192780721881;8074.273315401642;8285.917140828635;8294.336453083739;8300.792480187924;4802.711837289774;5223.895522089461;5699.401562836008;5900.409959004065;5974.790882319098;9766.316357854881;9983.051610097029;9988.450635214842;9997.500124993576;10093.747656308651;9966.900330996887;10141.391516509033;10148.594056237667;10175.338602990782;10248.282620216512;6632.635205063768;7463.104427468738;7464.552126872405;7544.886826697494;7559.130699969354;8302.335813135056;8360.205780450848;8462.407631465738;8476.49141051539;8481.236688698047;7385.741499047507;7558.508696934681;7582.772516824504;7622.504537205064;7726.575038372944;7319.304666056716;7382.815429614087;7405.740799072007;7425.70297188128;7492.40060795146;7019.543729657556;7029.3673569379;7054;7069.9646501767165;7079.07971963662;4918.6065114791445;4994.425641051325;5089.211172268304;5132.30713853625;5134.306868572695;5016.272640467038;5089.287499749964;5156.097682905552;5185.818496352609;5219.451957544403 06:02:25 INFO - PROCESS | 6540 | |4;prototype.html;298.63417313790603;302.39219169469374;312.1805261189378;315.1035304558531;319.09695561560784;4936.35827342445;5201.739913004353;5639.323281206256;5708.771649134035;5757.934782065321;5348.679079255247;5506.8335706968455;5594.14698503763;5600.887982240352;5620.494155526002;4235.491740991078;4278.529361770203;4289.806958686867;4293.957060429387;4315.395844581761;3970.82131304092;4146.834126634946;4250.808713607895;4252.319628859384;4335.609795118438;4210.389493523453;4225.450691410112;4234.894127646803;4254.595813397722;4256.787160641971;3773.2453509298252;4250.553691862372;4253.106847562016;4257.552956939523;4282.400463935051;3015.42706885691;3082.4297504961655;3272.345530893807;3475.852968520382;3532.417151170054;4563.361129441881;4951.282064100721;5177.637565370425;5445.292112025411;5518.089515229983;4945.406551213841;4969.509147255809;4977.800887964477;4981.3810511583515;4986.700797952134;5568.470995255444;5964.850878728023;5973.163757074013;5995.700214989233;6007.8197654070445;3473.565804274466;3716.0709822544363;3861.517310336208;3983.342748446503;3997.560268370477;5407.270018547485;5484.890302193934;6291.370862913764;6322.715477803464;6346.333634968308;3885.300645883768;3896.649301562873;4017.4978127734034;4053.7365071270274;4064.532578753422;3546.7871927684423;3751.2872554214614;3905.1408690088133;3933.38128921393;4086;4532.614727748179;4830.951690483121;4845.418549773979;4845.6608037437745;4854.2233242681;5579.804706835242;5769.711514424262;6032.215811944507;6039.214902062791;6040.607360521552;1314.737052589486;1336.1248382309602;1361.3533571553432;1373.6085215713474;1443.942242310317;894.1460904835866;904.2088172848757;932.7248461703814;947.5025611553989;999.9450030248261;4318.892027699269;4485.282354823278;4633.980524284652;4650.442101895836;4657.790399432034;4667.719936803803;5071.548085807698;5090.287359769635;5092.759185958166;5139.331886854759;4131.710780245354;4431.844885429059;4686.601638860667;4710.670253082251;4710.9635880106325;2619.188051703978;2684.718104598989;2704.594310853388;2705.91882243533;2707.7156898525764;4869.488703686133;4922.58158056562;4924.926126108111;5287.894242115213;5290.841274761687;4592.632589392841;4981.808004478018;5133.768980395891;5227.7326670830735;5236.502532259429;4602.539746025438;4843.927341089816;4980.8256711015665;5000.6249531284575;5150.587952963754;4715.5243497164265;4948.505149485023;5067.898642027212;5069.389264528697;5080.237964305236;4373.3877257183385;4435.892950848322;4700.529947005272;5019.824306149268;5065.265536497261;4349.282033596774;4620.330052142488;4708.4349878014855;5284.26020357158;5289.761960711854;3189.1070500259966;3441.449368101192;3520.6973419833803;3781.6785573226584;4051;6031.728572214349;6061.36355682647;6064.757409703562;6077.665728384981;6084.574079814371;5604.859878502907;5849.53203743708;5856;5877.588568800314;5890;4779.498152694056;4946.183879659816;5016.821047053866;5158.767855446439;5781.392953739797;5132.897342053213;5741.971290143522;5766.740496677743;5780.913286300793;5809.360970293349;4675.602573781267;4710.845842768406;4850.638556255588;5863.325717542537;5935.525157987437;4769.833055843029;4982.501749824988;5611.184394611703;5681.801136960352;5774.199513236161 06:02:25 INFO - PROCESS | 6540 | |5;yui.html;713.5968177979438;782.3271986091963;792.8533221354049;815.584052133412;821.7165078048079;15287.69424611507;15680.215989200551;16196.919015404921;16206.432774852883;16217.026978381291;12346.506139754421;14719.926400367984;14944.103353798753;14967.700645987074;15042.247887605603;879.8812160358334;904.9411788233776;911.4166933162782;931.1154403316842;935.1163150822475;905.1536813079758;921.6820197032047;924.2605915267759;926.3006430145231;930.1767935377206;969.1907257440046;969.6363863551169;975.2149519636689;977.2621670638658;980.1717548671361;936.8735220745215;965.2760429677742;965.874436323277;966.8743063401748;975.8341082016075;713.9892901606428;732.2531018361295;732.4470025130992;732.7545272333774;733.5745267744695;13528.188308701509;13548.45806167752;13709.520166794115;13732.862671373257;13774.93112534441;11704.531818727326;11952.103592230618;12064.336461494615;12114;12567.497300107985;8885;10221.540030698561;10766.24636275461;10826.945865270623;10951.397673127974;3635.963640363589;3663.5420572428447;3686.8156592170553;3707.1102935295603;3835.7506762060375;85377.57311213404;107642.46178769134;109999.45000275028;110222.44888775585;111782.44108779567;9071.092890710876;9084.81830363389;9144.3598948073;9255.35212535116;9498.620055197853;19928.20287188496;19936.401907942785;20016.099275532637;20018.399448016582;20102.39692809218;7750.689972401041;7895.868115608753;7934.841303173905;8027.5584842834205;8169.142240064826;4151.813168407429;4235.745855248695;4256.124278032276;4312.956870431318;4353.869383918487;3450.6721861423625;3497.2655742293896;3538.858445662196;3561.554805649294;3571.982140089283;548.7722595123059;640.5228105061666;641.5124505375854;647.4464332995316;661.4377778887907;598.3328588623654;670.6814263225027;687.285223367702;690.6719308328604;703.9577625342394;8507;9109.817803644022;9302.581383837743;9347.813043739086;9430.339876208736;651.6546230497844;653.4870126950321;657.3196741372657;668.2315337361975;689.8861687821553;3747.793871337048;3773.547174339042;4404.207242696281;4661.114388266218;4698.8120475181295;95804.04195957951;103308;151120.2443987795;152818;155970;125643;130160.69839301486;131690;132877;133915;111065;112694.87305127007;114413;114762;117113.4144329273;7225.060742103493;8893.110688931056;8983.907269303481;9005.099490050943;9088.681896133603;56415.71792141013;63323;63902;65153.348466514726;65341.346586533524;60824;61352;87504.68742968733;93698;95633;11995.64013079609;12037.75924481523;12075.094367922266;12121.878781212075;12255.142140050111;12881.48474061027;12929.870701292866;12950.004466545694;13336.93331533375;13613.387397566936;11840.76318473643;11844.881551184722;12083.154179207722;12253.264804111783;12536.811947820972;12105.69735756613;12241.816372754238;12246.44890979926;12277.20198187115;12697.682557936125;12034.458449369624;12075.27548347102;12180;12486.750264994831;12512.937435312766;9706.320557560903;9871.703848884545;9962;10056.899431005597;10271.229657775846;9268.814623707352;9828.606855725691;9889.85165222508;9900.950495247478;9918.950405247928 06:02:25 INFO - PROCESS | 6540 | __end_tp_report 06:02:25 INFO - PROCESS | 6540 | __start_cc_report 06:02:25 INFO - PROCESS | 6540 | _x_x_mozilla_cycle_collect,361 06:02:25 INFO - PROCESS | 6540 | __end_cc_report 06:02:25 INFO - PROCESS | 6540 | __startTimestamp1476795745717__endTimestamp 06:02:25 INFO - PROCESS | 6540 | 06:02:25 INFO - PROCESS | 6540 | ------- Summary: start ------- 06:02:25 INFO - PROCESS | 6540 | Number of tests: 6 06:02:25 INFO - PROCESS | 6540 | 06:02:25 INFO - PROCESS | 6540 | [#0] dojo.html Cycles:165 Average:5701.90 Median:5897.53 stddev:678.06 (11.5%) stddev-sans-first:654.00 06:02:25 INFO - PROCESS | 6540 | Values: 3324.9 3364.5 3377.5 3387.7 3403.4 5648.6 5765.7 5784.4 5785.1 5795.2 5710.4 5843.5 5857.0 5873.1 5897.5 5169.1 5225.3 5300.9 5333.0 5359.5 5228.7 5311.1 5348.8 5360.4 5388.2 4064.6 4946.0 5144.6 5166.4 5179.9 5241.9 5266.1 5284.3 5301.6 5307.2 5173.8 5219.8 5225.2 5232.4 5241.8 5623.0 5632.9 5696.8 5713.0 5794.1 6223.9 6255.8 6303.7 6304.8 6317.9 5329.9 6230.7 6259.8 6267.9 6273.2 5746.0 5799.9 5958.4 5963.0 6010.8 6197.0 6228.4 6264.0 6321.7 6332.0 6170.3 6190.5 6218.1 6219.5 6241.4 4967.5 4985.5 6274.7 6303.5 6370.6 5061.3 5167.5 5808.1 6216.4 6222.6 6236.6 6266.3 6301.5 6306.3 6309.0 4958.6 4977.9 4982.0 4984.8 5011.9 3451.3 4431.2 4448.9 4474.8 4492.9 4991.8 5728.1 5884.9 5890.4 5951.5 5164.2 6220.1 6260.5 6273.7 6286.9 5687.8 5702.0 5863.3 5896.7 5905.4 5398.1 5411.1 5439.7 5445.7 5506.0 5304.1 6249.9 6331.1 6345.9 6383.4 5879.6 6147.8 6259.9 6273.8 6273.8 6200.2 6234.0 6234.6 6287.3 6404.7 5232.5 6242.5 6247.7 6269.9 6271.3 6174.4 6227.5 6258.1 6262.8 6267.9 4882.5 6079.1 6081.4 6101.5 6144.7 6098.7 6154.6 6177.3 6179.8 6198.1 4836.3 5846.6 5918.2 6016.9 6024.4 6084.6 6096.8 6119.8 6206.6 6211.3 5921.6 6056.4 6065.5 6086.3 6112.5 06:02:25 INFO - PROCESS | 6540 | 06:02:25 INFO - PROCESS | 6540 | [#1] ext.html Cycles:180 Average:66492.21 Median:40157.30 stddev:144980.71 (361.0%) stddev-sans-first:145302.66 06:02:25 INFO - PROCESS | 6540 | Values: 462.7 464.2 491.8 519.4 546.2 38298.6 40499.6 41287.8 41331.0 41435.0 41554.0 41664.0 41881.2 42061.6 42288.8 9420.5 9572.2 9601.4 9620.4 9626.5 9438.1 9488.8 9570.6 9572.0 9573.3 9554.4 9559.0 9574.9 9593.0 9624.4 9487.7 9551.7 9572.2 9575.9 9583.1 9462.0 9520.7 9544.8 9563.4 9599.7 39730.6 39927.0 39947.6 40112.2 40202.4 3367.6 3399.5 3507.2 3567.5 4119.0 29931.7 30229.4 31007.5 31049.7 31533.0 3232.9 3330.7 3338.7 3381.8 3385.1 307512.0 332886.0 344379.0 354942.2 355246.0 57775.1 58031.7 58213.4 58226.0 58232.4 50083.7 53020.0 60716.0 60892.4 61090.0 15042.2 15242.8 15506.5 15536.8 15589.5 11769.5 12181.8 12182.1 12201.7 12221.7 12689.9 12950.2 14879.6 15670.6 15766.7 6822.4 6977.2 6998.4 7005.3 7087.8 792.8 833.2 999.8 1019.5 1036.5 27869.6 29694.0 29880.6 30137.1 30278.8 1026.0 1036.5 1037.7 1043.2 1085.0 15285.8 15406.1 15435.5 15495.9 15800.1 639814.0 643424.0 644813.0 648172.0 648245.0 56340.9 56461.9 56586.0 56622.0 56935.7 1329.6 1370.2 1374.6 1379.9 1385.1 29057.4 29266.9 29278.6 29893.0 30048.5 48099.5 48188.5 48334.8 48711.8 48762.3 587726.0 587824.0 589352.0 592061.0 597136.0 37985.0 39037.6 39414.0 39466.6 39527.4 46196.8 46273.8 46697.5 46780.8 46790.8 39098.4 39168.2 39316.4 40609.4 40934.6 38336.8 38465.6 38565.8 38652.8 39769.4 38444.0 39590.0 40357.8 40519.6 40580.6 30233.7 32897.1 34558.0 35170.0 35435.5 25602.9 25641.5 25644.6 25855.2 26285.6 06:02:25 INFO - PROCESS | 6540 | 06:02:25 INFO - PROCESS | 6540 | [#2] jquery.html Cycles:180 Average:34915.87 Median:14057.99 stddev:98916.27 (703.6%) stddev-sans-first:99159.68 06:02:25 INFO - PROCESS | 6540 | Values: 338.2 346.2 367.1 375.4 386.2 19709.0 20266.2 20927.5 20947.7 21074.4 19704.2 19922.9 20605.1 20616.9 20641.5 5743.3 5823.2 5992.4 6028.1 6146.4 3931.3 6138.0 6190.5 6190.8 6196.6 4111.8 5851.2 6145.5 6184.5 6222.3 5906.4 6076.1 6079.3 6109.1 6201.3 5891.3 5899.4 5981.0 5984.4 5988.3 13098.9 13783.9 21943.5 25608.6 25795.6 2376.9 2495.5 2507.4 2817.8 2873.4 9822.2 9933.3 10455.8 13579.7 14508.2 1858.8 1872.1 1949.1 1994.5 2027.2 106394.9 118152.0 119438.0 122157.0 122781.0 11321.6 11336.3 11455.4 11621.1 11698.4 12233.9 14483.5 14560.3 14595.6 14611.2 7920.8 8137.4 8187.8 8245.1 8285.8 6527.4 7848.8 7872.3 7880.4 7957.4 3742.4 4561.3 4565.3 4600.7 4735.6 1449.0 1526.0 1962.8 1969.4 2003.4 819.2 910.2 947.0 993.4 1063.0 19934.1 21447.9 21484.7 21568.4 21598.9 914.2 934.0 941.1 943.6 984.1 11077.1 11319.3 11347.1 11398.1 11416.6 576804.0 586517.0 590113.0 594368.0 605297.0 131634.0 132757.0 133420.0 138619.0 142666.0 950.7 997.6 1009.2 1019.5 1022.4 18669.0 19608.9 19620.8 19708.2 19739.5 43309.4 51796.7 53392.7 54309.5 56235.9 79397.0 79439.0 80051.8 80105.0 80344.6 6905.9 7915.5 7932.2 7966.0 8080.6 10714.7 12933.5 13118.4 13166.4 13294.7 10052.7 10309.3 11210.8 11225.5 11281.9 10865.9 10997.5 11077.0 11338.5 11364.1 11186.0 11494.4 11641.1 11664.3 11726.9 6492.9 6502.3 7470.0 7593.6 7621.9 9373.3 9880.7 11121.2 11433.0 11577.3 06:02:25 INFO - PROCESS | 6540 | 06:02:25 INFO - PROCESS | 6540 | [#3] mootools.html Cycles:180 Average:5669.80 Median:4939.18 stddev:3190.05 (64.6%) stddev-sans-first:3182.04 06:02:25 INFO - PROCESS | 6540 | Values: 1293.0 1487.5 1512.4 1799.9 1948.0 6950.6 7942.9 8455.2 8981.6 9045.9 8514.8 8800.8 8812.1 8866.2 8900.0 2013.5 2348.4 2360.9 2382.8 2400.0 1966.6 2332.3 2389.6 2414.3 2439.3 2386.1 2386.7 2397.6 2401.5 2404.9 2350.5 2360.1 2361.5 2371.4 2383.5 2355.6 2359.8 2363.0 2364.5 2367.6 8819.2 8982.6 8989.6 9003.4 9012.0 5259.6 5269.5 5310.6 5363.1 5363.9 3451.4 3570.9 3609.9 3660.9 3663.5 1034.8 1064.9 1071.8 1074.8 1093.6 9891.3 10775.6 11063.0 11565.7 11699.6 4679.0 4723.3 4778.8 4838.8 4876.5 9853.2 9973.0 9977.7 9990.1 10009.6 5286.0 5287.3 5293.9 5294.9 5300.8 4385.2 4607.4 4921.1 4936.7 4936.8 3439.6 3939.9 3941.9 4086.1 4134.9 1258.0 1313.3 1336.3 1340.1 1340.4 768.8 790.8 794.0 807.9 918.6 4941.6 4953.9 5919.1 6126.0 6136.1 383.3 385.7 437.3 441.6 459.9 3622.7 3982.5 4016.5 4077.3 4105.9 9241.1 10449.0 10693.5 10710.7 10865.6 10646.3 10787.0 10793.7 10796.7 10817.4 8072.2 8074.3 8285.9 8294.3 8300.8 4802.7 5223.9 5699.4 5900.4 5974.8 9766.3 9983.1 9988.5 9997.5 10093.7 9966.9 10141.4 10148.6 10175.3 10248.3 6632.6 7463.1 7464.6 7544.9 7559.1 8302.3 8360.2 8462.4 8476.5 8481.2 7385.7 7558.5 7582.8 7622.5 7726.6 7319.3 7382.8 7405.7 7425.7 7492.4 7019.5 7029.4 7054.0 7070.0 7079.1 4918.6 4994.4 5089.2 5132.3 5134.3 5016.3 5089.3 5156.1 5185.8 5219.5 06:02:25 INFO - PROCESS | 6540 | 06:02:25 INFO - PROCESS | 6540 | [#4] prototype.html Cycles:180 Average:4477.61 Median:4852.43 stddev:1362.07 (28.1%) stddev-sans-first:1329.28 06:02:25 INFO - PROCESS | 6540 | Values: 298.6 302.4 312.2 315.1 319.1 4936.4 5201.7 5639.3 5708.8 5757.9 5348.7 5506.8 5594.1 5600.9 5620.5 4235.5 4278.5 4289.8 4294.0 4315.4 3970.8 4146.8 4250.8 4252.3 4335.6 4210.4 4225.5 4234.9 4254.6 4256.8 3773.2 4250.6 4253.1 4257.6 4282.4 3015.4 3082.4 3272.3 3475.9 3532.4 4563.4 4951.3 5177.6 5445.3 5518.1 4945.4 4969.5 4977.8 4981.4 4986.7 5568.5 5964.9 5973.2 5995.7 6007.8 3473.6 3716.1 3861.5 3983.3 3997.6 5407.3 5484.9 6291.4 6322.7 6346.3 3885.3 3896.6 4017.5 4053.7 4064.5 3546.8 3751.3 3905.1 3933.4 4086.0 4532.6 4831.0 4845.4 4845.7 4854.2 5579.8 5769.7 6032.2 6039.2 6040.6 1314.7 1336.1 1361.4 1373.6 1443.9 894.1 904.2 932.7 947.5 999.9 4318.9 4485.3 4634.0 4650.4 4657.8 4667.7 5071.5 5090.3 5092.8 5139.3 4131.7 4431.8 4686.6 4710.7 4711.0 2619.2 2684.7 2704.6 2705.9 2707.7 4869.5 4922.6 4924.9 5287.9 5290.8 4592.6 4981.8 5133.8 5227.7 5236.5 4602.5 4843.9 4980.8 5000.6 5150.6 4715.5 4948.5 5067.9 5069.4 5080.2 4373.4 4435.9 4700.5 5019.8 5065.3 4349.3 4620.3 4708.4 5284.3 5289.8 3189.1 3441.4 3520.7 3781.7 4051.0 6031.7 6061.4 6064.8 6077.7 6084.6 5604.9 5849.5 5856.0 5877.6 5890.0 4779.5 4946.2 5016.8 5158.8 5781.4 5132.9 5742.0 5766.7 5780.9 5809.4 4675.6 4710.8 4850.6 5863.3 5935.5 4769.8 4982.5 5611.2 5681.8 5774.2 06:02:25 INFO - PROCESS | 6540 | 06:02:25 INFO - PROCESS | 6540 | [#5] yui.html Cycles:180 Average:23619.33 Median:4379.04 stddev:38278.65 (874.1%) stddev-sans-first:38347.40 06:02:25 INFO - PROCESS | 6540 | Values: 713.6 782.3 792.9 815.6 821.7 15287.7 15680.2 16196.9 16206.4 16217.0 12346.5 14719.9 14944.1 14967.7 15042.2 879.9 904.9 911.4 931.1 935.1 905.2 921.7 924.3 926.3 930.2 969.2 969.6 975.2 977.3 980.2 936.9 965.3 965.9 966.9 975.8 714.0 732.3 732.4 732.8 733.6 13528.2 13548.5 13709.5 13732.9 13774.9 11704.5 11952.1 12064.3 12114.0 12567.5 8885.0 10221.5 10766.2 10826.9 10951.4 3636.0 3663.5 3686.8 3707.1 3835.8 85377.6 107642.5 109999.5 110222.4 111782.4 9071.1 9084.8 9144.4 9255.4 9498.6 19928.2 19936.4 20016.1 20018.4 20102.4 7750.7 7895.9 7934.8 8027.6 8169.1 4151.8 4235.7 4256.1 4313.0 4353.9 3450.7 3497.3 3538.9 3561.6 3572.0 548.8 640.5 641.5 647.4 661.4 598.3 670.7 687.3 690.7 704.0 8507.0 9109.8 9302.6 9347.8 9430.3 651.7 653.5 657.3 668.2 689.9 3747.8 3773.5 4404.2 4661.1 4698.8 95804.0 103308.0 151120.2 152818.0 155970.0 125643.0 130160.7 131690.0 132877.0 133915.0 111065.0 112694.9 114413.0 114762.0 117113.4 7225.1 8893.1 8983.9 9005.1 9088.7 56415.7 63323.0 63902.0 65153.3 65341.3 60824.0 61352.0 87504.7 93698.0 95633.0 11995.6 12037.8 12075.1 12121.9 12255.1 12881.5 12929.9 12950.0 13336.9 13613.4 11840.8 11844.9 12083.2 12253.3 12536.8 12105.7 12241.8 12246.4 12277.2 12697.7 12034.5 12075.3 12180.0 12486.8 12512.9 9706.3 9871.7 9962.0 10056.9 10271.2 9268.8 9828.6 9889.9 9901.0 9919.0 06:02:25 INFO - PROCESS | 6540 | -------- Summary: end -------- 06:02:25 INFO - PROCESS | 6540 | 06:02:26 INFO - TEST-INFO | 6540: exit 0 06:02:26 INFO - TEST-OK | dromaeo_css | took 1115811ms 06:02:26 INFO - TEST-START | kraken 06:02:26 INFO - operating with platform_type : mac_ 06:02:26 INFO - Initialising browser for kraken test... 06:02:26 INFO - TEST-INFO | started process 6548 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpGcMJ01/profile http://localhost:58316/getInfo.html) 06:02:29 INFO - PROCESS | 6548 | 2016-10-18 06:02:29.190 plugin-container[6549:115723] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 06:02:29 INFO - PROCESS | 6548 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:02:29 INFO - PROCESS | 6548 | 2016-10-18 06:02:29.194 plugin-container[6549:115723] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 06:02:29 INFO - PROCESS | 6548 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:02:29 INFO - PROCESS | 6548 | 2016-10-18 06:02:29.195 plugin-container[6549:115723] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:02:29 INFO - PROCESS | 6548 | __metrics Screen width/height:1600/1200 06:02:29 INFO - PROCESS | 6548 | colorDepth:24 06:02:29 INFO - PROCESS | 6548 | Browser inner width/height: 1024/689 06:02:29 INFO - PROCESS | 6548 | __metrics 06:02:29 ERROR - PROCESS | 6548 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 06:02:30 INFO - TEST-INFO | 6548: exit 0 06:02:30 INFO - Browser initialized. 06:02:30 INFO - Running cycle 1/1 for kraken test... 06:02:30 INFO - TEST-INFO | started process 6550 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpGcMJ01/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:02:32 INFO - PROCESS | 6550 | 2016-10-18 06:02:32.610 plugin-container[6551:115834] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 06:02:32 INFO - PROCESS | 6550 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:02:32 INFO - PROCESS | 6550 | 2016-10-18 06:02:32.611 plugin-container[6551:115834] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 06:02:32 INFO - PROCESS | 6550 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:02:32 INFO - PROCESS | 6550 | 2016-10-18 06:02:32.611 plugin-container[6551:115834] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:02:42 INFO - PROCESS | 6550 | RSS: Main: 237420544 06:02:42 INFO - PROCESS | 6550 | 06:02:44 INFO - PROCESS | 6550 | Cycle 1(1): loaded http://localhost:58316/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58316/tests/kraken/driver.html?testName=audio-beat-detection) 06:02:45 INFO - PROCESS | 6550 | RSS: Main: 249573376 06:02:45 INFO - PROCESS | 6550 | 06:02:48 INFO - PROCESS | 6550 | Cycle 1(1): loaded http://localhost:58316/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58316/tests/kraken/driver.html?testName=audio-dft) 06:02:49 INFO - PROCESS | 6550 | RSS: Main: 257138688 06:02:49 INFO - PROCESS | 6550 | 06:02:52 INFO - PROCESS | 6550 | Cycle 1(1): loaded http://localhost:58316/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58316/tests/kraken/driver.html?testName=audio-fft) 06:02:53 INFO - PROCESS | 6550 | RSS: Main: 259940352 06:02:53 INFO - PROCESS | 6550 | 06:02:55 INFO - PROCESS | 6550 | Cycle 1(1): loaded http://localhost:58316/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58316/tests/kraken/driver.html?testName=audio-oscillator) 06:02:55 INFO - PROCESS | 6550 | RSS: Main: 261451776 06:02:55 INFO - PROCESS | 6550 | 06:02:57 INFO - PROCESS | 6550 | Cycle 1(1): loaded http://localhost:58316/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58316/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:02:58 INFO - PROCESS | 6550 | RSS: Main: 261386240 06:02:58 INFO - PROCESS | 6550 | 06:03:01 INFO - PROCESS | 6550 | Cycle 1(1): loaded http://localhost:58316/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58316/tests/kraken/driver.html?testName=imaging-darkroom) 06:03:01 INFO - PROCESS | 6550 | RSS: Main: 262483968 06:03:01 INFO - PROCESS | 6550 | 06:03:04 INFO - PROCESS | 6550 | Cycle 1(1): loaded http://localhost:58316/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58316/tests/kraken/driver.html?testName=imaging-desaturate) 06:03:05 INFO - PROCESS | 6550 | RSS: Main: 263581696 06:03:05 INFO - PROCESS | 6550 | 06:03:08 INFO - PROCESS | 6550 | Cycle 1(1): loaded http://localhost:58316/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58316/tests/kraken/driver.html?testName=json-parse-financial) 06:03:08 INFO - PROCESS | 6550 | RSS: Main: 263995392 06:03:08 INFO - PROCESS | 6550 | 06:03:10 INFO - PROCESS | 6550 | Cycle 1(1): loaded http://localhost:58316/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58316/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:03:11 INFO - PROCESS | 6550 | RSS: Main: 263221248 06:03:11 INFO - PROCESS | 6550 | 06:03:13 INFO - PROCESS | 6550 | Cycle 1(1): loaded http://localhost:58316/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58316/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:03:13 INFO - PROCESS | 6550 | RSS: Main: 263233536 06:03:13 INFO - PROCESS | 6550 | 06:03:15 INFO - PROCESS | 6550 | Cycle 1(1): loaded http://localhost:58316/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58316/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:03:16 INFO - PROCESS | 6550 | RSS: Main: 263192576 06:03:16 INFO - PROCESS | 6550 | 06:03:19 INFO - PROCESS | 6550 | Cycle 1(1): loaded http://localhost:58316/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58316/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:03:20 INFO - PROCESS | 6550 | RSS: Main: 263360512 06:03:20 INFO - PROCESS | 6550 | 06:03:23 INFO - PROCESS | 6550 | Cycle 1(1): loaded http://localhost:58316/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58316/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:03:23 INFO - PROCESS | 6550 | RSS: Main: 263655424 06:03:23 INFO - PROCESS | 6550 | 06:03:25 INFO - PROCESS | 6550 | Cycle 1(1): loaded http://localhost:58316/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58316/tests/kraken/driver.html?testName=ai-astar) 06:03:26 INFO - PROCESS | 6550 | RSS: Main: 265371648 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | __start_tp_report 06:03:26 INFO - PROCESS | 6550 | _x_x_mozilla_page_load 06:03:26 INFO - PROCESS | 6550 | _x_x_mozilla_page_load_details 06:03:26 INFO - PROCESS | 6550 | |i|pagename|runs| 06:03:26 INFO - PROCESS | 6550 | |0;ai-astar;115;117;117;115;120;117;113;115;117;116 06:03:26 INFO - PROCESS | 6550 | |1;audio-beat-detection;151;118;100;182;147;119;141;145;179;116 06:03:26 INFO - PROCESS | 6550 | |2;audio-dft;199;201;205;234;199;196;198;195;197;197 06:03:26 INFO - PROCESS | 6550 | |3;audio-fft;84;59;59;92;65;85;84;58;80;79 06:03:26 INFO - PROCESS | 6550 | |4;audio-oscillator;85;70;90;78;55;70;113;98;101;108 06:03:26 INFO - PROCESS | 6550 | |5;imaging-gaussian-blur;89;77;137;86;97;131;89;86;89;89 06:03:26 INFO - PROCESS | 6550 | |6;imaging-darkroom;112;100;97;98;99;97;98;102;98;99 06:03:26 INFO - PROCESS | 6550 | |7;imaging-desaturate;93;96;86;87;121;89;91;93;88;86 06:03:26 INFO - PROCESS | 6550 | |8;json-parse-financial;50;78;69;51;55;57;53;85;77;85 06:03:26 INFO - PROCESS | 6550 | |9;json-stringify-tinderbox;64;89;104;83;80;77;73;73;72;71 06:03:26 INFO - PROCESS | 6550 | |10;stanford-crypto-aes;86;89;90;88;92;94;90;88;95;90 06:03:26 INFO - PROCESS | 6550 | |11;stanford-crypto-ccm;160;143;160;145;163;150;163;142;193;295 06:03:26 INFO - PROCESS | 6550 | |12;stanford-crypto-pbkdf2;175;176;175;194;177;176;174;173;173;176 06:03:26 INFO - PROCESS | 6550 | |13;stanford-crypto-sha256-iterative;70;70;72;82;91;76;69;67;71;81 06:03:26 INFO - PROCESS | 6550 | __end_tp_report 06:03:26 INFO - PROCESS | 6550 | __start_cc_report 06:03:26 INFO - PROCESS | 6550 | _x_x_mozilla_cycle_collect,952 06:03:26 INFO - PROCESS | 6550 | __end_cc_report 06:03:26 INFO - PROCESS | 6550 | __startTimestamp1476795806036__endTimestamp 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | ------- Summary: start ------- 06:03:26 INFO - PROCESS | 6550 | Number of tests: 14 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | [#0] ai-astar Cycles:10 Average:116.20 Median:117.00 stddev:1.87 (1.6%) stddev-sans-first:1.94 06:03:26 INFO - PROCESS | 6550 | Values: 115.0 117.0 117.0 115.0 120.0 117.0 113.0 115.0 117.0 116.0 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:146.00 stddev:27.04 (18.5%) stddev-sans-first:28.38 06:03:26 INFO - PROCESS | 6550 | Values: 151.0 118.0 100.0 182.0 147.0 119.0 141.0 145.0 179.0 116.0 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | [#2] audio-dft Cycles:10 Average:202.10 Median:199.00 stddev:11.56 (5.8%) stddev-sans-first:12.21 06:03:26 INFO - PROCESS | 6550 | Values: 199.0 201.0 205.0 234.0 199.0 196.0 198.0 195.0 197.0 197.0 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | [#3] audio-fft Cycles:10 Average:74.50 Median:82.00 stddev:12.87 (15.7%) stddev-sans-first:13.18 06:03:26 INFO - PROCESS | 6550 | Values: 84.0 59.0 59.0 92.0 65.0 85.0 84.0 58.0 80.0 79.0 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | [#4] audio-oscillator Cycles:10 Average:86.80 Median:74.00 stddev:18.65 (25.2%) stddev-sans-first:19.77 06:03:26 INFO - PROCESS | 6550 | Values: 85.0 70.0 90.0 78.0 55.0 70.0 113.0 98.0 101.0 108.0 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | [#5] imaging-gaussian-blur Cycles:10 Average:97.00 Median:89.00 stddev:20.15 (22.6%) stddev-sans-first:21.16 06:03:26 INFO - PROCESS | 6550 | Values: 89.0 77.0 137.0 86.0 97.0 131.0 89.0 86.0 89.0 89.0 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | [#6] imaging-darkroom Cycles:10 Average:100.00 Median:98.00 stddev:4.47 (4.6%) stddev-sans-first:1.58 06:03:26 INFO - PROCESS | 6550 | Values: 112.0 100.0 97.0 98.0 99.0 97.0 98.0 102.0 98.0 99.0 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | [#7] imaging-desaturate Cycles:10 Average:93.00 Median:90.00 stddev:10.39 (11.5%) stddev-sans-first:11.02 06:03:26 INFO - PROCESS | 6550 | Values: 93.0 96.0 86.0 87.0 121.0 89.0 91.0 93.0 88.0 86.0 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | [#8] json-parse-financial Cycles:10 Average:66.00 Median:73.00 stddev:14.33 (19.6%) stddev-sans-first:13.98 06:03:26 INFO - PROCESS | 6550 | Values: 50.0 78.0 69.0 51.0 55.0 57.0 53.0 85.0 77.0 85.0 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | [#9] json-stringify-tinderbox Cycles:10 Average:78.60 Median:75.00 stddev:11.33 (15.1%) stddev-sans-first:10.71 06:03:26 INFO - PROCESS | 6550 | Values: 64.0 89.0 104.0 83.0 80.0 77.0 73.0 73.0 72.0 71.0 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:90.00 stddev:2.78 (3.1%) stddev-sans-first:2.50 06:03:26 INFO - PROCESS | 6550 | Values: 86.0 89.0 90.0 88.0 92.0 94.0 90.0 88.0 95.0 90.0 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | [#11] stanford-crypto-ccm Cycles:10 Average:171.40 Median:161.50 stddev:45.91 (28.4%) stddev-sans-first:48.51 06:03:26 INFO - PROCESS | 6550 | Values: 160.0 143.0 160.0 145.0 163.0 150.0 163.0 142.0 193.0 295.0 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.90 Median:176.00 stddev:6.15 (3.5%) stddev-sans-first:6.49 06:03:26 INFO - PROCESS | 6550 | Values: 175.0 176.0 175.0 194.0 177.0 176.0 174.0 173.0 173.0 176.0 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - PROCESS | 6550 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.90 Median:74.00 stddev:7.58 (10.2%) stddev-sans-first:7.83 06:03:26 INFO - PROCESS | 6550 | Values: 70.0 70.0 72.0 82.0 91.0 76.0 69.0 67.0 71.0 81.0 06:03:26 INFO - PROCESS | 6550 | -------- Summary: end -------- 06:03:26 INFO - PROCESS | 6550 | 06:03:26 INFO - TEST-INFO | 6550: exit 0 06:03:26 INFO - TEST-OK | kraken | took 60527ms 06:03:26 INFO - SUITE-END | took 1176s 06:03:27 INFO - Completed test suite (00:19:36) 06:03:27 INFO - javascript benchmark 06:03:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7248.14640777187, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3324.9002529924096, 3364.478505831596, 3377.54403155574, 3387.728981681466, 3403.3703764803527, 5648.604597678164, 5765.740541675625, 5784.363719990798, 5785.074388097905, 5795.21764561784, 5710.371859095496, 5843.503302219306, 5856.9750293698535, 5873.148393482942, 5897.528197744181, 5169.147090730044, 5225.320708307915, 5300.893982120355, 5332.986732520828, 5359.490848369418, 5228.73856307185, 5311.123664595337, 5348.812791552277, 5360.356757189143, 5388.191771234307, 4064.593540645941, 4945.999999999982, 5144.588432925357, 5166.405863325728, 5179.922301165486, 5241.9213711794355, 5266.104762190437, 5284.313039304866, 5301.628885977983, 5307.230451584499, 5173.767180476888, 5219.8434046978655, 5225.242339860699, 5232.372115346145, 5241.794387290906, 5623.0, 5632.915506267409, 5696.829095127153, 5712.971435142797, 5794.101914203306, 6223.906641400383, 6255.812325630193, 6303.653299068549, 6304.7793327233785, 6317.873642527124, 5329.893402131975, 6230.722701846156, 6259.812205633838, 6267.874642507124, 6273.215848018997, 5746.0, 5799.927013502515, 5958.404159584094, 5962.970185149046, 6010.789622363196, 6197.0, 6228.408301211378, 6264.0, 6321.747130114836, 6332.0, 6170.259568851767, 6190.535709821781, 6218.098375735493, 6219.533534984895, 6241.438270555672, 4967.503249675004, 4985.476524964826, 6274.749010039639, 6303.527235457267, 6370.55406121567, 5061.342025536656, 5167.534921856975, 5808.128780682931, 6216.44052035319, 6222.62664240147, 6236.594621349688, 6266.310705822356, 6301.52738544611, 6306.306306306303, 6308.968455157695, 4958.553730164303, 4977.875553111201, 4982.0, 4984.825531106467, 5011.949880501221, 3451.3442445935193, 4431.180231657154, 4448.911021779546, 4474.843380481668, 4492.910141797146, 4991.775370108354, 5728.1407788831175, 5884.882302353929, 5890.410958904161, 5951.464368206882, 5164.225366195101, 6220.066989951453, 6260.530460215502, 6273.7176827042895, 6286.937130628727, 5687.8009269676195, 5701.971490142523, 5863.29640443141, 5896.705164741831, 5905.40945905406, 5398.082326004589, 5411.134218525017, 5439.673619582838, 5445.673259604437, 5505.953868765002, 5304.071787437106, 6249.9375006251175, 6331.145295385156, 6345.93654063472, 6383.425491705769, 5879.617824841372, 6147.770445910746, 6259.906101408574, 6273.780417685359, 6273.780417685359, 6200.224971878515, 6233.968830156002, 6234.563580549318, 6287.30839607652, 6404.679766011774, 5232.529072383504, 6242.500599951902, 6247.656378899204, 6269.905951410824, 6271.341509141657, 6174.413430724254, 6227.470664993526, 6258.12386265914, 6262.780802671885, 6267.905981410191, 4882.487338829372, 6079.088136779518, 6081.361457046946, 6101.459346768484, 6144.662043587646, 6098.664573448503, 6154.630212548961, 6177.289611694712, 6179.81460556184, 6198.101275315144, 4836.250381190921, 5846.5615078870105, 5918.201042859159, 6016.939830601813, 6024.4017593122835, 6084.574079814371, 6096.786612468542, 6119.837693858789, 6206.627602343874, 6211.3478084800445, 5921.644701317935, 6056.424639659225, 6065.505059577285, 6086.330503644507, 6112.480439162721], "value": 5661.37984286324, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.7478024476658, 464.2015732939343, 491.8171796828626, 519.3793416866844, 546.2107255016498, 38298.61701382989, 40499.59500404995, 41287.79356103219, 41331.0, 41435.02779666437, 41554.0, 41664.0, 41881.16237675244, 42061.57938420615, 42288.788556057174, 9420.528973551312, 9572.234221262317, 9601.423914565148, 9620.42277463351, 9626.470544120071, 9438.103380178873, 9488.762780930463, 9570.617175312978, 9571.95214023929, 9573.329866909318, 9554.426734395924, 9558.952205238964, 9574.856377154349, 9592.952035239814, 9624.422534647909, 9487.667931622394, 9551.665691700826, 9572.18636415902, 9575.850897892262, 9583.137517623447, 9462.0, 9520.666776662852, 9544.761380965461, 9563.426194428357, 9599.712008639752, 39730.60269397298, 39927.0, 39947.600523994675, 40112.19775604501, 40202.39696404556, 3367.6295607483157, 3399.507071474647, 3507.2459421224557, 3567.4648802679544, 4118.9588104118875, 29931.70068299311, 30229.395412091853, 31007.534886976715, 31049.689503104906, 31533.0, 3232.8868489602987, 3330.6835850594157, 3338.7162091222276, 3381.8140002299865, 3385.0691059958463, 307512.0, 332886.0, 344379.0, 354942.22528887447, 355246.0, 57775.066991961234, 58031.70984145137, 58213.41786582165, 58226.0, 58232.41767582355, 50083.74958125186, 53020.0, 60716.0, 60892.39107608956, 61090.0, 15042.172680502677, 15242.847571524364, 15506.534803955674, 15536.84463155354, 15589.454369097028, 11769.470373833197, 12181.817272740916, 12182.147249692436, 12201.694957625952, 12221.69445763863, 12689.87310126887, 12950.222986620833, 14879.553613391616, 15670.608234794221, 15766.68466630661, 6822.420094292041, 6977.232504424509, 6998.440124790005, 7005.299470052954, 7087.751928682472, 792.7978365516872, 833.2167762303416, 999.7600575861886, 1019.5259204469809, 1036.549101141006, 27869.58195627067, 29694.0, 29880.55179172314, 30137.09588712342, 30278.84860575683, 1026.0252759878147, 1036.4817591204398, 1037.7094413564214, 1043.1654676258963, 1085.039739830256, 15285.770713439308, 15406.075635461908, 15435.459758908384, 15495.922520387327, 15800.130992795277, 639814.0, 643424.0, 644813.0, 648172.0, 648245.0, 56340.87318253612, 56461.87076258452, 56586.0, 56622.0, 56935.71532142313, 1329.6210579984659, 1370.1915869636962, 1374.6219789557952, 1379.8689124533153, 1385.0858433433884, 29057.41885162327, 29266.853665731534, 29278.56082158812, 29893.0, 30048.549271761378, 48099.5190048095, 48188.5181148198, 48334.758326208146, 48711.75644121757, 48762.26856597225, 587726.0, 587824.0, 589352.0, 592061.0, 597136.0, 37985.0, 39037.60962390454, 39414.01464963399, 39466.60533394744, 39527.40708889312, 46196.769016156046, 46273.76863115663, 46697.53302466932, 46780.7660961693, 46790.76604616955, 39098.4135237966, 39168.2166356677, 39316.410253846785, 40609.390859137726, 40934.59065409427, 38336.80831595936, 38465.615343846206, 38565.80717096396, 38652.80673596614, 39769.40345894756, 38444.0, 39590.0, 40357.798211008754, 40519.59480405158, 40580.59419405768, 30233.69766302309, 32897.142402925085, 34558.0, 35170.0, 35435.46846797249, 25602.87198563995, 25641.487170256863, 25644.61533076968, 25855.224343269732, 26285.60067597763], "value": 19681.041750431225, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.2440246050078, 346.1743741177293, 367.1463846556756, 375.44059351566165, 386.23139951496495, 19709.01454927253, 20266.189352425903, 20927.47681307968, 20947.68578471324, 21074.367768966957, 19704.21183152672, 19922.900385498087, 20605.07277172527, 20616.866072366014, 20641.483962900937, 5743.282089738793, 5823.184754134425, 5992.400759923995, 6028.095785632168, 6146.354632763562, 3931.2923673738715, 6137.969310153465, 6190.473809726156, 6190.84522886927, 6196.628202307854, 4111.773852438115, 5851.15158302046, 6145.5390845686525, 6184.505239580845, 6222.284437289702, 5906.438888305604, 6076.118962750398, 6079.331273559905, 6109.14471973924, 6201.255849298091, 5891.263592050994, 5899.351071382144, 5980.970095149496, 5984.431479009487, 5988.28140623128, 13098.934505327508, 13783.931080344635, 21943.46395752298, 25608.615870761954, 25795.613065804027, 2376.857388556692, 2495.538325409787, 2507.3731567108225, 2817.8168419052695, 2873.41095075509, 9822.165115965152, 9933.25500587459, 10455.790884182274, 13579.660508487366, 14508.202048887308, 1858.8196495225493, 1872.092035362848, 1949.0741897598668, 1994.5312851479891, 2027.2195204846241, 106394.93605063928, 118152.0, 119438.0, 122157.0, 122781.0, 11321.603743868925, 11336.263142895685, 11455.369954652408, 11621.07031437483, 11698.35659038744, 12233.898949094624, 14483.493077742229, 14560.271986400849, 14595.562133136023, 14611.196384198758, 7920.801979950432, 8137.430379873471, 8187.754367368989, 8245.13426090264, 8285.751427457186, 6527.445167160749, 7848.803779905434, 7872.291493765589, 7880.369570434352, 7957.442978991531, 3742.438634204891, 4561.338605902126, 4565.269556870885, 4600.723956562617, 4735.621150307967, 1448.992755036218, 1525.9771103433457, 1962.7840937496865, 1969.4047821264535, 2003.4290227285153, 819.2176471469733, 910.1990248581207, 946.9819943560562, 993.4039576254189, 1062.9574817007388, 19934.102965366594, 21447.8927605361, 21484.677729834064, 21568.352949411543, 21598.89200553987, 914.2092090341806, 933.9533023348805, 941.1012483078644, 943.5848226780195, 984.1290457944627, 11077.11383089351, 11319.264247823865, 11347.148963827582, 11398.08815294791, 11416.600418985296, 576804.0, 586517.0, 590113.0, 594368.0, 605297.0, 131634.0, 132757.0, 133420.0, 138619.0, 142666.0, 950.6815216902415, 997.6109317366233, 1009.1926458832904, 1019.4749703902496, 1022.3865680591585, 18669.0, 19608.90195549013, 19620.80379196247, 19708.21167153298, 19739.506512337306, 43309.350359744, 51796.741016294676, 53392.66518337073, 54309.45690543044, 56235.87528249494, 79397.0, 79439.0, 80051.79922301053, 80105.0, 80344.59827700823, 6905.930940690529, 7915.485493442908, 7932.215251568351, 7965.960170199112, 8080.636371363185, 10714.678559643224, 12933.54732584355, 13118.409671564994, 13166.40751166219, 13294.734105318033, 10052.69841904744, 10309.278350515391, 11210.83183752228, 11225.494852731483, 11281.943590281997, 10865.945670271598, 10997.505112270128, 11077.0, 11338.546458141582, 11364.14768892353, 11186.04918581897, 11494.367809770209, 11641.126915481203, 11664.325737392694, 11726.94136529312, 6492.935070649233, 6502.252240992158, 7469.962650186714, 7593.5823529704185, 7621.923780762343, 9373.25013998892, 9880.654177104054, 11121.221514493907, 11433.0, 11577.305361678325], "value": 9521.465542242762, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1293.0173068467964, 1487.5016869348767, 1512.3723654683306, 1799.8830076045065, 1947.9610407791836, 6950.582965022102, 7942.880856787153, 8455.154484551542, 8981.59582818773, 9045.90954090459, 8514.787130321745, 8800.77998050049, 8812.118788121184, 8866.157715017063, 8899.955500222506, 2013.5268211970176, 2348.3776799148195, 2360.9173678921243, 2382.797462215705, 2399.9760002400017, 1966.6263409952135, 2332.3003099070297, 2389.5937690592555, 2414.263649586875, 2439.3413778279855, 2386.0932845518764, 2386.677798497198, 2397.6043952747864, 2401.5076909233567, 2404.915827946022, 2350.471143992588, 2360.1267531013636, 2361.49227915998, 2371.3834403055243, 2383.4637206628545, 2355.6466530020634, 2359.752226016261, 2363.019346971005, 2364.4679947011955, 2367.5620010297976, 8819.162179592993, 8982.550872456351, 8989.595468203946, 9003.369764116514, 9012.0, 5259.579233661298, 5269.499397557264, 5310.60170487215, 5363.06146424378, 5363.892722145535, 3451.4132597458242, 3570.8571657133684, 3609.9097522561883, 3660.890173294779, 3663.523741913534, 1034.7620047389144, 1064.8562444070028, 1071.8285074388061, 1074.7957888001333, 1093.5625749700184, 9891.258155638356, 10775.622853200179, 11063.004329610374, 11565.653030409101, 11699.590514331958, 4678.953210467852, 4723.291506274018, 4778.776524490898, 4838.782254798542, 4876.463589005207, 9853.211743060681, 9972.950135249277, 9977.700668979942, 9990.050945160197, 10009.599616015423, 5286.0, 5287.339082614673, 5293.8882834605165, 5294.947050529446, 5300.787968481295, 4385.166818304512, 4607.35497030416, 4921.064997650435, 4936.703797772145, 4936.777844996984, 3439.6216416194197, 3939.9015024624614, 3941.940870886939, 4086.1010577672864, 4134.91730165401, 1258.0250306012913, 1313.2842600782596, 1336.3184775764237, 1340.1490053815703, 1340.4370164530922, 768.7847402727245, 790.7943934576937, 793.9595240232662, 807.9313258373104, 918.6266531535264, 4941.579965702884, 4953.90092198154, 5919.112133180058, 6125.9693701531205, 6136.079588061737, 383.33874067233796, 385.6875930496307, 437.29595351484085, 441.62682533259346, 459.9356090147382, 3622.692071173927, 3982.482277303932, 4016.477857878457, 4077.26609210345, 4105.85629502966, 9241.12209340125, 10449.0, 10693.465326733633, 10710.678679639623, 10865.619703310345, 10646.307989980627, 10787.0, 10793.67618971432, 10796.67609971702, 10817.350958942488, 8072.192780721881, 8074.273315401642, 8285.917140828635, 8294.336453083739, 8300.792480187924, 4802.711837289774, 5223.895522089461, 5699.401562836008, 5900.409959004065, 5974.790882319098, 9766.316357854881, 9983.051610097029, 9988.450635214842, 9997.500124993576, 10093.747656308651, 9966.900330996887, 10141.391516509033, 10148.594056237667, 10175.338602990782, 10248.282620216512, 6632.635205063768, 7463.104427468738, 7464.552126872405, 7544.886826697494, 7559.130699969354, 8302.335813135056, 8360.205780450848, 8462.407631465738, 8476.49141051539, 8481.236688698047, 7385.741499047507, 7558.508696934681, 7582.772516824504, 7622.504537205064, 7726.575038372944, 7319.304666056716, 7382.815429614087, 7405.740799072007, 7425.70297188128, 7492.40060795146, 7019.543729657556, 7029.3673569379, 7054.0, 7069.9646501767165, 7079.07971963662, 4918.6065114791445, 4994.425641051325, 5089.211172268304, 5132.30713853625, 5134.306868572695, 5016.272640467038, 5089.287499749964, 5156.097682905552, 5185.818496352609, 5219.451957544403], "value": 4469.444152302489, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.63417313790603, 302.39219169469374, 312.1805261189378, 315.1035304558531, 319.09695561560784, 4936.35827342445, 5201.739913004353, 5639.323281206256, 5708.771649134035, 5757.934782065321, 5348.679079255247, 5506.8335706968455, 5594.14698503763, 5600.887982240352, 5620.494155526002, 4235.491740991078, 4278.529361770203, 4289.806958686867, 4293.957060429387, 4315.395844581761, 3970.82131304092, 4146.834126634946, 4250.808713607895, 4252.319628859384, 4335.609795118438, 4210.389493523453, 4225.450691410112, 4234.894127646803, 4254.595813397722, 4256.787160641971, 3773.2453509298252, 4250.553691862372, 4253.106847562016, 4257.552956939523, 4282.400463935051, 3015.42706885691, 3082.4297504961655, 3272.345530893807, 3475.852968520382, 3532.417151170054, 4563.361129441881, 4951.282064100721, 5177.637565370425, 5445.292112025411, 5518.089515229983, 4945.406551213841, 4969.509147255809, 4977.800887964477, 4981.3810511583515, 4986.700797952134, 5568.470995255444, 5964.850878728023, 5973.163757074013, 5995.700214989233, 6007.8197654070445, 3473.565804274466, 3716.0709822544363, 3861.517310336208, 3983.342748446503, 3997.560268370477, 5407.270018547485, 5484.890302193934, 6291.370862913764, 6322.715477803464, 6346.333634968308, 3885.300645883768, 3896.649301562873, 4017.4978127734034, 4053.7365071270274, 4064.532578753422, 3546.7871927684423, 3751.2872554214614, 3905.1408690088133, 3933.38128921393, 4086.0, 4532.614727748179, 4830.951690483121, 4845.418549773979, 4845.6608037437745, 4854.2233242681, 5579.804706835242, 5769.711514424262, 6032.215811944507, 6039.214902062791, 6040.607360521552, 1314.737052589486, 1336.1248382309602, 1361.3533571553432, 1373.6085215713474, 1443.942242310317, 894.1460904835866, 904.2088172848757, 932.7248461703814, 947.5025611553989, 999.9450030248261, 4318.892027699269, 4485.282354823278, 4633.980524284652, 4650.442101895836, 4657.790399432034, 4667.719936803803, 5071.548085807698, 5090.287359769635, 5092.759185958166, 5139.331886854759, 4131.710780245354, 4431.844885429059, 4686.601638860667, 4710.670253082251, 4710.9635880106325, 2619.188051703978, 2684.718104598989, 2704.594310853388, 2705.91882243533, 2707.7156898525764, 4869.488703686133, 4922.58158056562, 4924.926126108111, 5287.894242115213, 5290.841274761687, 4592.632589392841, 4981.808004478018, 5133.768980395891, 5227.7326670830735, 5236.502532259429, 4602.539746025438, 4843.927341089816, 4980.8256711015665, 5000.6249531284575, 5150.587952963754, 4715.5243497164265, 4948.505149485023, 5067.898642027212, 5069.389264528697, 5080.237964305236, 4373.3877257183385, 4435.892950848322, 4700.529947005272, 5019.824306149268, 5065.265536497261, 4349.282033596774, 4620.330052142488, 4708.4349878014855, 5284.26020357158, 5289.761960711854, 3189.1070500259966, 3441.449368101192, 3520.6973419833803, 3781.6785573226584, 4051.0, 6031.728572214349, 6061.36355682647, 6064.757409703562, 6077.665728384981, 6084.574079814371, 5604.859878502907, 5849.53203743708, 5856.0, 5877.588568800314, 5890.0, 4779.498152694056, 4946.183879659816, 5016.821047053866, 5158.767855446439, 5781.392953739797, 5132.897342053213, 5741.971290143522, 5766.740496677743, 5780.913286300793, 5809.360970293349, 4675.602573781267, 4710.845842768406, 4850.638556255588, 5863.325717542537, 5935.525157987437, 4769.833055843029, 4982.501749824988, 5611.184394611703, 5681.801136960352, 5774.199513236161], "value": 4051.02767088471, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.5968177979438, 782.3271986091963, 792.8533221354049, 815.584052133412, 821.7165078048079, 15287.69424611507, 15680.215989200551, 16196.919015404921, 16206.432774852883, 16217.026978381291, 12346.506139754421, 14719.926400367984, 14944.103353798753, 14967.700645987074, 15042.247887605603, 879.8812160358334, 904.9411788233776, 911.4166933162782, 931.1154403316842, 935.1163150822475, 905.1536813079758, 921.6820197032047, 924.2605915267759, 926.3006430145231, 930.1767935377206, 969.1907257440046, 969.6363863551169, 975.2149519636689, 977.2621670638658, 980.1717548671361, 936.8735220745215, 965.2760429677742, 965.874436323277, 966.8743063401748, 975.8341082016075, 713.9892901606428, 732.2531018361295, 732.4470025130992, 732.7545272333774, 733.5745267744695, 13528.188308701509, 13548.45806167752, 13709.520166794115, 13732.862671373257, 13774.93112534441, 11704.531818727326, 11952.103592230618, 12064.336461494615, 12114.0, 12567.497300107985, 8885.0, 10221.540030698561, 10766.24636275461, 10826.945865270623, 10951.397673127974, 3635.963640363589, 3663.5420572428447, 3686.8156592170553, 3707.1102935295603, 3835.7506762060375, 85377.57311213404, 107642.46178769134, 109999.45000275028, 110222.44888775585, 111782.44108779567, 9071.092890710876, 9084.81830363389, 9144.3598948073, 9255.35212535116, 9498.620055197853, 19928.20287188496, 19936.401907942785, 20016.099275532637, 20018.399448016582, 20102.39692809218, 7750.689972401041, 7895.868115608753, 7934.841303173905, 8027.5584842834205, 8169.142240064826, 4151.813168407429, 4235.745855248695, 4256.124278032276, 4312.956870431318, 4353.869383918487, 3450.6721861423625, 3497.2655742293896, 3538.858445662196, 3561.554805649294, 3571.982140089283, 548.7722595123059, 640.5228105061666, 641.5124505375854, 647.4464332995316, 661.4377778887907, 598.3328588623654, 670.6814263225027, 687.285223367702, 690.6719308328604, 703.9577625342394, 8507.0, 9109.817803644022, 9302.581383837743, 9347.813043739086, 9430.339876208736, 651.6546230497844, 653.4870126950321, 657.3196741372657, 668.2315337361975, 689.8861687821553, 3747.793871337048, 3773.547174339042, 4404.207242696281, 4661.114388266218, 4698.8120475181295, 95804.04195957951, 103308.0, 151120.2443987795, 152818.0, 155970.0, 125643.0, 130160.69839301486, 131690.0, 132877.0, 133915.0, 111065.0, 112694.87305127007, 114413.0, 114762.0, 117113.4144329273, 7225.060742103493, 8893.110688931056, 8983.907269303481, 9005.099490050943, 9088.681896133603, 56415.71792141013, 63323.0, 63902.0, 65153.348466514726, 65341.346586533524, 60824.0, 61352.0, 87504.68742968733, 93698.0, 95633.0, 11995.64013079609, 12037.75924481523, 12075.094367922266, 12121.878781212075, 12255.142140050111, 12881.48474061027, 12929.870701292866, 12950.004466545694, 13336.93331533375, 13613.387397566936, 11840.76318473643, 11844.881551184722, 12083.154179207722, 12253.264804111783, 12536.811947820972, 12105.69735756613, 12241.816372754238, 12246.44890979926, 12277.20198187115, 12697.682557936125, 12034.458449369624, 12075.27548347102, 12180.0, 12486.750264994831, 12512.937435312766, 9706.320557560903, 9871.703848884545, 9962.0, 10056.899431005597, 10271.229657775846, 9268.814623707352, 9828.606855725691, 9889.85165222508, 9900.950495247478, 9918.950405247928], "value": 7547.829085504526, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1567.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 117.0, 115.0, 120.0, 117.0, 113.0, 115.0, 117.0, 116.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 118.0, 100.0, 182.0, 147.0, 119.0, 141.0, 145.0, 179.0, 116.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 201.0, 205.0, 234.0, 199.0, 196.0, 198.0, 195.0, 197.0, 197.0], "value": 202.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 59.0, 92.0, 65.0, 85.0, 84.0, 58.0, 80.0, 79.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 90.0, 78.0, 55.0, 70.0, 113.0, 98.0, 101.0, 108.0], "value": 86.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 77.0, 137.0, 86.0, 97.0, 131.0, 89.0, 86.0, 89.0, 89.0], "value": 97.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 100.0, 97.0, 98.0, 99.0, 97.0, 98.0, 102.0, 98.0, 99.0], "value": 100.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 86.0, 87.0, 121.0, 89.0, 91.0, 93.0, 88.0, 86.0], "value": 93.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 78.0, 69.0, 51.0, 55.0, 57.0, 53.0, 85.0, 77.0, 85.0], "value": 66.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 89.0, 104.0, 83.0, 80.0, 77.0, 73.0, 73.0, 72.0, 71.0], "value": 78.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 90.0, 88.0, 92.0, 94.0, 90.0, 88.0, 95.0, 90.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 143.0, 160.0, 145.0, 163.0, 150.0, 163.0, 142.0, 193.0, 295.0], "value": 171.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 175.0, 194.0, 177.0, 176.0, 174.0, 173.0, 173.0, 176.0], "value": 176.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 72.0, 82.0, 91.0, 76.0, 69.0, 67.0, 71.0, 81.0], "value": 74.9, "unit": "score"}]}]} 06:03:27 INFO - Return code: 0 06:03:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:03:27 ERROR - # TBPL SUCCESS # 06:03:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:03:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:03:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:03:27 INFO - 2016-10-18 06:03:27.343 screenresolution[6555:116208] starting screenresolution argv=screenresolution get 06:03:27 INFO - 2016-10-18 06:03:27.345 screenresolution[6555:116208] Display 0: 1600x1200x32@60 06:03:27 INFO - 2016-10-18 06:03:27.351 screenresolution[6556:116211] starting screenresolution argv=screenresolution list 06:03:27 INFO - Available Modes on Display 0 06:03:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:03:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:03:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:03:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:03:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:03:27 INFO - Intel Iris: 06:03:27 INFO - Chipset Model: Intel Iris 06:03:27 INFO - Type: GPU 06:03:27 INFO - Bus: Built-In 06:03:27 INFO - VRAM (Dynamic, Max): 1536 MB 06:03:27 INFO - Vendor: Intel (0x8086) 06:03:27 INFO - Device ID: 0x0a2e 06:03:27 INFO - Revision ID: 0x0009 06:03:27 INFO - Displays: 06:03:27 INFO - Display: 06:03:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:03:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:03:27 INFO - Display Serial Number: 1600x1200 60 06:03:27 INFO - Main Display: Yes 06:03:27 INFO - Mirror: Off 06:03:27 INFO - Online: Yes 06:03:27 INFO - Rotation: Supported 06:03:27 INFO - Return code: 0 06:03:27 INFO - Running post-action listener: _resource_record_post_action 06:03:27 INFO - [mozharness: 2016-10-18 13:03:27.592990Z] Finished run-tests step (success) 06:03:27 INFO - Running post-run listener: _resource_record_post_run 06:03:27 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 3959808; Write bytes: 710744064; Read time: 91; Write time: 1978 06:03:27 INFO - TinderboxPrint: CPU usage
54.9% 06:03:27 INFO - TinderboxPrint: I/O read bytes / time
3,959,808 / 91 06:03:27 INFO - TinderboxPrint: I/O write bytes / time
710,744,064 / 1,978 06:03:27 INFO - TinderboxPrint: CPU idle
2,186.9 (45.1%) 06:03:27 INFO - TinderboxPrint: CPU system
985.1 (20.3%) 06:03:27 INFO - TinderboxPrint: CPU user
1,673.8 (34.5%) 06:03:27 INFO - TinderboxPrint: Swap in / out
408,477,696 / 0 06:03:27 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397527552; Write bytes: 391579136; Read time: 29803; Write time: 740 06:03:27 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 3238912; Write bytes: 300945920; Read time: 74; Write time: 1195 06:03:28 INFO - Running post-run listener: _upload_blobber_files 06:03:28 INFO - Blob upload gear active. 06:03:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:03:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:03:28 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:03:28 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:03:28 INFO - (blobuploader) - INFO - Open directory for files ... 06:03:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:03:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:03:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:03:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:03:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:03:29 INFO - (blobuploader) - INFO - Done attempting. 06:03:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:03:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:03:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:03:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:03:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:03:30 INFO - (blobuploader) - INFO - Done attempting. 06:03:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:03:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:03:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:03:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:03:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:03:34 INFO - (blobuploader) - INFO - Done attempting. 06:03:34 INFO - (blobuploader) - INFO - Iteration through files over. 06:03:34 INFO - Return code: 0 06:03:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:03:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:03:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f9112e80242d13f76487e7a607c5a64e64250570016d4aae21643918921c5b9e382788a034ed3d3860e319c63b8bad4f0c2a087039268d39645555a6c8a3e45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f369a23539a47f3e13ecd2bbb08419594abf9c97510270bdcc7e0267c668a6a64b756485e6b057ee4d351ae36751044068f2e8c2649efcf0449ae2039fe9dc53", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7605b93692eba22e469a317a8b13187ef2ea36751926617c7e05b915a3ed9f0ada1c33d51f335170af3056d17ab9707639f309e9eac4cee382cb0e58cec5d01"} 06:03:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:03:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:03:34 INFO - Contents: 06:03:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f9112e80242d13f76487e7a607c5a64e64250570016d4aae21643918921c5b9e382788a034ed3d3860e319c63b8bad4f0c2a087039268d39645555a6c8a3e45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f369a23539a47f3e13ecd2bbb08419594abf9c97510270bdcc7e0267c668a6a64b756485e6b057ee4d351ae36751044068f2e8c2649efcf0449ae2039fe9dc53", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7605b93692eba22e469a317a8b13187ef2ea36751926617c7e05b915a3ed9f0ada1c33d51f335170af3056d17ab9707639f309e9eac4cee382cb0e58cec5d01"} 06:03:34 INFO - Running post-run listener: copy_logs_to_upload_dir 06:03:34 INFO - Copying logs to upload dir... 06:03:34 INFO - mkdir: /builds/slave/test/build/upload/logs 06:03:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.813845 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-18 06:03:34.898265) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 06:03:34.905347) ========= 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.kskTAnsDIm/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.oba2rbehHu/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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/Fx-Team/sha512/1f9112e80242d13f76487e7a607c5a64e64250570016d4aae21643918921c5b9e382788a034ed3d3860e319c63b8bad4f0c2a087039268d39645555a6c8a3e45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f369a23539a47f3e13ecd2bbb08419594abf9c97510270bdcc7e0267c668a6a64b756485e6b057ee4d351ae36751044068f2e8c2649efcf0449ae2039fe9dc53", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7605b93692eba22e469a317a8b13187ef2ea36751926617c7e05b915a3ed9f0ada1c33d51f335170af3056d17ab9707639f309e9eac4cee382cb0e58cec5d01"} build_url:https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011695 build_url: 'https://queue.taskcluster.net/v1/task/RDR-xggoT3-4l3i99XJe2w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f9112e80242d13f76487e7a607c5a64e64250570016d4aae21643918921c5b9e382788a034ed3d3860e319c63b8bad4f0c2a087039268d39645555a6c8a3e45", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f369a23539a47f3e13ecd2bbb08419594abf9c97510270bdcc7e0267c668a6a64b756485e6b057ee4d351ae36751044068f2e8c2649efcf0449ae2039fe9dc53", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7605b93692eba22e469a317a8b13187ef2ea36751926617c7e05b915a3ed9f0ada1c33d51f335170af3056d17ab9707639f309e9eac4cee382cb0e58cec5d01"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 06:03:34.941171) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 06:03:34.941492) ========= 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.kskTAnsDIm/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.oba2rbehHu/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.005360 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 06:03:34.989571) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 06:03:34.989859) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 06:03:34.993185) ========= ========= Total master_lag: 0.19 =========