builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0163 starttime: 1480078355.33 results: success (0) buildid: 20161125015119 builduid: 7ca95eb8e8454b6a86afa2292d008894 revision: 1d31baa686c55268e4ec5364704b8ea5d7c33fe2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 04:52:35.330997) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 04:52:35.331440) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 04:52:35.331734) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 04:52:35.361848) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 04:52:35.362225) ========= 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.xjSrBRUqdi/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.gJx5y85AyX/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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-11-25 04:52:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.63M=0.002s 2016-11-25 04:52:35 (7.63 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128643 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 04:52:35.514182) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 04:52:35.514494) ========= 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.xjSrBRUqdi/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.gJx5y85AyX/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.019516 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 04:52:35.565513) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 04:52:35.565870) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 1d31baa686c55268e4ec5364704b8ea5d7c33fe2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev 1d31baa686c55268e4ec5364704b8ea5d7c33fe2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xjSrBRUqdi/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.gJx5y85AyX/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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-11-25 04:52:35,649 truncating revision to first 12 chars 2016-11-25 04:52:35,649 Setting DEBUG logging. 2016-11-25 04:52:35,649 attempt 1/10 2016-11-25 04:52:35,649 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/1d31baa686c5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 04:52:37,222 unpacking tar archive at: cedar-1d31baa686c5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.807847 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 04:52:37.397034) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 04:52:37.397348) ========= script_repo_revision: 1d31baa686c55268e4ec5364704b8ea5d7c33fe2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 04:52:37.399107) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 04:52:37.401668) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 04:52:37.421446) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-25 04:52:37.421835) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xjSrBRUqdi/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.gJx5y85AyX/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:52:37 INFO - MultiFileLogger online at 20161125 04:52:37 in /builds/slave/test 04:52:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 04:52:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:52:37 INFO - {'append_to_log': False, 04:52:37 INFO - 'base_work_dir': '/builds/slave/test', 04:52:37 INFO - 'blob_upload_branch': 'Cedar', 04:52:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:52:37 INFO - 'branch': 'Cedar', 04:52:37 INFO - 'buildbot_json_path': 'buildprops.json', 04:52:37 INFO - 'config_files': ('talos/mac_config.py',), 04:52:37 INFO - 'default_actions': ('clobber', 04:52:37 INFO - 'read-buildbot-config', 04:52:37 INFO - 'download-and-extract', 04:52:37 INFO - 'populate-webroot', 04:52:37 INFO - 'create-virtualenv', 04:52:37 INFO - 'install', 04:52:37 INFO - 'run-tests'), 04:52:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:52:37 INFO - 'download_minidump_stackwalk': True, 04:52:37 INFO - 'download_symbols': 'ondemand', 04:52:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:52:37 INFO - 'tooltool.py': '/tools/tooltool.py', 04:52:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:52:37 INFO - '/tools/misc-python/virtualenv.py')}, 04:52:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:52:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:52:37 INFO - 'installer_path': 'installer.exe', 04:52:37 INFO - 'log_level': 'info', 04:52:37 INFO - 'log_name': 'talos', 04:52:37 INFO - 'log_to_console': True, 04:52:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:52:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:52:37 INFO - 'opt_config_files': (), 04:52:37 INFO - 'pip_index': False, 04:52:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:52:37 INFO - 'cmd': ('bash', 04:52:37 INFO - '-c', 04:52:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:52:37 INFO - 'enabled': True, 04:52:37 INFO - 'halt_on_failure': False, 04:52:37 INFO - 'name': 'check_screen_resolution'},), 04:52:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:52:37 INFO - 'cmd': ('bash', 04:52:37 INFO - '-c', 04:52:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:52:37 INFO - 'enabled': True, 04:52:37 INFO - 'halt_on_failure': False, 04:52:37 INFO - 'name': 'check_screen_resolution'},), 04:52:37 INFO - 'run_cmd_checks_enabled': True, 04:52:37 INFO - 'sps_profile': False, 04:52:37 INFO - 'sps_profile_interval': 0, 04:52:37 INFO - 'suite': 'dromaeojs-e10s', 04:52:37 INFO - 'system_bits': '32', 04:52:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:52:37 INFO - 'title': 't-yosemite-r7-0163', 04:52:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:52:37 INFO - 'use_talos_json': True, 04:52:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:52:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:52:37 INFO - 'work_dir': 'build'} 04:52:37 INFO - [mozharness: 2016-11-25 12:52:37.633591Z] Running clobber step. 04:52:37 INFO - Running pre-action listener: _resource_record_pre_action 04:52:37 INFO - Running main action method: clobber 04:52:37 INFO - rmtree: /builds/slave/test/build 04:52:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:52:39 INFO - Running post-action listener: _resource_record_post_action 04:52:39 INFO - [mozharness: 2016-11-25 12:52:39.758837Z] Finished clobber step (success) 04:52:39 INFO - [mozharness: 2016-11-25 12:52:39.758995Z] Running read-buildbot-config step. 04:52:39 INFO - Running pre-action listener: _resource_record_pre_action 04:52:39 INFO - Running main action method: read_buildbot_config 04:52:39 INFO - Using buildbot properties: 04:52:39 INFO - { 04:52:39 INFO - "project": "", 04:52:39 INFO - "product": "firefox", 04:52:39 INFO - "script_repo_revision": "default", 04:52:39 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 04:52:39 INFO - "repository": "", 04:52:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 04:52:39 INFO - "buildid": "20161125015119", 04:52:39 INFO - "pgo_build": "False", 04:52:39 INFO - "basedir": "/builds/slave/test", 04:52:39 INFO - "buildnumber": 1, 04:52:39 INFO - "slavename": "t-yosemite-r7-0163", 04:52:39 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:52:39 INFO - "platform": "yosemite_r7", 04:52:39 INFO - "scheduler": "tests-cedar-macosx64-talos", 04:52:39 INFO - "branch": "cedar", 04:52:39 INFO - "repo_path": "projects/cedar", 04:52:39 INFO - "revision": "1d31baa686c55268e4ec5364704b8ea5d7c33fe2", 04:52:39 INFO - "stage_platform": "macosx64", 04:52:39 INFO - "builduid": "7ca95eb8e8454b6a86afa2292d008894", 04:52:39 INFO - "slavebuilddir": "test" 04:52:39 INFO - } 04:52:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:52:39 INFO - Running post-action listener: _resource_record_post_action 04:52:39 INFO - [mozharness: 2016-11-25 12:52:39.762674Z] Finished read-buildbot-config step (success) 04:52:39 INFO - [mozharness: 2016-11-25 12:52:39.762812Z] Running download-and-extract step. 04:52:39 INFO - Running pre-action listener: _resource_record_pre_action 04:52:39 INFO - Running main action method: download_and_extract 04:52:39 INFO - mkdir: /builds/slave/test/build/tests 04:52: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')]} 04:52:39 INFO - trying https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:52:39 INFO - Downloading https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:52:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 04:52:41 INFO - Downloaded 1199 bytes. 04:52:41 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:52:41 INFO - Using the following test package requirements: 04:52:41 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:52:41 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:52:41 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:52:41 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:52:41 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:52:41 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:52:41 INFO - u'jsshell-mac.zip'], 04:52:41 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:52:41 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:52:41 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:52:41 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:52:41 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:52:41 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:52:41 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:52:41 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:52:41 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:52:41 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:52:41 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:52:41 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:52:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:52:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:52:41 INFO - Fetch https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:52:41 INFO - Http code: 200 04:52:41 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 04:52:41 INFO - x-amz-cf-id: G47KeoT0GsrGbkOLByOS5IYILQfBrzPUBlssKLZccVR98Kqn9MPAXw== 04:52:41 INFO - x-amz-version-id: MGYvlExjzahnIXtDDmt.75AHCNW.HBnh 04:52:41 INFO - Expected file size: 16268544 04:52:41 INFO - Obtained file size: 16268544 04:52:48 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:52:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:52:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:52:48 INFO - Fetch https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:52:49 INFO - Http code: 200 04:52:49 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 04:52:49 INFO - x-amz-cf-id: WT_E-2LjtmwNxG_d32N3pqvtzti3D18DJdio3Mv8Ms43f3kSgIpnpg== 04:52:49 INFO - x-amz-version-id: MGYvlExjzahnIXtDDmt.75AHCNW.HBnh 04:52:49 INFO - Expected file size: 16268544 04:52:49 INFO - Obtained file size: 16268544 04:52:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:52:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:52:56 INFO - Fetch https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:52:56 INFO - Http code: 200 04:52:56 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 04:52:56 INFO - x-amz-cf-id: pkx18rIavrxBAk9bVvZl5B7c3uaV0wJuJlsBghWnZ2wUnH_Gy-lO_w== 04:52:56 INFO - x-amz-version-id: MaAJSqympUPLZN9k_Mw.sV5EGKUb4V8k 04:52:57 INFO - Expected file size: 14409749 04:52:57 INFO - Obtained file size: 14409749 04:52:57 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')]} 04:52:57 INFO - trying https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:52:57 INFO - Downloading https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:52:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 04:53:00 INFO - Downloaded 107594624 bytes. 04:53:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:53:00 INFO - mkdir: /builds/slave/test/properties 04:53:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:53:00 INFO - Writing to file /builds/slave/test/properties/build_url 04:53:00 INFO - Contents: 04:53:00 INFO - build_url:https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:53:00 INFO - Running post-action listener: _resource_record_post_action 04:53:00 INFO - Running post-action listener: set_extra_try_arguments 04:53:00 INFO - [mozharness: 2016-11-25 12:53:00.903540Z] Finished download-and-extract step (success) 04:53:00 INFO - [mozharness: 2016-11-25 12:53:00.903703Z] Running populate-webroot step. 04:53:00 INFO - Running pre-action listener: _resource_record_pre_action 04:53:00 INFO - Running main action method: populate_webroot 04:53:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:53:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:53:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:53:00 INFO - '--tppagecycles', 04:53:00 INFO - '7'], 04:53:00 INFO - 'tests': ['tsvgm']}}, 04:53:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:53:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:53:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:53:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:53:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:53:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:53:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:53:00 INFO - 'talos_options': ['--disable-e10s'], 04:53:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:53:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:53:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:53:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:53:00 INFO - 'talos_options': ['--disable-e10s'], 04:53:00 INFO - 'tests': ['damp', 'tps']}, 04:53:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:53:00 INFO - 'tests': ['damp', 'tps']}, 04:53:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:53:00 INFO - 'tests': ['dromaeo_dom']}, 04:53:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:53:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:53:00 INFO - 'tests': ['basic_compositor_video']}, 04:53:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:53:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:53:00 INFO - 'tests': ['a11yr', 04:53:00 INFO - 'ts_paint', 04:53:00 INFO - 'tpaint', 04:53:00 INFO - 'sessionrestore', 04:53:00 INFO - 'sessionrestore_no_auto_restore', 04:53:00 INFO - 'tabpaint']}, 04:53:00 INFO - 'other-e10s': {'tests': ['a11yr', 04:53:00 INFO - 'ts_paint', 04:53:00 INFO - 'tpaint', 04:53:00 INFO - 'sessionrestore', 04:53:00 INFO - 'sessionrestore_no_auto_restore', 04:53:00 INFO - 'tabpaint']}, 04:53:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:53:00 INFO - 'ts_paint', 04:53:00 INFO - 'tpaint', 04:53:00 INFO - 'sessionrestore', 04:53:00 INFO - 'sessionrestore_no_auto_restore', 04:53:00 INFO - 'tabpaint']}, 04:53:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:53:00 INFO - 'ts_paint', 04:53:00 INFO - 'tpaint', 04:53:00 INFO - 'sessionrestore', 04:53:00 INFO - 'sessionrestore_no_auto_restore', 04:53:00 INFO - 'tabpaint']}, 04:53:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:53:00 INFO - 'tests': ['a11yr', 04:53:00 INFO - 'ts_paint', 04:53:00 INFO - 'tpaint', 04:53:00 INFO - 'sessionrestore', 04:53:00 INFO - 'sessionrestore_no_auto_restore', 04:53:00 INFO - 'tabpaint']}, 04:53:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:53:00 INFO - 'tests': ['a11yr', 04:53:00 INFO - 'ts_paint', 04:53:00 INFO - 'tpaint', 04:53:00 INFO - 'sessionrestore', 04:53:00 INFO - 'sessionrestore_no_auto_restore', 04:53:00 INFO - 'tabpaint']}, 04:53:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:53:00 INFO - 'tests': ['tsvgx', 04:53:00 INFO - 'tsvgr_opacity', 04:53:00 INFO - 'tart', 04:53:00 INFO - 'tscrollx', 04:53:00 INFO - 'cart', 04:53:00 INFO - 'tsvg_static']}, 04:53:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:53:00 INFO - 'tsvgr_opacity', 04:53:00 INFO - 'tart', 04:53:00 INFO - 'tscrollx', 04:53:00 INFO - 'cart', 04:53:00 INFO - 'tsvg_static']}, 04:53:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:53:00 INFO - 'talos_options': ['--disable-e10s'], 04:53:00 INFO - 'tests': ['tp5o']}, 04:53:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:53:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:53:00 INFO - 'talos_options': ['--disable-e10s', 04:53:00 INFO - '--xperf_path', 04:53:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:53:00 INFO - 'tests': ['tp5n']}, 04:53:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:53:00 INFO - 'talos_options': ['--xperf_path', 04:53:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:53:00 INFO - 'tests': ['tp5n']}}, 04:53:00 INFO - 'talos.zip': {'path': '', 04:53:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:53:00 INFO - Running post-action listener: _resource_record_post_action 04:53:00 INFO - [mozharness: 2016-11-25 12:53:00.922181Z] Finished populate-webroot step (success) 04:53:00 INFO - [mozharness: 2016-11-25 12:53:00.922304Z] Running create-virtualenv step. 04:53:00 INFO - Running pre-action listener: _resource_record_pre_action 04:53:00 INFO - Running main action method: create_virtualenv 04:53:00 INFO - Creating virtualenv /builds/slave/test/build/venv 04:53:00 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:53:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:53:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:53:01 INFO - Using real prefix '/tools/python27' 04:53:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:53:01 INFO - Installing distribute.............................................................................................................................................................................................done. 04:53:04 INFO - Installing pip.................done. 04:53:04 INFO - Return code: 0 04:53:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:53:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:53:04 INFO - Reading from file tmpfile_stdout 04:53:04 INFO - Output received: 04:53:04 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:53:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:53:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10796b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f80dbc170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107781d30>, '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 0x1075e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.xjSrBRUqdi/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.gJx5y85AyX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:53:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:53:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:53:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xjSrBRUqdi/Render', 04:53:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:04 INFO - 'HOME': '/Users/cltbld', 04:53:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:04 INFO - 'LOGNAME': 'cltbld', 04:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:04 INFO - 'MOZ_NO_REMOTE': '1', 04:53:04 INFO - 'NO_EM_RESTART': '1', 04:53:04 INFO - 'PAGER': '/bin/cat', 04:53:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:04 INFO - 'PWD': '/builds/slave/test', 04:53:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:04 INFO - 'SHELL': '/bin/bash', 04:53:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJx5y85AyX/Listeners', 04:53:04 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:53:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:04 INFO - 'USER': 'cltbld', 04:53:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:04 INFO - 'XPC_FLAGS': '0x0', 04:53:04 INFO - 'XPC_SERVICE_NAME': '0', 04:53:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:05 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 04:53:05 INFO - Cleaning up... 04:53:05 INFO - Return code: 0 04:53:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:53:05 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')]} 04:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:05 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')]} 04:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:05 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 0x10796b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f80dbc170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107781d30>, '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 0x1075e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.xjSrBRUqdi/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.gJx5y85AyX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:53:05 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 04:53:05 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 04:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xjSrBRUqdi/Render', 04:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:05 INFO - 'HOME': '/Users/cltbld', 04:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:05 INFO - 'LOGNAME': 'cltbld', 04:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:05 INFO - 'MOZ_NO_REMOTE': '1', 04:53:05 INFO - 'NO_EM_RESTART': '1', 04:53:05 INFO - 'PAGER': '/bin/cat', 04:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:05 INFO - 'PWD': '/builds/slave/test', 04:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:05 INFO - 'SHELL': '/bin/bash', 04:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJx5y85AyX/Listeners', 04:53:05 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:05 INFO - 'USER': 'cltbld', 04:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:05 INFO - 'XPC_FLAGS': '0x0', 04:53:05 INFO - 'XPC_SERVICE_NAME': '0', 04:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:05 INFO - Downloading/unpacking psutil>=3.1.1 04:53:05 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 04:53:05 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 04:53:05 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 04:53:05 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 04:53:05 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 04:53:05 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 04:53:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:53:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:53:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:53:08 INFO - Installing collected packages: psutil 04:53:08 INFO - Running setup.py install for psutil 04:53:09 INFO - building 'psutil._psutil_osx' extension 04:53:09 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 04:53:09 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 04:53:09 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 04:53:09 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 04:53:09 INFO - building 'psutil._psutil_posix' extension 04:53:09 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 04:53:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:53:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:53:09 INFO - ^ 04:53:09 INFO - 1 warning generated. 04:53:09 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 04:53:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:53:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:53:09 INFO - Successfully installed psutil 04:53:09 INFO - Cleaning up... 04:53:09 INFO - Return code: 0 04:53:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:53:09 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')]} 04:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:09 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')]} 04:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:09 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 0x10796b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f80dbc170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107781d30>, '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 0x1075e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.xjSrBRUqdi/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.gJx5y85AyX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:53:09 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 04:53:09 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 04:53:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xjSrBRUqdi/Render', 04:53:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:09 INFO - 'HOME': '/Users/cltbld', 04:53:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:09 INFO - 'LOGNAME': 'cltbld', 04:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:09 INFO - 'MOZ_NO_REMOTE': '1', 04:53:09 INFO - 'NO_EM_RESTART': '1', 04:53:09 INFO - 'PAGER': '/bin/cat', 04:53:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:09 INFO - 'PWD': '/builds/slave/test', 04:53:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:09 INFO - 'SHELL': '/bin/bash', 04:53:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJx5y85AyX/Listeners', 04:53:09 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:53:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:09 INFO - 'USER': 'cltbld', 04:53:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:09 INFO - 'XPC_FLAGS': '0x0', 04:53:09 INFO - 'XPC_SERVICE_NAME': '0', 04:53:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:53:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:53:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:53:13 INFO - Installing collected packages: mozsystemmonitor 04:53:13 INFO - Running setup.py install for mozsystemmonitor 04:53:13 INFO - Successfully installed mozsystemmonitor 04:53:13 INFO - Cleaning up... 04:53:13 INFO - Return code: 0 04:53:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:53:13 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')]} 04:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:13 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')]} 04:53:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:13 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 0x10796b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f80dbc170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107781d30>, '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 0x1075e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.xjSrBRUqdi/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.gJx5y85AyX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:53:13 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 04:53:13 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 04:53:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xjSrBRUqdi/Render', 04:53:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:13 INFO - 'HOME': '/Users/cltbld', 04:53:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:13 INFO - 'LOGNAME': 'cltbld', 04:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:13 INFO - 'MOZ_NO_REMOTE': '1', 04:53:13 INFO - 'NO_EM_RESTART': '1', 04:53:13 INFO - 'PAGER': '/bin/cat', 04:53:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:13 INFO - 'PWD': '/builds/slave/test', 04:53:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:13 INFO - 'SHELL': '/bin/bash', 04:53:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJx5y85AyX/Listeners', 04:53:13 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:53:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:13 INFO - 'USER': 'cltbld', 04:53:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:13 INFO - 'XPC_FLAGS': '0x0', 04:53:13 INFO - 'XPC_SERVICE_NAME': '0', 04:53:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:13 INFO - Downloading/unpacking jsonschema==2.5.1 04:53:13 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 04:53:13 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 04:53:13 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 04:53:13 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 04:53:13 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 04:53:13 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 04:53:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:53:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:53:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:53:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:53:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:53:16 INFO - Downloading functools32-3.2.3-2.tar.gz 04:53:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:53:16 INFO - warning: no files found matching '*.txt' 04:53:16 INFO - no previously-included directories found matching 'build' 04:53:16 INFO - no previously-included directories found matching 'dist' 04:53:16 INFO - no previously-included directories found matching '.git*' 04:53:16 INFO - Installing collected packages: jsonschema, functools32 04:53:16 INFO - Running setup.py install for functools32 04:53:16 INFO - warning: no files found matching '*.txt' 04:53:16 INFO - no previously-included directories found matching 'build' 04:53:16 INFO - no previously-included directories found matching 'dist' 04:53:16 INFO - no previously-included directories found matching '.git*' 04:53:16 INFO - Successfully installed jsonschema functools32 04:53:16 INFO - Cleaning up... 04:53:16 INFO - Return code: 0 04:53:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:53:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:16 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 0x10796b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f80dbc170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107781d30>, '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 0x1075e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.xjSrBRUqdi/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.gJx5y85AyX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:53:16 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 04:53:16 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 04:53:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xjSrBRUqdi/Render', 04:53:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:16 INFO - 'HOME': '/Users/cltbld', 04:53:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:16 INFO - 'LOGNAME': 'cltbld', 04:53:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:16 INFO - 'MOZ_NO_REMOTE': '1', 04:53:16 INFO - 'NO_EM_RESTART': '1', 04:53:16 INFO - 'PAGER': '/bin/cat', 04:53:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:16 INFO - 'PWD': '/builds/slave/test', 04:53:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:16 INFO - 'SHELL': '/bin/bash', 04:53:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJx5y85AyX/Listeners', 04:53:16 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:53:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:16 INFO - 'USER': 'cltbld', 04:53:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:16 INFO - 'XPC_FLAGS': '0x0', 04:53:16 INFO - 'XPC_SERVICE_NAME': '0', 04:53:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:53:17 INFO - Cleaning up... 04:53:17 INFO - Return code: 0 04:53:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:53:17 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')]} 04:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:17 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')]} 04:53:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:17 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 0x10796b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f80dbc170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107781d30>, '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 0x1075e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.xjSrBRUqdi/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.gJx5y85AyX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:53:17 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 04:53:17 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 04:53:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xjSrBRUqdi/Render', 04:53:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:17 INFO - 'HOME': '/Users/cltbld', 04:53:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:17 INFO - 'LOGNAME': 'cltbld', 04:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:17 INFO - 'MOZ_NO_REMOTE': '1', 04:53:17 INFO - 'NO_EM_RESTART': '1', 04:53:17 INFO - 'PAGER': '/bin/cat', 04:53:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:17 INFO - 'PWD': '/builds/slave/test', 04:53:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:17 INFO - 'SHELL': '/bin/bash', 04:53:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJx5y85AyX/Listeners', 04:53:17 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:53:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:17 INFO - 'USER': 'cltbld', 04:53:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:17 INFO - 'XPC_FLAGS': '0x0', 04:53:17 INFO - 'XPC_SERVICE_NAME': '0', 04:53:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:17 INFO - Downloading/unpacking blobuploader==1.2.4 04:53:17 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 04:53:17 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 04:53:17 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 04:53:17 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 04:53:17 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 04:53:17 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 04:53:19 INFO - Downloading blobuploader-1.2.4.tar.gz 04:53:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:53:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:53:20 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 04:53:20 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 04:53:20 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 04:53:20 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 04:53:20 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 04:53:20 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 04:53:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:53:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:53:20 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 04:53:20 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 04:53:20 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 04:53:20 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 04:53:20 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 04:53:20 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 04:53:21 INFO - Downloading docopt-0.6.1.tar.gz 04:53:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:53:21 INFO - Installing collected packages: blobuploader, requests, docopt 04:53:21 INFO - Running setup.py install for blobuploader 04:53:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:53:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:53:21 INFO - Running setup.py install for requests 04:53:21 INFO - Running setup.py install for docopt 04:53:21 INFO - Successfully installed blobuploader requests docopt 04:53:21 INFO - Cleaning up... 04:53:22 INFO - Return code: 0 04:53:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53:22 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')]} 04:53:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:22 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')]} 04:53:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:22 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 0x10796b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f80dbc170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107781d30>, '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 0x1075e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.xjSrBRUqdi/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.gJx5y85AyX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:53:22 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 04:53:22 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 04:53:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xjSrBRUqdi/Render', 04:53:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:22 INFO - 'HOME': '/Users/cltbld', 04:53:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:22 INFO - 'LOGNAME': 'cltbld', 04:53:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:22 INFO - 'MOZ_NO_REMOTE': '1', 04:53:22 INFO - 'NO_EM_RESTART': '1', 04:53:22 INFO - 'PAGER': '/bin/cat', 04:53:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:22 INFO - 'PWD': '/builds/slave/test', 04:53:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:22 INFO - 'SHELL': '/bin/bash', 04:53:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJx5y85AyX/Listeners', 04:53:22 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:53:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:22 INFO - 'USER': 'cltbld', 04:53:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:22 INFO - 'XPC_FLAGS': '0x0', 04:53:22 INFO - 'XPC_SERVICE_NAME': '0', 04:53:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:53:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-aDKSMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:53:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-41Z1Gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:53:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-1Jr1B3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:53:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-B2bUy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:53:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-gnx3_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:53:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-esXso1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:53:22 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-LASWle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:53:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-Z3I7fW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:53:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-XIlsQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:53:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-uQ30U9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:53:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-ZHK6aJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:53:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-RszBZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:53:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-lX36Ta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:53:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-ywWZND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-zveO18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:53:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-Nwqe2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:53:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:53:23 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-iiqSaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:53:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:53:24 INFO - Running setup.py install for manifestparser 04:53:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:53:24 INFO - Running setup.py install for mozcrash 04:53:24 INFO - Running setup.py install for mozdebug 04:53:24 INFO - Running setup.py install for mozdevice 04:53:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:53:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:53:24 INFO - Running setup.py install for mozfile 04:53:24 INFO - Running setup.py install for mozhttpd 04:53:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:53:24 INFO - Running setup.py install for mozinfo 04:53:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:53:25 INFO - Running setup.py install for mozInstall 04:53:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:53:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:53:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:53:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:53:25 INFO - Running setup.py install for mozleak 04:53:25 INFO - Running setup.py install for mozlog 04:53:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:53:25 INFO - Running setup.py install for moznetwork 04:53:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:53:25 INFO - Running setup.py install for mozprocess 04:53:25 INFO - Running setup.py install for mozprofile 04:53:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:53:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:53:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:53:26 INFO - Running setup.py install for mozrunner 04:53:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:53:26 INFO - Running setup.py install for mozscreenshot 04:53:26 INFO - Running setup.py install for moztest 04:53:26 INFO - Running setup.py install for mozversion 04:53:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:53:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:53:26 INFO - Cleaning up... 04:53:26 INFO - Return code: 0 04:53:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:26 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 0x10796b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f80dbc170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107781d30>, '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 0x1075e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.xjSrBRUqdi/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.gJx5y85AyX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:53:26 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 04:53:26 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 04:53:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xjSrBRUqdi/Render', 04:53:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:26 INFO - 'HOME': '/Users/cltbld', 04:53:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:26 INFO - 'LOGNAME': 'cltbld', 04:53:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:26 INFO - 'MOZ_NO_REMOTE': '1', 04:53:26 INFO - 'NO_EM_RESTART': '1', 04:53:26 INFO - 'PAGER': '/bin/cat', 04:53:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:26 INFO - 'PWD': '/builds/slave/test', 04:53:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:26 INFO - 'SHELL': '/bin/bash', 04:53:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJx5y85AyX/Listeners', 04:53:26 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:53:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:26 INFO - 'USER': 'cltbld', 04:53:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:26 INFO - 'XPC_FLAGS': '0x0', 04:53:26 INFO - 'XPC_SERVICE_NAME': '0', 04:53:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:53:26 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-gUOU9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:53:27 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)) 04:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:53:27 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-MX2cGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:53:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:53:27 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-F2b8xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:53:27 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)) 04:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:53:27 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-VkGGgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:53:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 04:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:53:27 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-7wzgDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:53:27 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)) 04:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:53:27 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-cKk5yG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:53:27 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)) 04:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:53:27 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-dPwfcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:53:27 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)) 04:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:53:27 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-aHH_K2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:53:27 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)) 04:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:53:27 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-HWe_L4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:53:27 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)) 04:53:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:53:27 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-66o4A1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:53:28 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)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:53:28 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-wO0QEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:53:28 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)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:53:28 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-zH69iF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:53:28 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)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:53:28 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-9zlj69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:53:28 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)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:53:28 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-QVDpf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:53:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:28 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-oG65b9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:53:28 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)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:53:28 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-n6lOfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:53:28 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)) 04:53:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:53:28 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-jogTrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:53:28 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)) 04:53:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:53:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:53:28 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)) 04:53:28 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:53:28 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:53:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:53:28 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 04:53:28 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 04:53:28 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 04:53:28 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 04:53:28 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 04:53:28 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 04:53:31 INFO - Downloading blessings-1.6.tar.gz 04:53:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:53:31 INFO - Installing collected packages: blessings 04:53:31 INFO - Running setup.py install for blessings 04:53:31 INFO - Successfully installed blessings 04:53:31 INFO - Cleaning up... 04:53:32 INFO - Return code: 0 04:53:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:53:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:53:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:53:32 INFO - Reading from file tmpfile_stdout 04:53:32 INFO - Current package versions: 04:53:32 INFO - blessings == 1.6 04:53:32 INFO - blobuploader == 1.2.4 04:53:32 INFO - docopt == 0.6.1 04:53:32 INFO - functools32 == 3.2.3-2 04:53:32 INFO - jsonschema == 2.5.1 04:53:32 INFO - manifestparser == 1.1 04:53:32 INFO - mozInstall == 1.12 04:53:32 INFO - mozcrash == 1.0 04:53:32 INFO - mozdebug == 0.1 04:53:32 INFO - mozdevice == 0.49 04:53:32 INFO - mozfile == 1.2 04:53:32 INFO - mozhttpd == 0.7 04:53:32 INFO - mozinfo == 0.9 04:53:32 INFO - mozleak == 0.1 04:53:32 INFO - mozlog == 3.4 04:53:32 INFO - moznetwork == 0.27 04:53:32 INFO - mozprocess == 0.23 04:53:32 INFO - mozprofile == 0.28 04:53:32 INFO - mozrunner == 6.13 04:53:32 INFO - mozscreenshot == 0.1 04:53:32 INFO - mozsystemmonitor == 0.3 04:53:32 INFO - moztest == 0.7 04:53:32 INFO - mozversion == 1.4 04:53:32 INFO - psutil == 3.1.1 04:53:32 INFO - requests == 1.2.3 04:53:32 INFO - wsgiref == 0.1.2 04:53:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:33 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 0x10796b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f80dbc170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107781d30>, '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 0x1075e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.xjSrBRUqdi/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.gJx5y85AyX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:53:33 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 04:53:33 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 04:53:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xjSrBRUqdi/Render', 04:53:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:33 INFO - 'HOME': '/Users/cltbld', 04:53:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:33 INFO - 'LOGNAME': 'cltbld', 04:53:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:33 INFO - 'MOZ_NO_REMOTE': '1', 04:53:33 INFO - 'NO_EM_RESTART': '1', 04:53:33 INFO - 'PAGER': '/bin/cat', 04:53:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:33 INFO - 'PWD': '/builds/slave/test', 04:53:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:33 INFO - 'SHELL': '/bin/bash', 04:53:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJx5y85AyX/Listeners', 04:53:33 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:53:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:33 INFO - 'USER': 'cltbld', 04:53:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:33 INFO - 'XPC_FLAGS': '0x0', 04:53:33 INFO - 'XPC_SERVICE_NAME': '0', 04:53:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 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)) 04:53:33 INFO - Cleaning up... 04:53:33 INFO - Return code: 0 04:53:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:53:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10796b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1078e1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f80dbc170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107781d30>, '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 0x1075e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, '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.xjSrBRUqdi/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.gJx5y85AyX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 04:53:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:53:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xjSrBRUqdi/Render', 04:53:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:33 INFO - 'HOME': '/Users/cltbld', 04:53:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:33 INFO - 'LOGNAME': 'cltbld', 04:53:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:33 INFO - 'MOZ_NO_REMOTE': '1', 04:53:33 INFO - 'NO_EM_RESTART': '1', 04:53:33 INFO - 'PAGER': '/bin/cat', 04:53:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:33 INFO - 'PWD': '/builds/slave/test', 04:53:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:33 INFO - 'SHELL': '/bin/bash', 04:53:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJx5y85AyX/Listeners', 04:53:33 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:53:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:33 INFO - 'USER': 'cltbld', 04:53:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:33 INFO - 'XPC_FLAGS': '0x0', 04:53:33 INFO - 'XPC_SERVICE_NAME': '0', 04:53:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:53:34 INFO - Cleaning up... 04:53:34 INFO - Return code: 0 04:53:34 INFO - Running post-action listener: _resource_record_post_action 04:53:34 INFO - Running post-action listener: _start_resource_monitoring 04:53:34 INFO - Starting resource monitoring. 04:53:34 INFO - [mozharness: 2016-11-25 12:53:34.098219Z] Finished create-virtualenv step (success) 04:53:34 INFO - [mozharness: 2016-11-25 12:53:34.099256Z] Running install step. 04:53:34 INFO - Running pre-action listener: _resource_record_pre_action 04:53:34 INFO - Running main action method: install 04:53:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:53:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:53:34 INFO - Reading from file tmpfile_stdout 04:53:34 INFO - Detecting whether we're running mozinstall >=1.0... 04:53:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:53:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:53:34 INFO - Reading from file tmpfile_stdout 04:53:34 INFO - Output received: 04:53:34 INFO - Usage: mozinstall [options] installer 04:53:34 INFO - Options: 04:53:34 INFO - -h, --help show this help message and exit 04:53:34 INFO - -d DEST, --destination=DEST 04:53:34 INFO - Directory to install application into. [default: 04:53:34 INFO - "/builds/slave/test"] 04:53:34 INFO - --app=APP Application being installed. [default: firefox] 04:53:34 INFO - mkdir: /builds/slave/test/build/application 04:53:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:53:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:54:07 INFO - Reading from file tmpfile_stdout 04:54:07 INFO - Output received: 04:54:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:54:07 INFO - Running post-action listener: _resource_record_post_action 04:54:07 INFO - [mozharness: 2016-11-25 12:54:07.997779Z] Finished install step (success) 04:54:07 INFO - [mozharness: 2016-11-25 12:54:07.997920Z] Running run-tests step. 04:54:07 INFO - Running pre-action listener: _resource_record_pre_action 04:54:07 INFO - Running main action method: run_tests 04:54:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:54:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:54:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:54:08 INFO - 2016-11-25 04:54:08.012 screenresolution[1901:18173] starting screenresolution argv=screenresolution get 04:54:08 INFO - 2016-11-25 04:54:08.015 screenresolution[1901:18173] Display 0: 1600x1200x32@60 04:54:08 INFO - 2016-11-25 04:54:08.021 screenresolution[1902:18175] starting screenresolution argv=screenresolution list 04:54:08 INFO - Available Modes on Display 0 04:54:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:54:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:54:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:54:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:54:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:54:08 INFO - Intel Iris: 04:54:08 INFO - Chipset Model: Intel Iris 04:54:08 INFO - Type: GPU 04:54:08 INFO - Bus: Built-In 04:54:08 INFO - VRAM (Dynamic, Max): 1536 MB 04:54:08 INFO - Vendor: Intel (0x8086) 04:54:08 INFO - Device ID: 0x0a2e 04:54:08 INFO - Revision ID: 0x0009 04:54:08 INFO - Displays: 04:54:08 INFO - Display: 04:54:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:54:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:54:08 INFO - Display Serial Number: 1600x1200 60 04:54:08 INFO - Main Display: Yes 04:54:08 INFO - Mirror: Off 04:54:08 INFO - Online: Yes 04:54:08 INFO - Rotation: Supported 04:54:08 INFO - Return code: 0 04:54:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:54:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:54:08 INFO - Python 2.7.3 04:54:08 INFO - Return code: 0 04:54:08 INFO - grabbing minidump binary from tooltool 04:54: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')]} 04:54:08 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 0x107781d30>, '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 0x1075e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107779b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:54:08 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 04:54:08 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 04:54:08 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 04:54:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:54:08 INFO - Return code: 0 04:54:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:54:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:54:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:54:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:54:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:54:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--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 04:54:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0163 --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 04:54:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xjSrBRUqdi/Render', 04:54:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:08 INFO - 'HOME': '/Users/cltbld', 04:54:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:08 INFO - 'LOGNAME': 'cltbld', 04:54:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:54:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:54:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:08 INFO - 'MOZ_NO_REMOTE': '1', 04:54:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:54:08 INFO - 'NO_EM_RESTART': '1', 04:54:08 INFO - 'PAGER': '/bin/cat', 04:54:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:08 INFO - 'PWD': '/builds/slave/test', 04:54:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:54:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:08 INFO - 'SHELL': '/bin/bash', 04:54:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJx5y85AyX/Listeners', 04:54:08 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 04:54:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:08 INFO - 'USER': 'cltbld', 04:54:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:08 INFO - 'XPC_FLAGS': '0x0', 04:54:08 INFO - 'XPC_SERVICE_NAME': '0', 04:54:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--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 04:54:08 INFO - mozversion application_buildid: 20161125015119 04:54:08 INFO - mozversion application_changeset: 1d31baa686c55268e4ec5364704b8ea5d7c33fe2 04:54:08 INFO - mozversion application_display_name: Nightly 04:54:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:54:08 INFO - mozversion application_name: Firefox 04:54:08 INFO - mozversion application_remotingname: firefox 04:54:08 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 04:54:08 INFO - mozversion application_vendor: Mozilla 04:54:08 INFO - mozversion application_version: 53.0a1 04:54:08 INFO - mozversion platform_buildid: 20161125015119 04:54:08 INFO - mozversion platform_changeset: 1d31baa686c55268e4ec5364704b8ea5d7c33fe2 04:54:08 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 04:54:08 INFO - mozversion platform_version: 53.0a1 04:54:08 INFO - using testdate: 1480078448 04:54:08 INFO - actual date: 1480078448 04:54:08 INFO - starting webserver on 'localhost:56528' 04:54:08 INFO - SUITE-START | Running 2 tests 04:54:08 INFO - TEST-START | dromaeo_css 04:54:08 INFO - operating with platform_type : mac_ 04:54:08 INFO - Initialising browser for dromaeo_css test... 04:54:08 INFO - TEST-INFO | started process 1912 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpfGqnps/profile http://localhost:56528/getInfo.html) 04:54:11 INFO - PROCESS | 1912 | 2016-11-25 04:54:11.566 plugin-container[1914:18334] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 04:54:11 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:54:11 INFO - PROCESS | 1912 | 2016-11-25 04:54:11.569 plugin-container[1914:18334] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 04:54:11 INFO - PROCESS | 1912 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:54:11 INFO - PROCESS | 1912 | 2016-11-25 04:54:11.570 plugin-container[1914:18334] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:54:11 INFO - PROCESS | 1912 | __metrics Screen width/height:1600/1200 04:54:11 INFO - PROCESS | 1912 | colorDepth:24 04:54:11 INFO - PROCESS | 1912 | Browser inner width/height: 1024/689 04:54:11 INFO - PROCESS | 1912 | __metrics 04:54:11 ERROR - PROCESS | 1912 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 04:54:12 INFO - TEST-INFO | 1912: exit 0 04:54:12 INFO - Browser initialized. 04:54:12 INFO - Running cycle 1/1 for dromaeo_css test... 04:54:12 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpfGqnps/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:54:15 INFO - PROCESS | 1915 | 2016-11-25 04:54:15.513 plugin-container[1916:18455] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 04:54:15 INFO - PROCESS | 1915 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:54:15 INFO - PROCESS | 1915 | 2016-11-25 04:54:15.514 plugin-container[1916:18455] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 04:54:15 INFO - PROCESS | 1915 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:54:15 INFO - PROCESS | 1915 | 2016-11-25 04:54:15.514 plugin-container[1916:18455] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:54:25 INFO - PROCESS | 1915 | RSS: Main: 242520064 04:54:25 INFO - PROCESS | 1915 | 04:55:14 INFO - PROCESS | 1915 | 1480078514780 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] 04:57:28 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:56528/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56528/tests/dromaeo/cssquery-ext.html) 04:57:30 INFO - PROCESS | 1915 | RSS: Main: 249937920 04:57:30 INFO - PROCESS | 1915 | 05:00:32 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:56528/tests/dromaeo/cssquery-ext.html (next: http://localhost:56528/tests/dromaeo/cssquery-jquery.html) 05:00:33 INFO - PROCESS | 1915 | RSS: Main: 262438912 05:00:33 INFO - PROCESS | 1915 | 05:03:35 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:56528/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56528/tests/dromaeo/cssquery-mootools.html) 05:03:35 INFO - PROCESS | 1915 | RSS: Main: 268320768 05:03:35 INFO - PROCESS | 1915 | 05:06:37 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:56528/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56528/tests/dromaeo/cssquery-prototype.html) 05:06:38 INFO - PROCESS | 1915 | RSS: Main: 266862592 05:06:38 INFO - PROCESS | 1915 | 05:09:41 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:56528/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56528/tests/dromaeo/cssquery-yui.html) 05:09:42 INFO - PROCESS | 1915 | RSS: Main: 271237120 05:09:42 INFO - PROCESS | 1915 | 05:12:44 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:56528/tests/dromaeo/cssquery-yui.html (next: http://localhost:56528/tests/dromaeo/cssquery-dojo.html) 05:12:45 INFO - PROCESS | 1915 | RSS: Main: 275279872 05:12:45 INFO - PROCESS | 1915 | 05:12:45 INFO - PROCESS | 1915 | __start_tp_report 05:12:45 INFO - PROCESS | 1915 | _x_x_mozilla_page_load 05:12:45 INFO - PROCESS | 1915 | _x_x_mozilla_page_load_details 05:12:45 INFO - PROCESS | 1915 | |i|pagename|runs| 05:12:45 INFO - PROCESS | 1915 | |0;dojo.html;3123.6407813101473;3163.051084674597;3321.6844399782017;3333.9833300833507;3365.326934613078;5545.805896793603;5566.359868615111;5573.498385145336;5631.239782629344;5697.6011679182475;4566.885827854297;5458.263134476844;5522.0336441122745;5540.667559946406;5562.332520097594;5099.694018358892;5124.436312005676;5153.896922061575;5244.501772331641;5267.52592266696;5080.949190508103;5254.57963362932;5277.340332458442;5282;5292.338457692788;5082.390113186404;5209.973950130244;5212.452692467275;5272.156454967206;5275.314209152824;5077.669951453181;5098.107831129574;5160.8193713220235;5178.352705911761;5184.170532714747;5152.742362881841;5157.8452646420665;5179.067767801795;5183.896322073575;5208.817691380784;5497.945020549783;5653.434656534355;5732;5736.168255602956;5810.4770570648425;5030.496950304977;6047.848803779941;6078.787242446538;6172.382761723792;6232.906506402408;5032.949670503284;6107.7957376609465;6143.204841796972;6182.907256391158;6188.907166392508;4725.5510726480925;5805.651660900359;5807.709614519257;5808.07070868659;5813.622114562582;5257.7896884124975;6089.878202436015;6148.6925653717135;6153.815385538352;6180.103884936659;5956.1065840123465;5996.460318571262;6026.096085587197;6043.244594425697;6134;6131.590761710035;6133.405314618231;6138.416850399205;6149.5080393568405;6152.78465253714;5437.075697131497;5999.850003749854;6076.817695469143;6092.847678807977;6144.201253836973;5279.498447647467;6150.846228844314;6166.383361663798;6175.382461753788;6246.406591373812;4883.902321953541;4902.632302577321;4907.7300748458465;4913.70517768935;4946.307516947631;4291.914161716748;4294.82820687169;4327.1345730853955;4365.08333250021;4365.886698891826;5680.204771332017;5852.502537284294;5871.383504732027;5897.587168898136;5915.911261331084;6185.350538193515;6201.81394558164;6207.099970504251;6215.968920155461;6238.594491358047;5654.660720356792;5727.942720572824;5737.139429085671;5824.796132135355;5854.121881717776;4486.75322857246;5286.920696189561;5294.41761406245;5322.520973112439;5335.62650614461;6199.907001394983;6274.905876411949;6281.591696539802;6288.591241569284;6294.842628934405;6109.572329937039;6163.229596300463;6174.38256174379;6203.844903877259;6217.875642487215;6110.133516259585;6132.845047373448;6136.365905528278;6181.350958149509;6214.9067763982675;6111.786087487095;6119.4798442132915;6139;6139;6166.4758495526585;6036.426539478743;6079.088136779518;6159.414855588712;6175.752969881154;6176.536759742948;5943.078822782476;5964.224650795369;5974.701264936823;6082.087686847009;6129.724162412791;6001.609895356788;6010.398960104129;6013.909791353046;6031.668258245839;6036.517078633787;5334.9466505336;5799.884002320015;5806.129080637938;5872.500837428866;5917.053271476543;5995.61028533144;6041.969790151021;6062.42406971337;6068.120122582112;6099.786507472217;5899.085641725539;5950.16697662319;5964.642121472725;5982.880342393215;5995.490383317467 05:12:45 INFO - PROCESS | 1915 | |1;ext.html;475.3820033955856;476.64728101205105;483.37885816725503;525.3748039832599;527.854839919022;36876.63123368766;40999.79500102499;41035.58964410359;41134.17731645365;41153.17693646133;39588.60411395885;40138;40189.39715904264;40189.799051004775;40317;8241;9177.587008584629;9361.48511831849;9373.484458354787;9425.717228483121;8310.168983101672;10943;11039.889601104007;11214.757048590274;11374.772504549905;11209.383483908381;11292.548298068068;11324.263922844988;11355;11377.293405989085;11235.494402751854;11281.153913456523;11296.830547541753;11296.830547541793;11307.434628268553;8283.50298982063;9037.277017838556;9370.812583748355;10586;11080.5622762992;34462.44918978652;36408.089797755005;37919.810400948096;38331.85004449871;38338.23323533514;3021.531662592302;3240.0179006513754;3267.640559538449;3400.846961886721;3647.2705458908326;28957.986470473432;29160.56259156114;29716;29827.850860745773;30016.69983300161;3150.2596889730894;3164.810111393324;3172.4924012157944;3245.594300712411;3273.3289675616443;432680;450921;452094.7395263003;452976;454093.9999999967;49668;49698.50301497011;49816.0036799262;49839;49934.25098623524;47410.051798963825;58463;58489.41510584925;58655.41344586585;58712;14897.851021489863;15354.462593809163;15357;15404.46084387041;15411.22943852803;10860.239783215256;11060.446977651085;11821.704457388634;11906.642800715992;12027.75944481123;14907.850921490646;14959.850401496064;15080;15126.39494420233;15379.461718839786;6536.379043990813;6543.705533251015;6570;6612.900806487906;6613.4709223262025;806.5080301016493;857.8155696525278;965.6234068713208;965.9953648205787;1003.5333569890037;24676.012959481777;27586.586201207;28306;28645.57031644527;28983.710162898522;871.7297637732323;968.3899143539525;996.4270544436978;1081.8377243413552;1147.2198904744853;13470;14827.332770025374;15014;15112.168830714196;15170;541294;562617;632955;634224.2353341129;636088;45332;54028.72985635047;54312.45687543153;54395.45604543904;54657;1321.7224382879704;1343.7111021130504;1354.7629164896105;1367.8768910798076;1375.0786972728297;22545.549089018456;24696.641684707512;27390;28455.59710621;29136.125916222547;47523.04953900972;47854.5214547864;47870;47884;48833.26750098822;541257;543396;543556;544404;544581;29034.12897613075;31587;32430.189245269055;38255.42616860805;38941.80529097337;43673;44302.55697442984;44515.33227001533;45118.77440612776;45178.77410612926;36195;37023.629763703095;37106.8144659275;37340.439893401075;37354;36132.4580131293;36381.27237455289;36495.27009459849;37415.81292093522;37589.248215035;36111.45832812457;36187.63812361948;36280.81859590685;36651;36736.448953265186;31315.686843131276;32574.34851303008;32614.51078233781;32717.34565308633;32724.34551309008;23740;24134.75865241325;24394.75605243925;24619.753802462463;24623.38441538976 05:12:45 INFO - PROCESS | 1915 | |2;jquery.html;326.6945406045348;344.9120474279059;361.0000299171294;372.9049092481417;390.00159591445885;18564.25742970281;19079.904600476995;20274.59450810983;20298.898505507488;20515.897420512876;19808.801911980874;19976.700349494768;20018.49953751157;20214.797852021475;20243.39269821904;5686.914696279559;5926.3184733755525;5983;5984.5511586630955;5997.130416089664;5616.438356164372;5832.62087964281;5837.503812175949;5913.438223368773;5926.496247818919;5593.412691667357;5840.474357307841;5860.882782344351;5870.530357571384;5872.677002764846;5747.109198074284;5867.090600956859;5868.735906884201;5878.50032747217;5878.617889837147;5001.9749901250625;5134.614903882187;5146.562542183919;5149.845504634867;5291.814786482493;12777.23336599807;13025.47898084085;13054.412551435209;13066.869331306756;18087.547811304823;1905.295040834886;2115.788421157887;2235.2065016918923;2823.759980401668;2843.1186332236866;7828.725994590219;8625.353098517637;9114.81770364589;9275;9545.997670244664;1492.3433689176727;1783.3579911231946;1798.6043070524463;1806.096951524238;1814.891106533599;121515.3924230382;125402;128862;132228;136152.51161186132;11477.139214558942;11547.595834145764;11630.53477860893;11639.76720465586;11855.05159587231;11981.580644677395;14348.282585870666;14491.420343186155;14512.274386280644;14528.85471145275;6685.899711504331;8334.20825021622;8394.706185283485;8424.452410593292;8520.360972927056;6878.69045892936;8198;8232.588370581447;8317.750467485985;8338.958305208556;4463.509014008457;4472.195004899149;4480.574345437179;4502.347159661831;4564.817407303737;1623.9431619893483;1854.842338401224;1905.1331644101901;1905.6855618823017;1910.1252614814894;723.7177500774702;779.6998155710093;825.471698113208;882.2060145868769;926.0600490502146;17217.741733874;18398;20118.49703757418;20196.79803201949;21010.054547545093;861.8534849075672;918.2195134136065;920.995395023034;926.416357694648;971.708487453761;8723.302135829119;10881.727956801144;10915.399653019002;10950.890491094988;11027.952344527257;550573;577327;601623;602835;604819;113789.4310528442;117324;135196;139490;145954;877.9604917778715;931.930105242096;975.4001289207087;976.2970661123979;989.3223142147796;16636.750448743038;18472.353467628567;18824.341148059753;19137.712934305717;19338.70991935094;55222.723886381915;56349.71825141012;56843;57737.71131144317;59634;85307;85395;86033.56983215043;86543;86924.83052372547;7826.41301902371;7955.204479551999;8035.276825085771;8115.391345648982;8201.71294004707;12445.62663120108;12600.684982875502;12609.49562017509;12825.102242842911;12948.352582370655;10397.168226541708;10862.293950893167;11114.44427778624;11197.496112675111;11421;10811.35131892089;10858.022278195389;10929;11181.776364472827;11202.327860328407;10792.29850059775;11124.777504450027;11201;11297.26567773092;11380.886191137983;7807.765767026998;8031.1567285434185;8069.233422824822;8081;8178.550179740171;9064;10204.387736735524;10751.784964300827;10845.728856778329;10895.509702063584 05:12:45 INFO - PROCESS | 1915 | |3;mootools.html;1236.585743775835;1449.9637509062275;1597.8641815445687;1758.9120543972815;1799.8290162434573;6448.161738973933;7316.268373162681;7610.391168706504;7917.881231781521;8308.875366869486;7737.883931741015;8003.519788812675;8029.678812847479;8150.836983260331;8160.551169685665;1879.4079864842531;2280.361498780344;2303.09027933966;2303.1823702585593;2303.9539209215804;2318.211807985285;2336.4509340305017;2338.5790557699606;2340.5903966805827;2351.329870986769;2214.236088549456;2254.9436264093365;2281.121768119278;2283.04112272845;2294.2199652118275;1940.7574053243345;2092.299079808266;2129.58473097746;2270.7729227077325;2280.8859557022083;2294.4837411582425;2319.3853628788215;2320.1879342230254;2320.2111282164146;2323.814094872423;8197.71308004217;8252.917470825276;8262.256396924306;8267.875981860278;8271.793205169859;4510.939929116655;4865.44047434546;5125.974370128163;5136.948630513684;5151.381834179885;3014.8191108533556;3213.469777486712;3232.9030129096163;3248.431524483206;3520.9119772005906;1040.4069680282241;1065.7442213868617;1079.7084787107437;1082.3019152646498;1086.0388556127805;8767.473951562926;9300.813983720356;10410.42742649154;10456.686299411105;10480.737981550446;4220.8051813057655;4300.548442413564;4389.648828093808;4423.358613001097;4423.579759922866;8337.583120843934;8479.364047696448;8479.915200848036;8491.065982741893;8503.149685031447;4997.375328083989;5025.723585202776;5035.446100928968;5037.269595908573;5049.798008079709;4200.348945913327;4502.302143167814;4712.434507859079;4734.124187025412;4734.360861283682;3536.734744894143;3544.556930383702;3655.469956856241;3655.908602284964;3682.287736202625;1130.6042884990188;1154.261272785418;1159.1769843411105;1160.2342453980332;1163.0521125282867;777.5218240781992;787.4291138924349;807.3097501636016;860.1871231685997;867.9801233550553;4334.804933777988;5227.581793456515;5323.866903327448;5476.561875049986;5684.459976302244;891.826093911681;929.9256059515222;935.6257497001118;995.2187532786728;1032.210359075308;3600.477930700034;3725.4784330193793;3823.4456003879836;3830.75100118497;3884.475595794588;9450.007749186374;9924.702258932244;9946.055124763136;9967.252456065824;9967.950160249298;9166.49584272858;9396.295277854051;9887.406755594688;9923.60305587783;9926.454045027449;7340.632968351668;7653.693852245848;7660.119086305035;7673.846523069507;7715.22847715224;5256.842294731164;5382.596305277041;5489.231507589021;5542.75057622416;5652.756393593403;8847.336449766164;8995.190432861074;9080.682176123804;9153.084691530794;9162.312826537904;9100.044495327895;9150.039245879087;9170.58732357059;9300.906990930003;9305.441673499612;5912.911306330495;6758.749631318222;7004.754833580801;7402.814929626802;7500.024996750388;7073.82315442118;7433.9256607433235;7607.695692172251;7718;7787.10448298453;6760.121184246016;6921.584704917721;6999.230084690782;7042.577445353295;7073.752418665322;6883.862322753617;6884.965575172092;6886.48351373639;6921.965390173017;6933.1333583302085;6493.480521558169;6521.282658907421;6525.314841941527;6595.901061484177;6605.669716514251;4662.790174442091;4762.833300834454;4784.091022705675;4804.399450068741;4902.289168070681;4758.2386818108935;4843.806247750051;4864.902701945871;4884.92672609904;4903.141950158779 05:12:45 INFO - PROCESS | 1915 | |4;prototype.html;250.81690366032797;269.2071848406444;296.67662248149526;305.7951172714282;308.79156569315705;5115.718635475047;5193.195054766517;5278.993982286725;5338;5452.945470545294;4942.5304596063315;5076.84769456916;5269.789208431668;5307.495787900153;5339.439358867321;3714.3685573452576;3715.182659814843;3743.4010558310674;3862.942055869164;4145.792710364485;3799.620037996192;4138.917221655565;4145.3781932710035;4145.631941459311;4158.771267580297;3929.76421414716;3990.620891015349;4012.8194231259663;4025.4364388985564;4029.952212424776;3997.9200415991663;4013.9397909031386;4032.9395059074136;4043.1307268937026;4044.2315959967514;3043.9391212175856;3501.38725723;3511.6312787157235;3521.559805024372;3522.8590856365713;4546.318052292149;4815.253635686474;4988.7505624718615;5090.312807770941;5223.71269580173;4039.4748682671066;4566.908661826778;4584.701994370389;4596.678232523725;4608.953910460886;5577.581681373913;5639.323281206241;5654.105874532037;5654.321481422215;5675.716214189315;3253.316803471274;3554.875579354736;3760.909336292472;3832.2335532893535;3841.2509560635685;4889.682170658932;4893.21708526634;5103.030424219386;5626.493615574618;5908.586398952075;3885;3898.161895192547;3906.746061505993;3925.1747937209334;3928.862489812843;3752.6810221130963;3780.111673756665;3939.3848522106373;3940.4680368150507;3958.5249770027203;4170.937435938464;4193.30810416284;4500.672301671015;4548.363229147922;4568.017876156576;4928.211486162197;5260.28986086881;5640.520555752807;5660.264165658438;5665.9716701417055;1180.9350485723196;1195.5397172088808;1253.4610117649315;1258.3079306381453;1262.72851336963;738.5716284554945;833.7248707926369;860.7245681381897;860.7503823891002;868.6091258933506;4127.587241275848;4137.420761093449;4711.316859055418;4867;5076.791595655902;4870.439899411613;4887.755612219375;5233.4243233244315;5548.805791797267;5641.633293835969;4845.2799256264225;5028.7176769923435;5037.3955125384455;5048.7728052237;5065.366829146356;2471.555120078403;2860.7269764954813;2863.642044744407;2893.9855300723784;2897.2032691009717;5074.8835256243565;5637.379888212294;5709.800156994485;5722.427757224327;5741.971290143522;4763.95236047642;5046.873828154252;5308.256844041837;5395.246544872932;5496;4828.25065769435;4988.775505102279;6146.5390095743;6170.475509581556;6184.257889053342;4908.852734417973;4984.40187177541;5592.888142237214;6005.219321488178;6020.458158765732;5006.974965125151;5426.375966763872;5796.391378905154;5815.76736930518;5866.853328666817;6065.908136535466;6075.420390698362;6086.847828804315;6122.204113465399;6143.354947730424;3358.1940334320075;3525.982370088133;3865.497485327002;3865.632764887331;3900.3369427198013;5520.199571062254;5552.611317207757;5811.651300921958;5877.0302900021015;5907;4454.933176002427;4908.901821963469;5159.942211846487;5597.832065038056;5775.537956963517;5293.5765138789575;5472.199468563335;5547.33431988164;5635.999220276906;5743.1385292206505;4474.709143905636;5573.442655734394;5584.692841893735;5599.944000559943;5615.915761263503;4327.826886924614;5190.2214667800135;5206.8958620828125;5663.915041274467;5744.712764361849;4929.654924155275;5613.494785469327;5629.634073785191;5640.774369025357;5661.745221464962 05:12:45 INFO - PROCESS | 1915 | |5;yui.html;688.9001094841245;690.4683393786785;716.154937174135;754.2306847016043;775.1554760186274;14283.428662853486;14842.035267707619;14866.884983626243;14894.180820054906;14938.850611493881;13481.73036539271;13617.046806723532;13619.523316683919;13646.044776865623;13682;637.1112298343808;660.1055569703055;691.6783695581541;793.2067932067932;821.1542111625006;813.6379311206515;818.9467684600481;834.3658819297317;835.1022650650544;839.4669384940546;880.0231742765557;880.7137680253911;884.0628933330657;884.5975081337975;884.7080463447079;859.9054104048549;871.5772850167664;874.7113452560671;877.6708734224698;879.9868001979976;651.9641419721913;652.2336254900473;652.3313603556345;652.9934700653027;653.7189008726245;14306.864802620108;14386.208758518278;14395.2082635455;14477.638059048502;14532.854671453257;13850.515231966934;13866.930665346608;13986.93006534971;14189.716205675932;14307.78538321926;11225.831612525819;11464;11480.138989575815;11493.367864767435;11667.59163429276;3681.4845921571;3689.2252626948375;3793;3807.809609519513;3868.2456920900436;87622.99999999936;89261.55369223177;89300;89521;89781;10042.096211340868;10570.841437378445;10824.07995320391;10924.617638382777;10971.780564388668;21912.780872191073;21945.341639750834;21951.780482195292;21977;21978.340649780534;5653.349864765522;7690.346320562815;7805.960970195226;7825.256600622932;7839.568823714636;3640.21735326906;4195.97902010488;4239.639630631369;4322.416473776063;4415.470143582726;3373.274745929588;3443.6900678939014;3476.1830969722096;3483.5993860705835;3493.737969652286;616.7933742196325;631.8388810853203;640.4620119099978;641.1760887259849;648.4326214562258;554.3071161048689;637.9458144773821;656.4190691238289;658.2392101129459;659.2649196146263;8071.09440601383;8168.836623267502;8704.782380440412;9417.246620270362;9751.219902407933;617.8084793713963;619.0559396919733;641;653.6992983227674;684.5789839248827;3632.219072899339;4161.795417528547;4259.9787001065415;4359.324304732771;4410.404595379647;100512.99487005036;105007;109934.90065099247;110293.44853275683;110904;60314.19057428284;69690.65154674194;82598;86746.13253867507;95446.13661590163;45653.77173114113;59307.40692593105;69873.65063174753;85558.5722071398;87743.56128219445;8622;9452.338336316392;9547.665831695856;9652.227821774382;9802.117809397188;38066.61933380631;50459.4862154136;51174.74412627913;51672.741636291576;52287.95424091573;59170;77683.4463310742;79458;80744;81296.10403803128;9959.177194783957;11792.823107653563;12335.568255111028;12471.563495277986;12728.61814145577;14031.368588413341;14238.928805355907;14365.569032929028;14366.13803171813;14367.712645746817;13144.27706476153;13350.332483375987;13381.799273010718;13408.061435699407;13424.79176874086;13176.934115329363;13288.800667989795;13295.867041329462;13563.186208827501;13760.449582017007;12745.681357966125;12877.935610322264;12916.178019837495;13232.669183270495;13402.128861623964;10394.116500097274;10524.579016839547;10552.894471055499;10724.249302549058;10969.341839489656;10759.515821788194;11087.501062452371;11191.832122518332;11352;11392.658220253406 05:12:45 INFO - PROCESS | 1915 | __end_tp_report 05:12:45 INFO - PROCESS | 1915 | __start_cc_report 05:12:45 INFO - PROCESS | 1915 | _x_x_mozilla_cycle_collect,370 05:12:45 INFO - PROCESS | 1915 | __end_cc_report 05:12:45 INFO - PROCESS | 1915 | __startTimestamp1480079565174__endTimestamp 05:12:45 INFO - PROCESS | 1915 | 05:12:45 INFO - PROCESS | 1915 | ------- Summary: start ------- 05:12:45 INFO - PROCESS | 1915 | Number of tests: 6 05:12:45 INFO - PROCESS | 1915 | 05:12:45 INFO - PROCESS | 1915 | [#0] dojo.html Cycles:165 Average:5642.48 Median:5897.59 stddev:649.67 (11.0%) stddev-sans-first:620.88 05:12:45 INFO - PROCESS | 1915 | Values: 3123.6 3163.1 3321.7 3334.0 3365.3 5545.8 5566.4 5573.5 5631.2 5697.6 4566.9 5458.3 5522.0 5540.7 5562.3 5099.7 5124.4 5153.9 5244.5 5267.5 5080.9 5254.6 5277.3 5282.0 5292.3 5082.4 5210.0 5212.5 5272.2 5275.3 5077.7 5098.1 5160.8 5178.4 5184.2 5152.7 5157.8 5179.1 5183.9 5208.8 5497.9 5653.4 5732.0 5736.2 5810.5 5030.5 6047.8 6078.8 6172.4 6232.9 5032.9 6107.8 6143.2 6182.9 6188.9 4725.6 5805.7 5807.7 5808.1 5813.6 5257.8 6089.9 6148.7 6153.8 6180.1 5956.1 5996.5 6026.1 6043.2 6134.0 6131.6 6133.4 6138.4 6149.5 6152.8 5437.1 5999.9 6076.8 6092.8 6144.2 5279.5 6150.8 6166.4 6175.4 6246.4 4883.9 4902.6 4907.7 4913.7 4946.3 4291.9 4294.8 4327.1 4365.1 4365.9 5680.2 5852.5 5871.4 5897.6 5915.9 6185.4 6201.8 6207.1 6216.0 6238.6 5654.7 5727.9 5737.1 5824.8 5854.1 4486.8 5286.9 5294.4 5322.5 5335.6 6199.9 6274.9 6281.6 6288.6 6294.8 6109.6 6163.2 6174.4 6203.8 6217.9 6110.1 6132.8 6136.4 6181.4 6214.9 6111.8 6119.5 6139.0 6139.0 6166.5 6036.4 6079.1 6159.4 6175.8 6176.5 5943.1 5964.2 5974.7 6082.1 6129.7 6001.6 6010.4 6013.9 6031.7 6036.5 5334.9 5799.9 5806.1 5872.5 5917.1 5995.6 6042.0 6062.4 6068.1 6099.8 5899.1 5950.2 5964.6 5982.9 5995.5 05:12:45 INFO - PROCESS | 1915 | 05:12:45 INFO - PROCESS | 1915 | [#1] ext.html Cycles:180 Average:65891.76 Median:36573.14 stddev:142960.62 (390.9%) stddev-sans-first:143277.29 05:12:45 INFO - PROCESS | 1915 | Values: 475.4 476.6 483.4 525.4 527.9 36876.6 40999.8 41035.6 41134.2 41153.2 39588.6 40138.0 40189.4 40189.8 40317.0 8241.0 9177.6 9361.5 9373.5 9425.7 8310.2 10943.0 11039.9 11214.8 11374.8 11209.4 11292.5 11324.3 11355.0 11377.3 11235.5 11281.2 11296.8 11296.8 11307.4 8283.5 9037.3 9370.8 10586.0 11080.6 34462.4 36408.1 37919.8 38331.9 38338.2 3021.5 3240.0 3267.6 3400.8 3647.3 28958.0 29160.6 29716.0 29827.9 30016.7 3150.3 3164.8 3172.5 3245.6 3273.3 432680.0 450921.0 452094.7 452976.0 454094.0 49668.0 49698.5 49816.0 49839.0 49934.3 47410.1 58463.0 58489.4 58655.4 58712.0 14897.9 15354.5 15357.0 15404.5 15411.2 10860.2 11060.4 11821.7 11906.6 12027.8 14907.9 14959.9 15080.0 15126.4 15379.5 6536.4 6543.7 6570.0 6612.9 6613.5 806.5 857.8 965.6 966.0 1003.5 24676.0 27586.6 28306.0 28645.6 28983.7 871.7 968.4 996.4 1081.8 1147.2 13470.0 14827.3 15014.0 15112.2 15170.0 541294.0 562617.0 632955.0 634224.2 636088.0 45332.0 54028.7 54312.5 54395.5 54657.0 1321.7 1343.7 1354.8 1367.9 1375.1 22545.5 24696.6 27390.0 28455.6 29136.1 47523.0 47854.5 47870.0 47884.0 48833.3 541257.0 543396.0 543556.0 544404.0 544581.0 29034.1 31587.0 32430.2 38255.4 38941.8 43673.0 44302.6 44515.3 45118.8 45178.8 36195.0 37023.6 37106.8 37340.4 37354.0 36132.5 36381.3 36495.3 37415.8 37589.2 36111.5 36187.6 36280.8 36651.0 36736.4 31315.7 32574.3 32614.5 32717.3 32724.3 23740.0 24134.8 24394.8 24619.8 24623.4 05:12:45 INFO - PROCESS | 1915 | 05:12:45 INFO - PROCESS | 1915 | [#2] jquery.html Cycles:180 Average:34912.34 Median:366.95 stddev:98793.74 (26922.8%) stddev-sans-first:99036.75 05:12:45 INFO - PROCESS | 1915 | Values: 326.7 344.9 361.0 372.9 390.0 18564.3 19079.9 20274.6 20298.9 20515.9 19808.8 19976.7 20018.5 20214.8 20243.4 5686.9 5926.3 5983.0 5984.6 5997.1 5616.4 5832.6 5837.5 5913.4 5926.5 5593.4 5840.5 5860.9 5870.5 5872.7 5747.1 5867.1 5868.7 5878.5 5878.6 5002.0 5134.6 5146.6 5149.8 5291.8 12777.2 13025.5 13054.4 13066.9 18087.5 1905.3 2115.8 2235.2 2823.8 2843.1 7828.7 8625.4 9114.8 9275.0 9546.0 1492.3 1783.4 1798.6 1806.1 1814.9 121515.4 125402.0 128862.0 132228.0 136152.5 11477.1 11547.6 11630.5 11639.8 11855.1 11981.6 14348.3 14491.4 14512.3 14528.9 6685.9 8334.2 8394.7 8424.5 8520.4 6878.7 8198.0 8232.6 8317.8 8339.0 4463.5 4472.2 4480.6 4502.3 4564.8 1623.9 1854.8 1905.1 1905.7 1910.1 723.7 779.7 825.5 882.2 926.1 17217.7 18398.0 20118.5 20196.8 21010.1 861.9 918.2 921.0 926.4 971.7 8723.3 10881.7 10915.4 10950.9 11028.0 550573.0 577327.0 601623.0 602835.0 604819.0 113789.4 117324.0 135196.0 139490.0 145954.0 878.0 931.9 975.4 976.3 989.3 16636.8 18472.4 18824.3 19137.7 19338.7 55222.7 56349.7 56843.0 57737.7 59634.0 85307.0 85395.0 86033.6 86543.0 86924.8 7826.4 7955.2 8035.3 8115.4 8201.7 12445.6 12600.7 12609.5 12825.1 12948.4 10397.2 10862.3 11114.4 11197.5 11421.0 10811.4 10858.0 10929.0 11181.8 11202.3 10792.3 11124.8 11201.0 11297.3 11380.9 7807.8 8031.2 8069.2 8081.0 8178.6 9064.0 10204.4 10751.8 10845.7 10895.5 05:12:45 INFO - PROCESS | 1915 | 05:12:45 INFO - PROCESS | 1915 | [#3] mootools.html Cycles:180 Average:5251.52 Median:5242.21 stddev:2838.90 (54.2%) stddev-sans-first:2830.83 05:12:45 INFO - PROCESS | 1915 | Values: 1236.6 1450.0 1597.9 1758.9 1799.8 6448.2 7316.3 7610.4 7917.9 8308.9 7737.9 8003.5 8029.7 8150.8 8160.6 1879.4 2280.4 2303.1 2303.2 2304.0 2318.2 2336.5 2338.6 2340.6 2351.3 2214.2 2254.9 2281.1 2283.0 2294.2 1940.8 2092.3 2129.6 2270.8 2280.9 2294.5 2319.4 2320.2 2320.2 2323.8 8197.7 8252.9 8262.3 8267.9 8271.8 4510.9 4865.4 5126.0 5136.9 5151.4 3014.8 3213.5 3232.9 3248.4 3520.9 1040.4 1065.7 1079.7 1082.3 1086.0 8767.5 9300.8 10410.4 10456.7 10480.7 4220.8 4300.5 4389.6 4423.4 4423.6 8337.6 8479.4 8479.9 8491.1 8503.1 4997.4 5025.7 5035.4 5037.3 5049.8 4200.3 4502.3 4712.4 4734.1 4734.4 3536.7 3544.6 3655.5 3655.9 3682.3 1130.6 1154.3 1159.2 1160.2 1163.1 777.5 787.4 807.3 860.2 868.0 4334.8 5227.6 5323.9 5476.6 5684.5 891.8 929.9 935.6 995.2 1032.2 3600.5 3725.5 3823.4 3830.8 3884.5 9450.0 9924.7 9946.1 9967.3 9968.0 9166.5 9396.3 9887.4 9923.6 9926.5 7340.6 7653.7 7660.1 7673.8 7715.2 5256.8 5382.6 5489.2 5542.8 5652.8 8847.3 8995.2 9080.7 9153.1 9162.3 9100.0 9150.0 9170.6 9300.9 9305.4 5912.9 6758.7 7004.8 7402.8 7500.0 7073.8 7433.9 7607.7 7718.0 7787.1 6760.1 6921.6 6999.2 7042.6 7073.8 6883.9 6885.0 6886.5 6922.0 6933.1 6493.5 6521.3 6525.3 6595.9 6605.7 4662.8 4762.8 4784.1 4804.4 4902.3 4758.2 4843.8 4864.9 4884.9 4903.1 05:12:45 INFO - PROCESS | 1915 | 05:12:45 INFO - PROCESS | 1915 | [#4] prototype.html Cycles:180 Average:4479.49 Median:4963.47 stddev:1394.87 (28.1%) stddev-sans-first:1362.20 05:12:45 INFO - PROCESS | 1915 | Values: 250.8 269.2 296.7 305.8 308.8 5115.7 5193.2 5279.0 5338.0 5452.9 4942.5 5076.8 5269.8 5307.5 5339.4 3714.4 3715.2 3743.4 3862.9 4145.8 3799.6 4138.9 4145.4 4145.6 4158.8 3929.8 3990.6 4012.8 4025.4 4030.0 3997.9 4013.9 4032.9 4043.1 4044.2 3043.9 3501.4 3511.6 3521.6 3522.9 4546.3 4815.3 4988.8 5090.3 5223.7 4039.5 4566.9 4584.7 4596.7 4609.0 5577.6 5639.3 5654.1 5654.3 5675.7 3253.3 3554.9 3760.9 3832.2 3841.3 4889.7 4893.2 5103.0 5626.5 5908.6 3885.0 3898.2 3906.7 3925.2 3928.9 3752.7 3780.1 3939.4 3940.5 3958.5 4170.9 4193.3 4500.7 4548.4 4568.0 4928.2 5260.3 5640.5 5660.3 5666.0 1180.9 1195.5 1253.5 1258.3 1262.7 738.6 833.7 860.7 860.8 868.6 4127.6 4137.4 4711.3 4867.0 5076.8 4870.4 4887.8 5233.4 5548.8 5641.6 4845.3 5028.7 5037.4 5048.8 5065.4 2471.6 2860.7 2863.6 2894.0 2897.2 5074.9 5637.4 5709.8 5722.4 5742.0 4764.0 5046.9 5308.3 5395.2 5496.0 4828.3 4988.8 6146.5 6170.5 6184.3 4908.9 4984.4 5592.9 6005.2 6020.5 5007.0 5426.4 5796.4 5815.8 5866.9 6065.9 6075.4 6086.8 6122.2 6143.4 3358.2 3526.0 3865.5 3865.6 3900.3 5520.2 5552.6 5811.7 5877.0 5907.0 4454.9 4908.9 5159.9 5597.8 5775.5 5293.6 5472.2 5547.3 5636.0 5743.1 4474.7 5573.4 5584.7 5599.9 5615.9 4327.8 5190.2 5206.9 5663.9 5744.7 4929.7 5613.5 5629.6 5640.8 5661.7 05:12:45 INFO - PROCESS | 1915 | 05:12:45 INFO - PROCESS | 1915 | [#5] yui.html Cycles:180 Average:19605.47 Median:4291.20 stddev:28097.11 (654.8%) stddev-sans-first:28140.03 05:12:45 INFO - PROCESS | 1915 | Values: 688.9 690.5 716.2 754.2 775.2 14283.4 14842.0 14866.9 14894.2 14938.9 13481.7 13617.0 13619.5 13646.0 13682.0 637.1 660.1 691.7 793.2 821.2 813.6 818.9 834.4 835.1 839.5 880.0 880.7 884.1 884.6 884.7 859.9 871.6 874.7 877.7 880.0 652.0 652.2 652.3 653.0 653.7 14306.9 14386.2 14395.2 14477.6 14532.9 13850.5 13866.9 13986.9 14189.7 14307.8 11225.8 11464.0 11480.1 11493.4 11667.6 3681.5 3689.2 3793.0 3807.8 3868.2 87623.0 89261.6 89300.0 89521.0 89781.0 10042.1 10570.8 10824.1 10924.6 10971.8 21912.8 21945.3 21951.8 21977.0 21978.3 5653.3 7690.3 7806.0 7825.3 7839.6 3640.2 4196.0 4239.6 4322.4 4415.5 3373.3 3443.7 3476.2 3483.6 3493.7 616.8 631.8 640.5 641.2 648.4 554.3 637.9 656.4 658.2 659.3 8071.1 8168.8 8704.8 9417.2 9751.2 617.8 619.1 641.0 653.7 684.6 3632.2 4161.8 4260.0 4359.3 4410.4 100513.0 105007.0 109934.9 110293.4 110904.0 60314.2 69690.7 82598.0 86746.1 95446.1 45653.8 59307.4 69873.7 85558.6 87743.6 8622.0 9452.3 9547.7 9652.2 9802.1 38066.6 50459.5 51174.7 51672.7 52288.0 59170.0 77683.4 79458.0 80744.0 81296.1 9959.2 11792.8 12335.6 12471.6 12728.6 14031.4 14238.9 14365.6 14366.1 14367.7 13144.3 13350.3 13381.8 13408.1 13424.8 13176.9 13288.8 13295.9 13563.2 13760.4 12745.7 12877.9 12916.2 13232.7 13402.1 10394.1 10524.6 10552.9 10724.2 10969.3 10759.5 11087.5 11191.8 11352.0 11392.7 05:12:45 INFO - PROCESS | 1915 | -------- Summary: end -------- 05:12:45 INFO - PROCESS | 1915 | 05:12:45 INFO - TEST-INFO | 1915: exit 0 05:12:45 INFO - TEST-OK | dromaeo_css | took 1117318ms 05:12:45 INFO - TEST-START | kraken 05:12:45 INFO - operating with platform_type : mac_ 05:12:45 INFO - Initialising browser for kraken test... 05:12:45 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpsCOr5d/profile http://localhost:56528/getInfo.html) 05:12:49 INFO - PROCESS | 1919 | 2016-11-25 05:12:49.038 plugin-container[1920:20483] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 05:12:49 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:12:49 INFO - PROCESS | 1919 | 2016-11-25 05:12:49.041 plugin-container[1920:20483] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 05:12:49 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:12:49 INFO - PROCESS | 1919 | 2016-11-25 05:12:49.041 plugin-container[1920:20483] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:12:49 INFO - PROCESS | 1919 | __metrics Screen width/height:1600/1200 05:12:49 INFO - PROCESS | 1919 | colorDepth:24 05:12:49 INFO - PROCESS | 1919 | Browser inner width/height: 1024/689 05:12:49 INFO - PROCESS | 1919 | __metrics 05:12:49 ERROR - PROCESS | 1919 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 05:12:49 INFO - TEST-INFO | 1919: exit 0 05:12:49 INFO - Browser initialized. 05:12:50 INFO - Running cycle 1/1 for kraken test... 05:12:50 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpsCOr5d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:12:52 INFO - PROCESS | 1921 | 2016-11-25 05:12:52.758 plugin-container[1922:20603] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 05:12:52 INFO - PROCESS | 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:12:52 INFO - PROCESS | 1921 | 2016-11-25 05:12:52.759 plugin-container[1922:20603] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 05:12:52 INFO - PROCESS | 1921 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:12:52 INFO - PROCESS | 1921 | 2016-11-25 05:12:52.759 plugin-container[1922:20603] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:13:02 INFO - PROCESS | 1921 | RSS: Main: 242917376 05:13:02 INFO - PROCESS | 1921 | 05:13:05 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56528/tests/kraken/driver.html?testName=audio-beat-detection) 05:13:06 INFO - PROCESS | 1921 | RSS: Main: 254853120 05:13:06 INFO - PROCESS | 1921 | 05:13:09 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56528/tests/kraken/driver.html?testName=audio-dft) 05:13:09 INFO - PROCESS | 1921 | RSS: Main: 262590464 05:13:09 INFO - PROCESS | 1921 | 05:13:13 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56528/tests/kraken/driver.html?testName=audio-fft) 05:13:13 INFO - PROCESS | 1921 | RSS: Main: 265437184 05:13:13 INFO - PROCESS | 1921 | 05:13:15 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56528/tests/kraken/driver.html?testName=audio-oscillator) 05:13:16 INFO - PROCESS | 1921 | RSS: Main: 266444800 05:13:16 INFO - PROCESS | 1921 | 05:13:18 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56528/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:13:19 INFO - PROCESS | 1921 | RSS: Main: 266813440 05:13:19 INFO - PROCESS | 1921 | 05:13:21 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56528/tests/kraken/driver.html?testName=imaging-darkroom) 05:13:22 INFO - PROCESS | 1921 | RSS: Main: 272207872 05:13:22 INFO - PROCESS | 1921 | 05:13:25 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56528/tests/kraken/driver.html?testName=imaging-desaturate) 05:13:26 INFO - PROCESS | 1921 | RSS: Main: 272728064 05:13:26 INFO - PROCESS | 1921 | 05:13:29 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56528/tests/kraken/driver.html?testName=json-parse-financial) 05:13:30 INFO - PROCESS | 1921 | RSS: Main: 272601088 05:13:30 INFO - PROCESS | 1921 | 05:13:31 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56528/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:13:32 INFO - PROCESS | 1921 | RSS: Main: 271822848 05:13:32 INFO - PROCESS | 1921 | 05:13:34 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:13:35 INFO - PROCESS | 1921 | RSS: Main: 271978496 05:13:35 INFO - PROCESS | 1921 | 05:13:37 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:13:38 INFO - PROCESS | 1921 | RSS: Main: 271974400 05:13:38 INFO - PROCESS | 1921 | 05:13:41 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:13:41 INFO - PROCESS | 1921 | RSS: Main: 272289792 05:13:41 INFO - PROCESS | 1921 | 05:13:44 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:13:45 INFO - PROCESS | 1921 | RSS: Main: 272166912 05:13:45 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:56528/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56528/tests/kraken/driver.html?testName=ai-astar) 05:13:47 INFO - PROCESS | 1921 | RSS: Main: 274038784 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | __start_tp_report 05:13:47 INFO - PROCESS | 1921 | _x_x_mozilla_page_load 05:13:47 INFO - PROCESS | 1921 | _x_x_mozilla_page_load_details 05:13:47 INFO - PROCESS | 1921 | |i|pagename|runs| 05:13:47 INFO - PROCESS | 1921 | |0;ai-astar;106;121;117;117;117;117;118;118;117;121 05:13:47 INFO - PROCESS | 1921 | |1;audio-beat-detection;155;115;119;155;118;123;124;152;169;137 05:13:47 INFO - PROCESS | 1921 | |2;audio-dft;212;230;199;199;198;201;212;278;226;199 05:13:47 INFO - PROCESS | 1921 | |3;audio-fft;85;61;60;89;59;62;61;58;93;68 05:13:47 INFO - PROCESS | 1921 | |4;audio-oscillator;90;72;85;73;71;72;84;89;102;97 05:13:47 INFO - PROCESS | 1921 | |5;imaging-gaussian-blur;93;83;132;101;91;90;94;91;93;89 05:13:47 INFO - PROCESS | 1921 | |6;imaging-darkroom;152;151;98;99;98;107;149;107;154;110 05:13:47 INFO - PROCESS | 1921 | |7;imaging-desaturate;141;91;92;94;94;92;79;144;129;90 05:13:47 INFO - PROCESS | 1921 | |8;json-parse-financial;57;56;58;59;58;58;57;60;59;58 05:13:47 INFO - PROCESS | 1921 | |9;json-stringify-tinderbox;71;63;99;68;98;107;63;69;103;63 05:13:47 INFO - PROCESS | 1921 | |10;stanford-crypto-aes;104;110;111;92;89;89;90;88;89;90 05:13:47 INFO - PROCESS | 1921 | |11;stanford-crypto-ccm;171;168;168;152;158;157;159;150;190;164 05:13:47 INFO - PROCESS | 1921 | |12;stanford-crypto-pbkdf2;178;176;177;177;179;176;175;179;179;180 05:13:47 INFO - PROCESS | 1921 | |13;stanford-crypto-sha256-iterative;67;69;70;67;72;78;92;76;69;68 05:13:47 INFO - PROCESS | 1921 | __end_tp_report 05:13:47 INFO - PROCESS | 1921 | __start_cc_report 05:13:47 INFO - PROCESS | 1921 | _x_x_mozilla_cycle_collect,963 05:13:47 INFO - PROCESS | 1921 | __end_cc_report 05:13:47 INFO - PROCESS | 1921 | __startTimestamp1480079627468__endTimestamp 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | ------- Summary: start ------- 05:13:47 INFO - PROCESS | 1921 | Number of tests: 14 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | [#0] ai-astar Cycles:10 Average:116.90 Median:117.50 stddev:4.15 (3.5%) stddev-sans-first:1.69 05:13:47 INFO - PROCESS | 1921 | Values: 106.0 121.0 117.0 117.0 117.0 117.0 118.0 118.0 117.0 121.0 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:144.50 stddev:19.52 (13.5%) stddev-sans-first:19.55 05:13:47 INFO - PROCESS | 1921 | Values: 155.0 115.0 119.0 155.0 118.0 123.0 124.0 152.0 169.0 137.0 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | [#2] audio-dft Cycles:10 Average:215.40 Median:212.00 stddev:24.91 (11.7%) stddev-sans-first:26.39 05:13:47 INFO - PROCESS | 1921 | Values: 212.0 230.0 199.0 199.0 198.0 201.0 212.0 278.0 226.0 199.0 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | [#3] audio-fft Cycles:10 Average:69.60 Median:65.00 stddev:13.78 (21.2%) stddev-sans-first:13.44 05:13:47 INFO - PROCESS | 1921 | Values: 85.0 61.0 60.0 89.0 59.0 62.0 61.0 58.0 93.0 68.0 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | [#4] audio-oscillator Cycles:10 Average:83.50 Median:84.50 stddev:11.21 (13.3%) stddev-sans-first:11.64 05:13:47 INFO - PROCESS | 1921 | Values: 90.0 72.0 85.0 73.0 71.0 72.0 84.0 89.0 102.0 97.0 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:91.00 stddev:13.52 (14.9%) stddev-sans-first:14.31 05:13:47 INFO - PROCESS | 1921 | Values: 93.0 83.0 132.0 101.0 91.0 90.0 94.0 91.0 93.0 89.0 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | [#6] imaging-darkroom Cycles:10 Average:122.50 Median:153.00 stddev:25.31 (16.5%) stddev-sans-first:24.49 05:13:47 INFO - PROCESS | 1921 | Values: 152.0 151.0 98.0 99.0 98.0 107.0 149.0 107.0 154.0 110.0 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | [#7] imaging-desaturate Cycles:10 Average:104.60 Median:91.50 stddev:23.73 (25.9%) stddev-sans-first:21.20 05:13:47 INFO - PROCESS | 1921 | Values: 141.0 91.0 92.0 94.0 94.0 92.0 79.0 144.0 129.0 90.0 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:58.00 stddev:1.15 (2.0%) stddev-sans-first:1.17 05:13:47 INFO - PROCESS | 1921 | Values: 57.0 56.0 58.0 59.0 58.0 58.0 57.0 60.0 59.0 58.0 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | [#9] json-stringify-tinderbox Cycles:10 Average:80.40 Median:68.50 stddev:18.72 (27.3%) stddev-sans-first:19.55 05:13:47 INFO - PROCESS | 1921 | Values: 71.0 63.0 99.0 68.0 98.0 107.0 63.0 69.0 103.0 63.0 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | [#10] stanford-crypto-aes Cycles:10 Average:95.20 Median:89.00 stddev:9.30 (10.4%) stddev-sans-first:9.30 05:13:47 INFO - PROCESS | 1921 | Values: 104.0 110.0 111.0 92.0 89.0 89.0 90.0 88.0 89.0 90.0 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | [#11] stanford-crypto-ccm Cycles:10 Average:163.70 Median:166.00 stddev:11.58 (7.0%) stddev-sans-first:11.97 05:13:47 INFO - PROCESS | 1921 | Values: 171.0 168.0 168.0 152.0 158.0 157.0 159.0 150.0 190.0 164.0 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:178.50 stddev:1.65 (0.9%) stddev-sans-first:1.74 05:13:47 INFO - PROCESS | 1921 | Values: 178.0 176.0 177.0 177.0 179.0 176.0 175.0 179.0 179.0 180.0 05:13:47 INFO - PROCESS | 1921 | 05:13:47 INFO - PROCESS | 1921 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:71.00 stddev:7.70 (10.8%) stddev-sans-first:7.88 05:13:47 INFO - PROCESS | 1921 | Values: 67.0 69.0 70.0 67.0 72.0 78.0 92.0 76.0 69.0 68.0 05:13:47 INFO - PROCESS | 1921 | -------- Summary: end -------- 05:13:47 INFO - PROCESS | 1921 | 05:13:48 INFO - TEST-INFO | 1921: exit 0 05:13:48 INFO - TEST-OK | kraken | took 62628ms 05:13:48 INFO - SUITE-END | took 1179s 05:13:48 INFO - Completed test suite (00:19:40) 05:13:48 INFO - javascript benchmark 05:13:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7056.836853675235, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3123.6407813101473, 3163.051084674597, 3321.6844399782017, 3333.9833300833507, 3365.326934613078, 5545.805896793603, 5566.359868615111, 5573.498385145336, 5631.239782629344, 5697.6011679182475, 4566.885827854297, 5458.263134476844, 5522.0336441122745, 5540.667559946406, 5562.332520097594, 5099.694018358892, 5124.436312005676, 5153.896922061575, 5244.501772331641, 5267.52592266696, 5080.949190508103, 5254.57963362932, 5277.340332458442, 5282.0, 5292.338457692788, 5082.390113186404, 5209.973950130244, 5212.452692467275, 5272.156454967206, 5275.314209152824, 5077.669951453181, 5098.107831129574, 5160.8193713220235, 5178.352705911761, 5184.170532714747, 5152.742362881841, 5157.8452646420665, 5179.067767801795, 5183.896322073575, 5208.817691380784, 5497.945020549783, 5653.434656534355, 5732.0, 5736.168255602956, 5810.4770570648425, 5030.496950304977, 6047.848803779941, 6078.787242446538, 6172.382761723792, 6232.906506402408, 5032.949670503284, 6107.7957376609465, 6143.204841796972, 6182.907256391158, 6188.907166392508, 4725.5510726480925, 5805.651660900359, 5807.709614519257, 5808.07070868659, 5813.622114562582, 5257.7896884124975, 6089.878202436015, 6148.6925653717135, 6153.815385538352, 6180.103884936659, 5956.1065840123465, 5996.460318571262, 6026.096085587197, 6043.244594425697, 6134.0, 6131.590761710035, 6133.405314618231, 6138.416850399205, 6149.5080393568405, 6152.78465253714, 5437.075697131497, 5999.850003749854, 6076.817695469143, 6092.847678807977, 6144.201253836973, 5279.498447647467, 6150.846228844314, 6166.383361663798, 6175.382461753788, 6246.406591373812, 4883.902321953541, 4902.632302577321, 4907.7300748458465, 4913.70517768935, 4946.307516947631, 4291.914161716748, 4294.82820687169, 4327.1345730853955, 4365.08333250021, 4365.886698891826, 5680.204771332017, 5852.502537284294, 5871.383504732027, 5897.587168898136, 5915.911261331084, 6185.350538193515, 6201.81394558164, 6207.099970504251, 6215.968920155461, 6238.594491358047, 5654.660720356792, 5727.942720572824, 5737.139429085671, 5824.796132135355, 5854.121881717776, 4486.75322857246, 5286.920696189561, 5294.41761406245, 5322.520973112439, 5335.62650614461, 6199.907001394983, 6274.905876411949, 6281.591696539802, 6288.591241569284, 6294.842628934405, 6109.572329937039, 6163.229596300463, 6174.38256174379, 6203.844903877259, 6217.875642487215, 6110.133516259585, 6132.845047373448, 6136.365905528278, 6181.350958149509, 6214.9067763982675, 6111.786087487095, 6119.4798442132915, 6139.0, 6139.0, 6166.4758495526585, 6036.426539478743, 6079.088136779518, 6159.414855588712, 6175.752969881154, 6176.536759742948, 5943.078822782476, 5964.224650795369, 5974.701264936823, 6082.087686847009, 6129.724162412791, 6001.609895356788, 6010.398960104129, 6013.909791353046, 6031.668258245839, 6036.517078633787, 5334.9466505336, 5799.884002320015, 5806.129080637938, 5872.500837428866, 5917.053271476543, 5995.61028533144, 6041.969790151021, 6062.42406971337, 6068.120122582112, 6099.786507472217, 5899.085641725539, 5950.16697662319, 5964.642121472725, 5982.880342393215, 5995.490383317467], "value": 5601.804919230318, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.3820033955856, 476.64728101205105, 483.37885816725503, 525.3748039832599, 527.854839919022, 36876.63123368766, 40999.79500102499, 41035.58964410359, 41134.17731645365, 41153.17693646133, 39588.60411395885, 40138.0, 40189.39715904264, 40189.799051004775, 40317.0, 8241.0, 9177.587008584629, 9361.48511831849, 9373.484458354787, 9425.717228483121, 8310.168983101672, 10943.0, 11039.889601104007, 11214.757048590274, 11374.772504549905, 11209.383483908381, 11292.548298068068, 11324.263922844988, 11355.0, 11377.293405989085, 11235.494402751854, 11281.153913456523, 11296.830547541753, 11296.830547541793, 11307.434628268553, 8283.50298982063, 9037.277017838556, 9370.812583748355, 10586.0, 11080.5622762992, 34462.44918978652, 36408.089797755005, 37919.810400948096, 38331.85004449871, 38338.23323533514, 3021.531662592302, 3240.0179006513754, 3267.640559538449, 3400.846961886721, 3647.2705458908326, 28957.986470473432, 29160.56259156114, 29716.0, 29827.850860745773, 30016.69983300161, 3150.2596889730894, 3164.810111393324, 3172.4924012157944, 3245.594300712411, 3273.3289675616443, 432680.0, 450921.0, 452094.7395263003, 452976.0, 454093.9999999967, 49668.0, 49698.50301497011, 49816.0036799262, 49839.0, 49934.25098623524, 47410.051798963825, 58463.0, 58489.41510584925, 58655.41344586585, 58712.0, 14897.851021489863, 15354.462593809163, 15357.0, 15404.46084387041, 15411.22943852803, 10860.239783215256, 11060.446977651085, 11821.704457388634, 11906.642800715992, 12027.75944481123, 14907.850921490646, 14959.850401496064, 15080.0, 15126.39494420233, 15379.461718839786, 6536.379043990813, 6543.705533251015, 6570.0, 6612.900806487906, 6613.4709223262025, 806.5080301016493, 857.8155696525278, 965.6234068713208, 965.9953648205787, 1003.5333569890037, 24676.012959481777, 27586.586201207, 28306.0, 28645.57031644527, 28983.710162898522, 871.7297637732323, 968.3899143539525, 996.4270544436978, 1081.8377243413552, 1147.2198904744853, 13470.0, 14827.332770025374, 15014.0, 15112.168830714196, 15170.0, 541294.0, 562617.0, 632955.0, 634224.2353341129, 636088.0, 45332.0, 54028.72985635047, 54312.45687543153, 54395.45604543904, 54657.0, 1321.7224382879704, 1343.7111021130504, 1354.7629164896105, 1367.8768910798076, 1375.0786972728297, 22545.549089018456, 24696.641684707512, 27390.0, 28455.59710621, 29136.125916222547, 47523.04953900972, 47854.5214547864, 47870.0, 47884.0, 48833.26750098822, 541257.0, 543396.0, 543556.0, 544404.0, 544581.0, 29034.12897613075, 31587.0, 32430.189245269055, 38255.42616860805, 38941.80529097337, 43673.0, 44302.55697442984, 44515.33227001533, 45118.77440612776, 45178.77410612926, 36195.0, 37023.629763703095, 37106.8144659275, 37340.439893401075, 37354.0, 36132.4580131293, 36381.27237455289, 36495.27009459849, 37415.81292093522, 37589.248215035, 36111.45832812457, 36187.63812361948, 36280.81859590685, 36651.0, 36736.448953265186, 31315.686843131276, 32574.34851303008, 32614.51078233781, 32717.34565308633, 32724.34551309008, 23740.0, 24134.75865241325, 24394.75605243925, 24619.753802462463, 24623.38441538976], "value": 19215.24396466565, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.6945406045348, 344.9120474279059, 361.0000299171294, 372.9049092481417, 390.00159591445885, 18564.25742970281, 19079.904600476995, 20274.59450810983, 20298.898505507488, 20515.897420512876, 19808.801911980874, 19976.700349494768, 20018.49953751157, 20214.797852021475, 20243.39269821904, 5686.914696279559, 5926.3184733755525, 5983.0, 5984.5511586630955, 5997.130416089664, 5616.438356164372, 5832.62087964281, 5837.503812175949, 5913.438223368773, 5926.496247818919, 5593.412691667357, 5840.474357307841, 5860.882782344351, 5870.530357571384, 5872.677002764846, 5747.109198074284, 5867.090600956859, 5868.735906884201, 5878.50032747217, 5878.617889837147, 5001.9749901250625, 5134.614903882187, 5146.562542183919, 5149.845504634867, 5291.814786482493, 12777.23336599807, 13025.47898084085, 13054.412551435209, 13066.869331306756, 18087.547811304823, 1905.295040834886, 2115.788421157887, 2235.2065016918923, 2823.759980401668, 2843.1186332236866, 7828.725994590219, 8625.353098517637, 9114.81770364589, 9275.0, 9545.997670244664, 1492.3433689176727, 1783.3579911231946, 1798.6043070524463, 1806.096951524238, 1814.891106533599, 121515.3924230382, 125402.0, 128862.0, 132228.0, 136152.51161186132, 11477.139214558942, 11547.595834145764, 11630.53477860893, 11639.76720465586, 11855.05159587231, 11981.580644677395, 14348.282585870666, 14491.420343186155, 14512.274386280644, 14528.85471145275, 6685.899711504331, 8334.20825021622, 8394.706185283485, 8424.452410593292, 8520.360972927056, 6878.69045892936, 8198.0, 8232.588370581447, 8317.750467485985, 8338.958305208556, 4463.509014008457, 4472.195004899149, 4480.574345437179, 4502.347159661831, 4564.817407303737, 1623.9431619893483, 1854.842338401224, 1905.1331644101901, 1905.6855618823017, 1910.1252614814894, 723.7177500774702, 779.6998155710093, 825.471698113208, 882.2060145868769, 926.0600490502146, 17217.741733874, 18398.0, 20118.49703757418, 20196.79803201949, 21010.054547545093, 861.8534849075672, 918.2195134136065, 920.995395023034, 926.416357694648, 971.708487453761, 8723.302135829119, 10881.727956801144, 10915.399653019002, 10950.890491094988, 11027.952344527257, 550573.0, 577327.0, 601623.0, 602835.0, 604819.0, 113789.4310528442, 117324.0, 135196.0, 139490.0, 145954.0, 877.9604917778715, 931.930105242096, 975.4001289207087, 976.2970661123979, 989.3223142147796, 16636.750448743038, 18472.353467628567, 18824.341148059753, 19137.712934305717, 19338.70991935094, 55222.723886381915, 56349.71825141012, 56843.0, 57737.71131144317, 59634.0, 85307.0, 85395.0, 86033.56983215043, 86543.0, 86924.83052372547, 7826.41301902371, 7955.204479551999, 8035.276825085771, 8115.391345648982, 8201.71294004707, 12445.62663120108, 12600.684982875502, 12609.49562017509, 12825.102242842911, 12948.352582370655, 10397.168226541708, 10862.293950893167, 11114.44427778624, 11197.496112675111, 11421.0, 10811.35131892089, 10858.022278195389, 10929.0, 11181.776364472827, 11202.327860328407, 10792.29850059775, 11124.777504450027, 11201.0, 11297.26567773092, 11380.886191137983, 7807.765767026998, 8031.1567285434185, 8069.233422824822, 8081.0, 8178.550179740171, 9064.0, 10204.387736735524, 10751.784964300827, 10845.728856778329, 10895.509702063584], "value": 9240.198714534483, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1236.585743775835, 1449.9637509062275, 1597.8641815445687, 1758.9120543972815, 1799.8290162434573, 6448.161738973933, 7316.268373162681, 7610.391168706504, 7917.881231781521, 8308.875366869486, 7737.883931741015, 8003.519788812675, 8029.678812847479, 8150.836983260331, 8160.551169685665, 1879.4079864842531, 2280.361498780344, 2303.09027933966, 2303.1823702585593, 2303.9539209215804, 2318.211807985285, 2336.4509340305017, 2338.5790557699606, 2340.5903966805827, 2351.329870986769, 2214.236088549456, 2254.9436264093365, 2281.121768119278, 2283.04112272845, 2294.2199652118275, 1940.7574053243345, 2092.299079808266, 2129.58473097746, 2270.7729227077325, 2280.8859557022083, 2294.4837411582425, 2319.3853628788215, 2320.1879342230254, 2320.2111282164146, 2323.814094872423, 8197.71308004217, 8252.917470825276, 8262.256396924306, 8267.875981860278, 8271.793205169859, 4510.939929116655, 4865.44047434546, 5125.974370128163, 5136.948630513684, 5151.381834179885, 3014.8191108533556, 3213.469777486712, 3232.9030129096163, 3248.431524483206, 3520.9119772005906, 1040.4069680282241, 1065.7442213868617, 1079.7084787107437, 1082.3019152646498, 1086.0388556127805, 8767.473951562926, 9300.813983720356, 10410.42742649154, 10456.686299411105, 10480.737981550446, 4220.8051813057655, 4300.548442413564, 4389.648828093808, 4423.358613001097, 4423.579759922866, 8337.583120843934, 8479.364047696448, 8479.915200848036, 8491.065982741893, 8503.149685031447, 4997.375328083989, 5025.723585202776, 5035.446100928968, 5037.269595908573, 5049.798008079709, 4200.348945913327, 4502.302143167814, 4712.434507859079, 4734.124187025412, 4734.360861283682, 3536.734744894143, 3544.556930383702, 3655.469956856241, 3655.908602284964, 3682.287736202625, 1130.6042884990188, 1154.261272785418, 1159.1769843411105, 1160.2342453980332, 1163.0521125282867, 777.5218240781992, 787.4291138924349, 807.3097501636016, 860.1871231685997, 867.9801233550553, 4334.804933777988, 5227.581793456515, 5323.866903327448, 5476.561875049986, 5684.459976302244, 891.826093911681, 929.9256059515222, 935.6257497001118, 995.2187532786728, 1032.210359075308, 3600.477930700034, 3725.4784330193793, 3823.4456003879836, 3830.75100118497, 3884.475595794588, 9450.007749186374, 9924.702258932244, 9946.055124763136, 9967.252456065824, 9967.950160249298, 9166.49584272858, 9396.295277854051, 9887.406755594688, 9923.60305587783, 9926.454045027449, 7340.632968351668, 7653.693852245848, 7660.119086305035, 7673.846523069507, 7715.22847715224, 5256.842294731164, 5382.596305277041, 5489.231507589021, 5542.75057622416, 5652.756393593403, 8847.336449766164, 8995.190432861074, 9080.682176123804, 9153.084691530794, 9162.312826537904, 9100.044495327895, 9150.039245879087, 9170.58732357059, 9300.906990930003, 9305.441673499612, 5912.911306330495, 6758.749631318222, 7004.754833580801, 7402.814929626802, 7500.024996750388, 7073.82315442118, 7433.9256607433235, 7607.695692172251, 7718.0, 7787.10448298453, 6760.121184246016, 6921.584704917721, 6999.230084690782, 7042.577445353295, 7073.752418665322, 6883.862322753617, 6884.965575172092, 6886.48351373639, 6921.965390173017, 6933.1333583302085, 6493.480521558169, 6521.282658907421, 6525.314841941527, 6595.901061484177, 6605.669716514251, 4662.790174442091, 4762.833300834454, 4784.091022705675, 4804.399450068741, 4902.289168070681, 4758.2386818108935, 4843.806247750051, 4864.902701945871, 4884.92672609904, 4903.141950158779], "value": 4277.6068700158, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.81690366032797, 269.2071848406444, 296.67662248149526, 305.7951172714282, 308.79156569315705, 5115.718635475047, 5193.195054766517, 5278.993982286725, 5338.0, 5452.945470545294, 4942.5304596063315, 5076.84769456916, 5269.789208431668, 5307.495787900153, 5339.439358867321, 3714.3685573452576, 3715.182659814843, 3743.4010558310674, 3862.942055869164, 4145.792710364485, 3799.620037996192, 4138.917221655565, 4145.3781932710035, 4145.631941459311, 4158.771267580297, 3929.76421414716, 3990.620891015349, 4012.8194231259663, 4025.4364388985564, 4029.952212424776, 3997.9200415991663, 4013.9397909031386, 4032.9395059074136, 4043.1307268937026, 4044.2315959967514, 3043.9391212175856, 3501.38725723, 3511.6312787157235, 3521.559805024372, 3522.8590856365713, 4546.318052292149, 4815.253635686474, 4988.7505624718615, 5090.312807770941, 5223.71269580173, 4039.4748682671066, 4566.908661826778, 4584.701994370389, 4596.678232523725, 4608.953910460886, 5577.581681373913, 5639.323281206241, 5654.105874532037, 5654.321481422215, 5675.716214189315, 3253.316803471274, 3554.875579354736, 3760.909336292472, 3832.2335532893535, 3841.2509560635685, 4889.682170658932, 4893.21708526634, 5103.030424219386, 5626.493615574618, 5908.586398952075, 3885.0, 3898.161895192547, 3906.746061505993, 3925.1747937209334, 3928.862489812843, 3752.6810221130963, 3780.111673756665, 3939.3848522106373, 3940.4680368150507, 3958.5249770027203, 4170.937435938464, 4193.30810416284, 4500.672301671015, 4548.363229147922, 4568.017876156576, 4928.211486162197, 5260.28986086881, 5640.520555752807, 5660.264165658438, 5665.9716701417055, 1180.9350485723196, 1195.5397172088808, 1253.4610117649315, 1258.3079306381453, 1262.72851336963, 738.5716284554945, 833.7248707926369, 860.7245681381897, 860.7503823891002, 868.6091258933506, 4127.587241275848, 4137.420761093449, 4711.316859055418, 4867.0, 5076.791595655902, 4870.439899411613, 4887.755612219375, 5233.4243233244315, 5548.805791797267, 5641.633293835969, 4845.2799256264225, 5028.7176769923435, 5037.3955125384455, 5048.7728052237, 5065.366829146356, 2471.555120078403, 2860.7269764954813, 2863.642044744407, 2893.9855300723784, 2897.2032691009717, 5074.8835256243565, 5637.379888212294, 5709.800156994485, 5722.427757224327, 5741.971290143522, 4763.95236047642, 5046.873828154252, 5308.256844041837, 5395.246544872932, 5496.0, 4828.25065769435, 4988.775505102279, 6146.5390095743, 6170.475509581556, 6184.257889053342, 4908.852734417973, 4984.40187177541, 5592.888142237214, 6005.219321488178, 6020.458158765732, 5006.974965125151, 5426.375966763872, 5796.391378905154, 5815.76736930518, 5866.853328666817, 6065.908136535466, 6075.420390698362, 6086.847828804315, 6122.204113465399, 6143.354947730424, 3358.1940334320075, 3525.982370088133, 3865.497485327002, 3865.632764887331, 3900.3369427198013, 5520.199571062254, 5552.611317207757, 5811.651300921958, 5877.0302900021015, 5907.0, 4454.933176002427, 4908.901821963469, 5159.942211846487, 5597.832065038056, 5775.537956963517, 5293.5765138789575, 5472.199468563335, 5547.33431988164, 5635.999220276906, 5743.1385292206505, 4474.709143905636, 5573.442655734394, 5584.692841893735, 5599.944000559943, 5615.915761263503, 4327.826886924614, 5190.2214667800135, 5206.8958620828125, 5663.915041274467, 5744.712764361849, 4929.654924155275, 5613.494785469327, 5629.634073785191, 5640.774369025357, 5661.745221464962], "value": 4023.9571676773494, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.9001094841245, 690.4683393786785, 716.154937174135, 754.2306847016043, 775.1554760186274, 14283.428662853486, 14842.035267707619, 14866.884983626243, 14894.180820054906, 14938.850611493881, 13481.73036539271, 13617.046806723532, 13619.523316683919, 13646.044776865623, 13682.0, 637.1112298343808, 660.1055569703055, 691.6783695581541, 793.2067932067932, 821.1542111625006, 813.6379311206515, 818.9467684600481, 834.3658819297317, 835.1022650650544, 839.4669384940546, 880.0231742765557, 880.7137680253911, 884.0628933330657, 884.5975081337975, 884.7080463447079, 859.9054104048549, 871.5772850167664, 874.7113452560671, 877.6708734224698, 879.9868001979976, 651.9641419721913, 652.2336254900473, 652.3313603556345, 652.9934700653027, 653.7189008726245, 14306.864802620108, 14386.208758518278, 14395.2082635455, 14477.638059048502, 14532.854671453257, 13850.515231966934, 13866.930665346608, 13986.93006534971, 14189.716205675932, 14307.78538321926, 11225.831612525819, 11464.0, 11480.138989575815, 11493.367864767435, 11667.59163429276, 3681.4845921571, 3689.2252626948375, 3793.0, 3807.809609519513, 3868.2456920900436, 87622.99999999936, 89261.55369223177, 89300.0, 89521.0, 89781.0, 10042.096211340868, 10570.841437378445, 10824.07995320391, 10924.617638382777, 10971.780564388668, 21912.780872191073, 21945.341639750834, 21951.780482195292, 21977.0, 21978.340649780534, 5653.349864765522, 7690.346320562815, 7805.960970195226, 7825.256600622932, 7839.568823714636, 3640.21735326906, 4195.97902010488, 4239.639630631369, 4322.416473776063, 4415.470143582726, 3373.274745929588, 3443.6900678939014, 3476.1830969722096, 3483.5993860705835, 3493.737969652286, 616.7933742196325, 631.8388810853203, 640.4620119099978, 641.1760887259849, 648.4326214562258, 554.3071161048689, 637.9458144773821, 656.4190691238289, 658.2392101129459, 659.2649196146263, 8071.09440601383, 8168.836623267502, 8704.782380440412, 9417.246620270362, 9751.219902407933, 617.8084793713963, 619.0559396919733, 641.0, 653.6992983227674, 684.5789839248827, 3632.219072899339, 4161.795417528547, 4259.9787001065415, 4359.324304732771, 4410.404595379647, 100512.99487005036, 105007.0, 109934.90065099247, 110293.44853275683, 110904.0, 60314.19057428284, 69690.65154674194, 82598.0, 86746.13253867507, 95446.13661590163, 45653.77173114113, 59307.40692593105, 69873.65063174753, 85558.5722071398, 87743.56128219445, 8622.0, 9452.338336316392, 9547.665831695856, 9652.227821774382, 9802.117809397188, 38066.61933380631, 50459.4862154136, 51174.74412627913, 51672.741636291576, 52287.95424091573, 59170.0, 77683.4463310742, 79458.0, 80744.0, 81296.10403803128, 9959.177194783957, 11792.823107653563, 12335.568255111028, 12471.563495277986, 12728.61814145577, 14031.368588413341, 14238.928805355907, 14365.569032929028, 14366.13803171813, 14367.712645746817, 13144.27706476153, 13350.332483375987, 13381.799273010718, 13408.061435699407, 13424.79176874086, 13176.934115329363, 13288.800667989795, 13295.867041329462, 13563.186208827501, 13760.449582017007, 12745.681357966125, 12877.935610322264, 12916.178019837495, 13232.669183270495, 13402.128861623964, 10394.116500097274, 10524.579016839547, 10552.894471055499, 10724.249302549058, 10969.341839489656, 10759.515821788194, 11087.501062452371, 11191.832122518332, 11352.0, 11392.658220253406], "value": 7212.793888068302, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1592.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 121.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0, 121.0], "value": 116.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 115.0, 119.0, 155.0, 118.0, 123.0, 124.0, 152.0, 169.0, 137.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 230.0, 199.0, 199.0, 198.0, 201.0, 212.0, 278.0, 226.0, 199.0], "value": 215.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 61.0, 60.0, 89.0, 59.0, 62.0, 61.0, 58.0, 93.0, 68.0], "value": 69.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 85.0, 73.0, 71.0, 72.0, 84.0, 89.0, 102.0, 97.0], "value": 83.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 83.0, 132.0, 101.0, 91.0, 90.0, 94.0, 91.0, 93.0, 89.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 98.0, 99.0, 98.0, 107.0, 149.0, 107.0, 154.0, 110.0], "value": 122.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 91.0, 92.0, 94.0, 94.0, 92.0, 79.0, 144.0, 129.0, 90.0], "value": 104.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 58.0, 59.0, 58.0, 58.0, 57.0, 60.0, 59.0, 58.0], "value": 58.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 63.0, 99.0, 68.0, 98.0, 107.0, 63.0, 69.0, 103.0, 63.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 110.0, 111.0, 92.0, 89.0, 89.0, 90.0, 88.0, 89.0, 90.0], "value": 95.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 168.0, 168.0, 152.0, 158.0, 157.0, 159.0, 150.0, 190.0, 164.0], "value": 163.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 176.0, 177.0, 177.0, 179.0, 176.0, 175.0, 179.0, 179.0, 180.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 70.0, 67.0, 72.0, 78.0, 92.0, 76.0, 69.0, 68.0], "value": 72.8, "unit": "score"}]}]} 05:13:48 INFO - Return code: 0 05:13:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:13:48 ERROR - # TBPL SUCCESS # 05:13:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:13:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:13:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:13:48 INFO - 2016-11-25 05:13:48.782 screenresolution[1926:20983] starting screenresolution argv=screenresolution get 05:13:48 INFO - 2016-11-25 05:13:48.786 screenresolution[1926:20983] Display 0: 1600x1200x32@60 05:13:48 INFO - 2016-11-25 05:13:48.793 screenresolution[1927:20986] starting screenresolution argv=screenresolution list 05:13:48 INFO - Available Modes on Display 0 05:13:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:13:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:13:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:13:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:13:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:13:49 INFO - Intel Iris: 05:13:49 INFO - Chipset Model: Intel Iris 05:13:49 INFO - Type: GPU 05:13:49 INFO - Bus: Built-In 05:13:49 INFO - VRAM (Dynamic, Max): 1536 MB 05:13:49 INFO - Vendor: Intel (0x8086) 05:13:49 INFO - Device ID: 0x0a2e 05:13:49 INFO - Revision ID: 0x0009 05:13:49 INFO - Displays: 05:13:49 INFO - Display: 05:13:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:13:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:13:49 INFO - Display Serial Number: 1600x1200 60 05:13:49 INFO - Main Display: Yes 05:13:49 INFO - Mirror: Off 05:13:49 INFO - Online: Yes 05:13:49 INFO - Rotation: Supported 05:13:49 INFO - Return code: 0 05:13:49 INFO - Running post-action listener: _resource_record_post_action 05:13:49 INFO - [mozharness: 2016-11-25 13:13:49.039712Z] Finished run-tests step (success) 05:13:49 INFO - Running post-run listener: _resource_record_post_run 05:13:49 INFO - Total resource usage - Wall time: 1215s; CPU: 55.0%; Read bytes: 964096; Write bytes: 693299200; Read time: 10; Write time: 1905 05:13:49 INFO - TinderboxPrint: CPU usage
54.6% 05:13:49 INFO - TinderboxPrint: I/O read bytes / time
964,096 / 10 05:13:49 INFO - TinderboxPrint: I/O write bytes / time
693,299,200 / 1,905 05:13:49 INFO - TinderboxPrint: CPU idle
2,204.4 (45.4%) 05:13:49 INFO - TinderboxPrint: CPU system
987.8 (20.3%) 05:13:49 INFO - TinderboxPrint: CPU user
1,665.6 (34.3%) 05:13:49 INFO - TinderboxPrint: Swap in / out
383,692,800 / 0 05:13:49 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 365908480; Write bytes: 366560768; Read time: 28846; Write time: 619 05:13:49 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 960000; Write bytes: 308204032; Read time: 10; Write time: 1243 05:13:49 INFO - Running post-run listener: _upload_blobber_files 05:13:49 INFO - Blob upload gear active. 05:13:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:13:49 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 05:13:49 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:13:49 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:13:49 INFO - (blobuploader) - INFO - Open directory for files ... 05:13:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:13:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:13:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:50 INFO - (blobuploader) - INFO - Done attempting. 05:13:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:13:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:13:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:51 INFO - (blobuploader) - INFO - Done attempting. 05:13:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:13:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:13:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:54 INFO - (blobuploader) - INFO - Done attempting. 05:13:54 INFO - (blobuploader) - INFO - Iteration through files over. 05:13:54 INFO - Return code: 0 05:13:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:13:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:13:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/b8b64f2edd8d65d1f0f50149cdd8a3fd2da1f33b6b46c4fc1898f677fdfed4b18a2f46ea9ca3180bc73c22342483dae0e3ebf91fd3e434ddf1dbd4a229f11581", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ca2a655b975a03eb419579f7871ff7f8fea8e658f48039c2e77a3447650a7098993ea10e5a99e979dada65bf40f8e6e3c3c0547524a714b16ed3a44dae1cb29f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/02c4cb6df56fa3658d0ecf532f9301ee2d521b40468eb3824f54e47ecb51910d7fadcb94512a6cc8c34258aa78182d43b100ed13d6f37f810d34c7d70e03e4e8"} 05:13:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:13:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:13:54 INFO - Contents: 05:13:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/b8b64f2edd8d65d1f0f50149cdd8a3fd2da1f33b6b46c4fc1898f677fdfed4b18a2f46ea9ca3180bc73c22342483dae0e3ebf91fd3e434ddf1dbd4a229f11581", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ca2a655b975a03eb419579f7871ff7f8fea8e658f48039c2e77a3447650a7098993ea10e5a99e979dada65bf40f8e6e3c3c0547524a714b16ed3a44dae1cb29f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/02c4cb6df56fa3658d0ecf532f9301ee2d521b40468eb3824f54e47ecb51910d7fadcb94512a6cc8c34258aa78182d43b100ed13d6f37f810d34c7d70e03e4e8"} 05:13:54 INFO - Running post-run listener: copy_logs_to_upload_dir 05:13:54 INFO - Copying logs to upload dir... 05:13:54 INFO - mkdir: /builds/slave/test/build/upload/logs 05:13:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.724110 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-25 05:13:54.186394) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 05:13:54.190033) ========= 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.xjSrBRUqdi/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.gJx5y85AyX/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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/Cedar/sha512/b8b64f2edd8d65d1f0f50149cdd8a3fd2da1f33b6b46c4fc1898f677fdfed4b18a2f46ea9ca3180bc73c22342483dae0e3ebf91fd3e434ddf1dbd4a229f11581", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ca2a655b975a03eb419579f7871ff7f8fea8e658f48039c2e77a3447650a7098993ea10e5a99e979dada65bf40f8e6e3c3c0547524a714b16ed3a44dae1cb29f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/02c4cb6df56fa3658d0ecf532f9301ee2d521b40468eb3824f54e47ecb51910d7fadcb94512a6cc8c34258aa78182d43b100ed13d6f37f810d34c7d70e03e4e8"} build_url:https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011155 build_url: 'https://queue.taskcluster.net/v1/task/FmnEMtM3SuOZTZFBKFBj-g/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/b8b64f2edd8d65d1f0f50149cdd8a3fd2da1f33b6b46c4fc1898f677fdfed4b18a2f46ea9ca3180bc73c22342483dae0e3ebf91fd3e434ddf1dbd4a229f11581", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ca2a655b975a03eb419579f7871ff7f8fea8e658f48039c2e77a3447650a7098993ea10e5a99e979dada65bf40f8e6e3c3c0547524a714b16ed3a44dae1cb29f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/02c4cb6df56fa3658d0ecf532f9301ee2d521b40468eb3824f54e47ecb51910d7fadcb94512a6cc8c34258aa78182d43b100ed13d6f37f810d34c7d70e03e4e8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 05:13:54.224436) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 05:13:54.224798) ========= 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.xjSrBRUqdi/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.gJx5y85AyX/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.005127 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 05:13:54.273474) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 05:13:54.273793) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 05:13:54.274108) ========= ========= Total master_lag: 0.19 =========