builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0332 starttime: 1479804129.42 results: success (0) buildid: 20161121214150 builduid: 856ae13c760b4f009905c30385a86ff8 revision: 805d42464705b0c6d7db1101c1550dc4855b4329 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:09.417189) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:09.417635) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:09.417936) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:09.447602) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:09.448037) ========= 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.7zZTIhR3oc/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.8uh3y90RaR/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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-22 00:42:09-- 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% 21.3M=0.001s 2016-11-22 00:42:09 (21.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101086 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:09.573064) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:09.573381) ========= 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.7zZTIhR3oc/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.8uh3y90RaR/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.029075 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:09.625146) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 00:42:09.625526) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 805d42464705b0c6d7db1101c1550dc4855b4329 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 805d42464705b0c6d7db1101c1550dc4855b4329 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7zZTIhR3oc/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.8uh3y90RaR/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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-22 00:42:09,722 truncating revision to first 12 chars 2016-11-22 00:42:09,722 Setting DEBUG logging. 2016-11-22 00:42:09,722 attempt 1/10 2016-11-22 00:42:09,723 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/805d42464705?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 00:42:10,904 unpacking tar archive at: graphics-805d42464705/testing/mozharness/ program finished with exit code 0 elapsedTime=1.463840 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 00:42:11.112649) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:11.114132) ========= script_repo_revision: 805d42464705b0c6d7db1101c1550dc4855b4329 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 00:42:11.114571) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-11-22 00:42:11.114898) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-11-22 00:42:13.066133) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-22 00:42:13.066539) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7zZTIhR3oc/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.8uh3y90RaR/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 00:42:13 INFO - MultiFileLogger online at 20161122 00:42:13 in /builds/slave/test 00:42:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 00:42:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:42:13 INFO - {'append_to_log': False, 00:42:13 INFO - 'base_work_dir': '/builds/slave/test', 00:42:13 INFO - 'blob_upload_branch': 'Graphics', 00:42:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:42:13 INFO - 'branch': 'Graphics', 00:42:13 INFO - 'buildbot_json_path': 'buildprops.json', 00:42:13 INFO - 'config_files': ('talos/mac_config.py',), 00:42:13 INFO - 'default_actions': ('clobber', 00:42:13 INFO - 'read-buildbot-config', 00:42:13 INFO - 'download-and-extract', 00:42:13 INFO - 'populate-webroot', 00:42:13 INFO - 'create-virtualenv', 00:42:13 INFO - 'install', 00:42:13 INFO - 'run-tests'), 00:42:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:42:13 INFO - 'download_minidump_stackwalk': True, 00:42:13 INFO - 'download_symbols': 'ondemand', 00:42:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:42:13 INFO - 'tooltool.py': '/tools/tooltool.py', 00:42:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:42:13 INFO - '/tools/misc-python/virtualenv.py')}, 00:42:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:42:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:42:13 INFO - 'installer_path': 'installer.exe', 00:42:13 INFO - 'log_level': 'info', 00:42:13 INFO - 'log_name': 'talos', 00:42:13 INFO - 'log_to_console': True, 00:42:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:42:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:42:13 INFO - 'opt_config_files': (), 00:42:13 INFO - 'pip_index': False, 00:42:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:42:13 INFO - 'cmd': ('bash', 00:42:13 INFO - '-c', 00:42:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:42:13 INFO - 'enabled': True, 00:42:13 INFO - 'halt_on_failure': False, 00:42:13 INFO - 'name': 'check_screen_resolution'},), 00:42:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:42:13 INFO - 'cmd': ('bash', 00:42:13 INFO - '-c', 00:42:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:42:13 INFO - 'enabled': True, 00:42:13 INFO - 'halt_on_failure': False, 00:42:13 INFO - 'name': 'check_screen_resolution'},), 00:42:13 INFO - 'run_cmd_checks_enabled': True, 00:42:13 INFO - 'sps_profile': False, 00:42:13 INFO - 'sps_profile_interval': 0, 00:42:13 INFO - 'suite': 'dromaeojs-e10s', 00:42:13 INFO - 'system_bits': '32', 00:42:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:42:13 INFO - 'title': 't-yosemite-r7-0332', 00:42:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:42:13 INFO - 'use_talos_json': True, 00:42:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:42:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:42:13 INFO - 'work_dir': 'build'} 00:42:13 INFO - [mozharness: 2016-11-22 08:42:13.353714Z] Running clobber step. 00:42:13 INFO - Running pre-action listener: _resource_record_pre_action 00:42:13 INFO - Running main action method: clobber 00:42:13 INFO - rmtree: /builds/slave/test/build 00:42:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:42:15 INFO - Running post-action listener: _resource_record_post_action 00:42:15 INFO - [mozharness: 2016-11-22 08:42:15.715506Z] Finished clobber step (success) 00:42:15 INFO - [mozharness: 2016-11-22 08:42:15.715769Z] Running read-buildbot-config step. 00:42:15 INFO - Running pre-action listener: _resource_record_pre_action 00:42:15 INFO - Running main action method: read_buildbot_config 00:42:15 INFO - Using buildbot properties: 00:42:15 INFO - { 00:42:15 INFO - "project": "", 00:42:15 INFO - "product": "firefox", 00:42:15 INFO - "script_repo_revision": "default", 00:42:15 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 00:42:15 INFO - "repository": "", 00:42:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 00:42:15 INFO - "buildid": "20161121214150", 00:42:15 INFO - "pgo_build": "False", 00:42:15 INFO - "basedir": "/builds/slave/test", 00:42:15 INFO - "buildnumber": 2, 00:42:15 INFO - "slavename": "t-yosemite-r7-0332", 00:42:15 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 00:42:15 INFO - "platform": "yosemite_r7", 00:42:15 INFO - "scheduler": "tests-graphics-macosx64-talos", 00:42:15 INFO - "branch": "graphics", 00:42:15 INFO - "repo_path": "projects/graphics", 00:42:15 INFO - "revision": "805d42464705b0c6d7db1101c1550dc4855b4329", 00:42:15 INFO - "stage_platform": "macosx64", 00:42:15 INFO - "builduid": "856ae13c760b4f009905c30385a86ff8", 00:42:15 INFO - "slavebuilddir": "test" 00:42:15 INFO - } 00:42:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 00:42:15 INFO - Running post-action listener: _resource_record_post_action 00:42:15 INFO - [mozharness: 2016-11-22 08:42:15.721303Z] Finished read-buildbot-config step (success) 00:42:15 INFO - [mozharness: 2016-11-22 08:42:15.721502Z] Running download-and-extract step. 00:42:15 INFO - Running pre-action listener: _resource_record_pre_action 00:42:15 INFO - Running main action method: download_and_extract 00:42:15 INFO - mkdir: /builds/slave/test/build/tests 00:42:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:15 INFO - trying https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 00:42:15 INFO - Downloading https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/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 00:42:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/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 00:42:16 INFO - Downloaded 1199 bytes. 00:42:16 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 00:42:16 INFO - Using the following test package requirements: 00:42:16 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 00:42:16 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:16 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 00:42:16 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:16 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 00:42:16 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:16 INFO - u'jsshell-mac.zip'], 00:42:16 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:16 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 00:42:16 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 00:42:16 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:16 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 00:42:16 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:16 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 00:42:16 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:16 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 00:42:16 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:42:16 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 00:42:16 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 00:42:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 00:42:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 00:42:16 INFO - Fetch https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 00:42:17 INFO - Http code: 200 00:42:17 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 00:42:17 INFO - x-amz-cf-id: RBd2Ur0FsNWFJAOUkR9jYIvUITrjaANgnftUT8sw-_d395EKF19y8w== 00:42:17 INFO - x-amz-version-id: cyjw8R_e_8uORi7EIXO.qzqiEgwKD2N5 00:42:17 INFO - Expected file size: 16282111 00:42:17 INFO - Obtained file size: 16282111 00:42:24 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 00:42:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 00:42:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 00:42:24 INFO - Fetch https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 00:42:24 INFO - Http code: 200 00:42:24 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 00:42:24 INFO - x-amz-cf-id: Xa7JRlSy8MVQzBJPLQIDcqPdg-Kb4J6ToEG8Dh090teqnnme5RCl1A== 00:42:24 INFO - x-amz-version-id: cyjw8R_e_8uORi7EIXO.qzqiEgwKD2N5 00:42:24 INFO - Expected file size: 16282111 00:42:24 INFO - Obtained file size: 16282111 00:42:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 00:42:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 00:42:31 INFO - Fetch https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 00:42:31 INFO - Http code: 200 00:42:31 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 00:42:31 INFO - x-amz-cf-id: YJ6fVK0IE2zZl-L-bBxjLkBn91Vv3OTgUx3yaiLKBJyY1gquk7xGPA== 00:42:31 INFO - x-amz-version-id: UbfA0rJLcAl1iQRs8xJeE8REvrPkJFdg 00:42:32 INFO - Expected file size: 14409392 00:42:32 INFO - Obtained file size: 14409392 00:42: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')]} 00:42:32 INFO - trying https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 00:42:32 INFO - Downloading https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 00:42:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/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 00:42:35 INFO - Downloaded 107571587 bytes. 00:42:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 00:42:35 INFO - mkdir: /builds/slave/test/properties 00:42:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:42:35 INFO - Writing to file /builds/slave/test/properties/build_url 00:42:35 INFO - Contents: 00:42:35 INFO - build_url:https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 00:42:35 INFO - Running post-action listener: _resource_record_post_action 00:42:35 INFO - Running post-action listener: set_extra_try_arguments 00:42:35 INFO - [mozharness: 2016-11-22 08:42:35.900361Z] Finished download-and-extract step (success) 00:42:35 INFO - [mozharness: 2016-11-22 08:42:35.900462Z] Running populate-webroot step. 00:42:35 INFO - Running pre-action listener: _resource_record_pre_action 00:42:35 INFO - Running main action method: populate_webroot 00:42:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:42:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:42:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:42:35 INFO - '--tppagecycles', 00:42:35 INFO - '7'], 00:42:35 INFO - 'tests': ['tsvgm']}}, 00:42:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:42:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:42:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:42:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:42:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:42:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:42:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 00:42:35 INFO - 'talos_options': ['--disable-e10s'], 00:42:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:42:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 00:42:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:42:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 00:42:35 INFO - 'talos_options': ['--disable-e10s'], 00:42:35 INFO - 'tests': ['damp', 'tps']}, 00:42:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 00:42:35 INFO - 'tests': ['damp', 'tps']}, 00:42:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:42:35 INFO - 'tests': ['dromaeo_dom']}, 00:42:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:42:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:42:35 INFO - 'tests': ['basic_compositor_video']}, 00:42:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:42:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:42:35 INFO - 'tests': ['a11yr', 00:42:35 INFO - 'ts_paint', 00:42:35 INFO - 'tpaint', 00:42:35 INFO - 'sessionrestore', 00:42:35 INFO - 'sessionrestore_no_auto_restore', 00:42:35 INFO - 'tabpaint']}, 00:42:35 INFO - 'other-e10s': {'tests': ['a11yr', 00:42:35 INFO - 'ts_paint', 00:42:35 INFO - 'tpaint', 00:42:35 INFO - 'sessionrestore', 00:42:35 INFO - 'sessionrestore_no_auto_restore', 00:42:35 INFO - 'tabpaint']}, 00:42:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:42:35 INFO - 'ts_paint', 00:42:35 INFO - 'tpaint', 00:42:35 INFO - 'sessionrestore', 00:42:35 INFO - 'sessionrestore_no_auto_restore', 00:42:35 INFO - 'tabpaint']}, 00:42:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:42:35 INFO - 'ts_paint', 00:42:35 INFO - 'tpaint', 00:42:35 INFO - 'sessionrestore', 00:42:35 INFO - 'sessionrestore_no_auto_restore', 00:42:35 INFO - 'tabpaint']}, 00:42:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:42:35 INFO - 'tests': ['a11yr', 00:42:35 INFO - 'ts_paint', 00:42:35 INFO - 'tpaint', 00:42:35 INFO - 'sessionrestore', 00:42:35 INFO - 'sessionrestore_no_auto_restore', 00:42:35 INFO - 'tabpaint']}, 00:42:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:42:35 INFO - 'tests': ['a11yr', 00:42:35 INFO - 'ts_paint', 00:42:35 INFO - 'tpaint', 00:42:35 INFO - 'sessionrestore', 00:42:35 INFO - 'sessionrestore_no_auto_restore', 00:42:35 INFO - 'tabpaint']}, 00:42:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:42:35 INFO - 'tests': ['tsvgx', 00:42:35 INFO - 'tsvgr_opacity', 00:42:35 INFO - 'tart', 00:42:35 INFO - 'tscrollx', 00:42:35 INFO - 'cart']}, 00:42:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:42:35 INFO - 'tsvgr_opacity', 00:42:35 INFO - 'tart', 00:42:35 INFO - 'tscrollx', 00:42:35 INFO - 'cart']}, 00:42:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 00:42:35 INFO - 'talos_options': ['--disable-e10s'], 00:42:35 INFO - 'tests': ['tp5o']}, 00:42:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 00:42:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 00:42:35 INFO - 'talos_options': ['--disable-e10s', 00:42:35 INFO - '--xperf_path', 00:42:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:42:35 INFO - 'tests': ['tp5n']}, 00:42:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 00:42:35 INFO - 'talos_options': ['--xperf_path', 00:42:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:42:35 INFO - 'tests': ['tp5n']}}, 00:42:35 INFO - 'talos.zip': {'path': '', 00:42:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:42:35 INFO - Running post-action listener: _resource_record_post_action 00:42:35 INFO - [mozharness: 2016-11-22 08:42:35.918377Z] Finished populate-webroot step (success) 00:42:35 INFO - [mozharness: 2016-11-22 08:42:35.918510Z] Running create-virtualenv step. 00:42:35 INFO - Running pre-action listener: _resource_record_pre_action 00:42:35 INFO - Running main action method: create_virtualenv 00:42:35 INFO - Creating virtualenv /builds/slave/test/build/venv 00:42:35 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 00:42:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:42:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:42:36 INFO - Using real prefix '/tools/python27' 00:42:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:42:36 INFO - Installing distribute.............................................................................................................................................................................................done. 00:42:39 INFO - Installing pip.................done. 00:42:39 INFO - Return code: 0 00:42:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 00:42:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 00:42:39 INFO - Reading from file tmpfile_stdout 00:42:39 INFO - Output received: 00:42:39 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) 00:42:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:42:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:42:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:42:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:39 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 0x1075923f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107508570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99584c51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a8d30>, '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 0x10720fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073a0b20>, '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.7zZTIhR3oc/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.8uh3y90RaR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 00:42:39 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 00:42:39 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 00:42:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7zZTIhR3oc/Render', 00:42:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:42:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:42:39 INFO - 'HOME': '/Users/cltbld', 00:42:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:42:39 INFO - 'LOGNAME': 'cltbld', 00:42:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:42:39 INFO - 'MOZ_NO_REMOTE': '1', 00:42:39 INFO - 'NO_EM_RESTART': '1', 00:42:39 INFO - 'PAGER': '/bin/cat', 00:42:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:42:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:42:39 INFO - 'PWD': '/builds/slave/test', 00:42:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:42:39 INFO - 'SHELL': '/bin/bash', 00:42:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8uh3y90RaR/Listeners', 00:42:39 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 00:42:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:42:39 INFO - 'USER': 'cltbld', 00:42:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:42:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:42:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:42:39 INFO - 'XPC_FLAGS': '0x0', 00:42:39 INFO - 'XPC_SERVICE_NAME': '0', 00:42:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:42:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:42:40 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 00:42:40 INFO - Cleaning up... 00:42:40 INFO - Return code: 0 00:42:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:42:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:42:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1075923f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107508570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99584c51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a8d30>, '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 0x10720fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073a0b20>, '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.7zZTIhR3oc/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.8uh3y90RaR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 00:42:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 00:42:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 00:42:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7zZTIhR3oc/Render', 00:42:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:42:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:42:40 INFO - 'HOME': '/Users/cltbld', 00:42:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:42:40 INFO - 'LOGNAME': 'cltbld', 00:42:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:42:40 INFO - 'MOZ_NO_REMOTE': '1', 00:42:40 INFO - 'NO_EM_RESTART': '1', 00:42:40 INFO - 'PAGER': '/bin/cat', 00:42:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:42:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:42:40 INFO - 'PWD': '/builds/slave/test', 00:42:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:42:40 INFO - 'SHELL': '/bin/bash', 00:42:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8uh3y90RaR/Listeners', 00:42:40 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 00:42:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:42:40 INFO - 'USER': 'cltbld', 00:42:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:42:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:42:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:42:40 INFO - 'XPC_FLAGS': '0x0', 00:42:40 INFO - 'XPC_SERVICE_NAME': '0', 00:42:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:42:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:42:40 INFO - Downloading/unpacking psutil>=3.1.1 00:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:42:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:42:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:42:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:42:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:42:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:42:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:42:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:42:43 INFO - Installing collected packages: psutil 00:42:43 INFO - Running setup.py install for psutil 00:42:43 INFO - building 'psutil._psutil_osx' extension 00:42:43 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 00:42:44 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 00:42:44 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 00:42:44 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 00:42:44 INFO - building 'psutil._psutil_posix' extension 00:42:44 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 00:42:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:42:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:42:44 INFO - ^ 00:42:44 INFO - 1 warning generated. 00:42:44 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 00:42:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:42:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:42:44 INFO - Successfully installed psutil 00:42:44 INFO - Cleaning up... 00:42:45 INFO - Return code: 0 00:42:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:42:45 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')]} 00:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:42:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:45 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')]} 00:42:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:42:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:45 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 0x1075923f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107508570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99584c51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a8d30>, '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 0x10720fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073a0b20>, '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.7zZTIhR3oc/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.8uh3y90RaR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 00:42:45 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 00:42:45 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 00:42:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7zZTIhR3oc/Render', 00:42:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:42:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:42:45 INFO - 'HOME': '/Users/cltbld', 00:42:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:42:45 INFO - 'LOGNAME': 'cltbld', 00:42:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:42:45 INFO - 'MOZ_NO_REMOTE': '1', 00:42:45 INFO - 'NO_EM_RESTART': '1', 00:42:45 INFO - 'PAGER': '/bin/cat', 00:42:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:42:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:42:45 INFO - 'PWD': '/builds/slave/test', 00:42:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:42:45 INFO - 'SHELL': '/bin/bash', 00:42:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8uh3y90RaR/Listeners', 00:42:45 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 00:42:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:42:45 INFO - 'USER': 'cltbld', 00:42:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:42:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:42:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:42:45 INFO - 'XPC_FLAGS': '0x0', 00:42:45 INFO - 'XPC_SERVICE_NAME': '0', 00:42:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:42:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:42:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:42:45 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 00:42:45 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 00:42:45 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 00:42:45 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 00:42:45 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 00:42:45 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 00:42:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:42:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:42:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:42:47 INFO - Installing collected packages: mozsystemmonitor 00:42:47 INFO - Running setup.py install for mozsystemmonitor 00:42:48 INFO - Successfully installed mozsystemmonitor 00:42:48 INFO - Cleaning up... 00:42:48 INFO - Return code: 0 00:42:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:42:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:42:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:42:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:48 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 0x1075923f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107508570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99584c51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a8d30>, '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 0x10720fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073a0b20>, '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.7zZTIhR3oc/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.8uh3y90RaR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 00:42:48 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 00:42:48 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 00:42:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7zZTIhR3oc/Render', 00:42:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:42:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:42:48 INFO - 'HOME': '/Users/cltbld', 00:42:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:42:48 INFO - 'LOGNAME': 'cltbld', 00:42:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:42:48 INFO - 'MOZ_NO_REMOTE': '1', 00:42:48 INFO - 'NO_EM_RESTART': '1', 00:42:48 INFO - 'PAGER': '/bin/cat', 00:42:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:42:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:42:48 INFO - 'PWD': '/builds/slave/test', 00:42:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:42:48 INFO - 'SHELL': '/bin/bash', 00:42:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8uh3y90RaR/Listeners', 00:42:48 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 00:42:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:42:48 INFO - 'USER': 'cltbld', 00:42:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:42:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:42:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:42:48 INFO - 'XPC_FLAGS': '0x0', 00:42:48 INFO - 'XPC_SERVICE_NAME': '0', 00:42:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:42:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:42:48 INFO - Downloading/unpacking jsonschema==2.5.1 00:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:42:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:42:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:42:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:42:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:42:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:42:50 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 00:42:50 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 00:42:50 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 00:42:50 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 00:42:50 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 00:42:50 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 00:42:51 INFO - Downloading functools32-3.2.3-2.tar.gz 00:42:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:42:51 INFO - warning: no files found matching '*.txt' 00:42:51 INFO - no previously-included directories found matching 'build' 00:42:51 INFO - no previously-included directories found matching 'dist' 00:42:51 INFO - no previously-included directories found matching '.git*' 00:42:51 INFO - Installing collected packages: jsonschema, functools32 00:42:51 INFO - Running setup.py install for functools32 00:42:51 INFO - warning: no files found matching '*.txt' 00:42:51 INFO - no previously-included directories found matching 'build' 00:42:51 INFO - no previously-included directories found matching 'dist' 00:42:51 INFO - no previously-included directories found matching '.git*' 00:42:51 INFO - Successfully installed jsonschema functools32 00:42:51 INFO - Cleaning up... 00:42:51 INFO - Return code: 0 00:42:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:42:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:42:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1075923f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107508570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99584c51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a8d30>, '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 0x10720fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073a0b20>, '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.7zZTIhR3oc/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.8uh3y90RaR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 00:42:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 00:42:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 00:42:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7zZTIhR3oc/Render', 00:42:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:42:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:42:51 INFO - 'HOME': '/Users/cltbld', 00:42:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:42:51 INFO - 'LOGNAME': 'cltbld', 00:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:42:51 INFO - 'MOZ_NO_REMOTE': '1', 00:42:51 INFO - 'NO_EM_RESTART': '1', 00:42:51 INFO - 'PAGER': '/bin/cat', 00:42:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:42:51 INFO - 'PWD': '/builds/slave/test', 00:42:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:42:51 INFO - 'SHELL': '/bin/bash', 00:42:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8uh3y90RaR/Listeners', 00:42:51 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 00:42:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:42:51 INFO - 'USER': 'cltbld', 00:42:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:42:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:42:51 INFO - 'XPC_FLAGS': '0x0', 00:42:51 INFO - 'XPC_SERVICE_NAME': '0', 00:42:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:42:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:42:51 INFO - Cleaning up... 00:42:51 INFO - Return code: 0 00:42:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:42:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:42:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1075923f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107508570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99584c51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a8d30>, '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 0x10720fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073a0b20>, '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.7zZTIhR3oc/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.8uh3y90RaR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 00:42:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 00:42:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 00:42:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7zZTIhR3oc/Render', 00:42:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:42:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:42:51 INFO - 'HOME': '/Users/cltbld', 00:42:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:42:51 INFO - 'LOGNAME': 'cltbld', 00:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:42:51 INFO - 'MOZ_NO_REMOTE': '1', 00:42:51 INFO - 'NO_EM_RESTART': '1', 00:42:51 INFO - 'PAGER': '/bin/cat', 00:42:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:42:51 INFO - 'PWD': '/builds/slave/test', 00:42:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:42:51 INFO - 'SHELL': '/bin/bash', 00:42:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8uh3y90RaR/Listeners', 00:42:51 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 00:42:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:42:51 INFO - 'USER': 'cltbld', 00:42:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:42:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:42:51 INFO - 'XPC_FLAGS': '0x0', 00:42:51 INFO - 'XPC_SERVICE_NAME': '0', 00:42:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:42:52 INFO - Downloading/unpacking blobuploader==1.2.4 00:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:42:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:42:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:42:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:42:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:42:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:42:54 INFO - Downloading blobuploader-1.2.4.tar.gz 00:42:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:42:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:42:54 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 00:42:54 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 00:42:54 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 00:42:54 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 00:42:54 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 00:42:54 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 00:42:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:42:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:42:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:42:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:42:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:42:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:42:55 INFO - Downloading docopt-0.6.1.tar.gz 00:42:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:42:56 INFO - Installing collected packages: blobuploader, requests, docopt 00:42:56 INFO - Running setup.py install for blobuploader 00:42:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:42:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:42:56 INFO - Running setup.py install for requests 00:42:56 INFO - Running setup.py install for docopt 00:42:56 INFO - Successfully installed blobuploader requests docopt 00:42:56 INFO - Cleaning up... 00:42:56 INFO - Return code: 0 00:42:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:42:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:42:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:42:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:42:56 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 0x1075923f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107508570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99584c51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a8d30>, '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 0x10720fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073a0b20>, '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.7zZTIhR3oc/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.8uh3y90RaR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 00:42:56 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 00:42:56 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 00:42:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7zZTIhR3oc/Render', 00:42:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:42:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:42:56 INFO - 'HOME': '/Users/cltbld', 00:42:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:42:56 INFO - 'LOGNAME': 'cltbld', 00:42:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:42:56 INFO - 'MOZ_NO_REMOTE': '1', 00:42:56 INFO - 'NO_EM_RESTART': '1', 00:42:56 INFO - 'PAGER': '/bin/cat', 00:42:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:42:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:42:56 INFO - 'PWD': '/builds/slave/test', 00:42:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:42:56 INFO - 'SHELL': '/bin/bash', 00:42:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8uh3y90RaR/Listeners', 00:42:56 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 00:42:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:42:56 INFO - 'USER': 'cltbld', 00:42:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:42:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:42:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:42:56 INFO - 'XPC_FLAGS': '0x0', 00:42:56 INFO - 'XPC_SERVICE_NAME': '0', 00:42:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:42:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:42:57 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-b2uJuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:42:57 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-VGokid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:42:57 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-4IKqoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:42:57 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-0eY2__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:42:57 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-mMn1hh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:42:57 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-kKoo9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:42:57 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-sXv_A5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:42:57 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-jTUG8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:42:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Em3b0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:42:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-rUMKOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:42:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-kwCTPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:42:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-UBP9LR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:42:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-7GpmFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:42:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-GUai7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:42:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-tLdTQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:42:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-tNzw3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:42:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:42:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-uBRX5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:42:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:42:58 INFO - Running setup.py install for manifestparser 00:42:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:42:59 INFO - Running setup.py install for mozcrash 00:42:59 INFO - Running setup.py install for mozdebug 00:42:59 INFO - Running setup.py install for mozdevice 00:42:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:42:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:42:59 INFO - Running setup.py install for mozfile 00:42:59 INFO - Running setup.py install for mozhttpd 00:42:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:42:59 INFO - Running setup.py install for mozinfo 00:42:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:42:59 INFO - Running setup.py install for mozInstall 00:43:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:43:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:43:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:43:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:43:00 INFO - Running setup.py install for mozleak 00:43:00 INFO - Running setup.py install for mozlog 00:43:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:43:00 INFO - Running setup.py install for moznetwork 00:43:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:43:00 INFO - Running setup.py install for mozprocess 00:43:00 INFO - Running setup.py install for mozprofile 00:43:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:43:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:43:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:43:00 INFO - Running setup.py install for mozrunner 00:43:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:43:01 INFO - Running setup.py install for mozscreenshot 00:43:01 INFO - Running setup.py install for moztest 00:43:01 INFO - Running setup.py install for mozversion 00:43:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:43:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:43:01 INFO - Cleaning up... 00:43:01 INFO - Return code: 0 00:43:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:43:01 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')]} 00:43:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:43:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:43:01 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')]} 00:43:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:43:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:43:01 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 0x1075923f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107508570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99584c51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a8d30>, '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 0x10720fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073a0b20>, '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.7zZTIhR3oc/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.8uh3y90RaR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 00:43:01 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 00:43:01 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 00:43:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7zZTIhR3oc/Render', 00:43:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:43:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:43:01 INFO - 'HOME': '/Users/cltbld', 00:43:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:43:01 INFO - 'LOGNAME': 'cltbld', 00:43:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:43:01 INFO - 'MOZ_NO_REMOTE': '1', 00:43:01 INFO - 'NO_EM_RESTART': '1', 00:43:01 INFO - 'PAGER': '/bin/cat', 00:43:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:43:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:43:01 INFO - 'PWD': '/builds/slave/test', 00:43:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:43:01 INFO - 'SHELL': '/bin/bash', 00:43:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8uh3y90RaR/Listeners', 00:43:01 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 00:43:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:43:01 INFO - 'USER': 'cltbld', 00:43:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:43:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:43:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:43:01 INFO - 'XPC_FLAGS': '0x0', 00:43:01 INFO - 'XPC_SERVICE_NAME': '0', 00:43:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:43:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:43:01 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-XmPlyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:43:01 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)) 00:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:43:01 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-9lnCj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:43:02 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)) 00:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:43:02 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-NwDg5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:43:02 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)) 00:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:43:02 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-wQ9bMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:43:02 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)) 00:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:43:02 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-G3vh23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:43:02 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)) 00:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:43:02 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-r9r3P1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:43:02 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)) 00:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:43:02 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-I78GGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:43:02 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)) 00:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:43:02 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-lDcFxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:43:02 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)) 00:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:43:02 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Slr1bv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:43:02 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)) 00:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:43:02 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-JJ43Vd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:43:02 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)) 00:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:43:02 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-HX4D2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:43:02 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)) 00:43:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:43:03 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-0dNo3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:43:03 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)) 00:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:43:03 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-YGZBQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:43:03 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)) 00:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:43:03 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Y0pfhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:43:03 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)) 00:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:43:03 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-3fq63A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:43:03 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)) 00:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:43:03 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-AyfMyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:43:03 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)) 00:43:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:43:03 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-60N296-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:43:03 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)) 00:43:03 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)) 00:43:03 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)) 00:43:03 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)) 00:43:03 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)) 00:43:03 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)) 00:43:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:43:03 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 00:43:03 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 00:43:03 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 00:43:03 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 00:43:03 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 00:43:03 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 00:43:06 INFO - Downloading blessings-1.6.tar.gz 00:43:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:43:06 INFO - Installing collected packages: blessings 00:43:06 INFO - Running setup.py install for blessings 00:43:06 INFO - Successfully installed blessings 00:43:06 INFO - Cleaning up... 00:43:06 INFO - Return code: 0 00:43:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:43:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:43:07 INFO - Reading from file tmpfile_stdout 00:43:07 INFO - Current package versions: 00:43:07 INFO - blessings == 1.6 00:43:07 INFO - blobuploader == 1.2.4 00:43:07 INFO - docopt == 0.6.1 00:43:07 INFO - functools32 == 3.2.3-2 00:43:07 INFO - jsonschema == 2.5.1 00:43:07 INFO - manifestparser == 1.1 00:43:07 INFO - mozInstall == 1.12 00:43:07 INFO - mozcrash == 1.0 00:43:07 INFO - mozdebug == 0.1 00:43:07 INFO - mozdevice == 0.49 00:43:07 INFO - mozfile == 1.2 00:43:07 INFO - mozhttpd == 0.7 00:43:07 INFO - mozinfo == 0.9 00:43:07 INFO - mozleak == 0.1 00:43:07 INFO - mozlog == 3.4 00:43:07 INFO - moznetwork == 0.27 00:43:07 INFO - mozprocess == 0.23 00:43:07 INFO - mozprofile == 0.28 00:43:07 INFO - mozrunner == 6.13 00:43:07 INFO - mozscreenshot == 0.1 00:43:07 INFO - mozsystemmonitor == 0.3 00:43:07 INFO - moztest == 0.7 00:43:07 INFO - mozversion == 1.4 00:43:07 INFO - psutil == 3.1.1 00:43:07 INFO - requests == 1.2.3 00:43:07 INFO - wsgiref == 0.1.2 00:43:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:43:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:43:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:43:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:43:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:43:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:43:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:43:07 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 0x1075923f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107508570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99584c51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a8d30>, '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 0x10720fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073a0b20>, '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.7zZTIhR3oc/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.8uh3y90RaR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 00:43:07 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 00:43:07 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 00:43:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7zZTIhR3oc/Render', 00:43:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:43:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:43:07 INFO - 'HOME': '/Users/cltbld', 00:43:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:43:07 INFO - 'LOGNAME': 'cltbld', 00:43:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:43:07 INFO - 'MOZ_NO_REMOTE': '1', 00:43:07 INFO - 'NO_EM_RESTART': '1', 00:43:07 INFO - 'PAGER': '/bin/cat', 00:43:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:43:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:43:07 INFO - 'PWD': '/builds/slave/test', 00:43:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:43:07 INFO - 'SHELL': '/bin/bash', 00:43:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8uh3y90RaR/Listeners', 00:43:07 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 00:43:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:43:07 INFO - 'USER': 'cltbld', 00:43:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:43:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:43:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:43:07 INFO - 'XPC_FLAGS': '0x0', 00:43:07 INFO - 'XPC_SERVICE_NAME': '0', 00:43:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:43:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:43:07 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)) 00:43:07 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)) 00:43:07 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)) 00:43:07 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)) 00:43:07 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)) 00:43:07 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)) 00:43:07 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)) 00:43:07 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)) 00:43:07 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)) 00:43:07 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)) 00:43:07 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)) 00:43:07 INFO - Cleaning up... 00:43:07 INFO - Return code: 0 00:43:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:43:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:43:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:43:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:43:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:43:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:43:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:43:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1075923f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107508570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99584c51d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073a8d30>, '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 0x10720fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073a0b20>, '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.7zZTIhR3oc/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.8uh3y90RaR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 00:43:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 00:43:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 00:43:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7zZTIhR3oc/Render', 00:43:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:43:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:43:07 INFO - 'HOME': '/Users/cltbld', 00:43:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:43:07 INFO - 'LOGNAME': 'cltbld', 00:43:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:43:07 INFO - 'MOZ_NO_REMOTE': '1', 00:43:07 INFO - 'NO_EM_RESTART': '1', 00:43:07 INFO - 'PAGER': '/bin/cat', 00:43:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:43:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:43:07 INFO - 'PWD': '/builds/slave/test', 00:43:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:43:07 INFO - 'SHELL': '/bin/bash', 00:43:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8uh3y90RaR/Listeners', 00:43:07 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 00:43:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:43:07 INFO - 'USER': 'cltbld', 00:43:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:43:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:43:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:43:07 INFO - 'XPC_FLAGS': '0x0', 00:43:07 INFO - 'XPC_SERVICE_NAME': '0', 00:43:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:43:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:43:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:43:08 INFO - Cleaning up... 00:43:08 INFO - Return code: 0 00:43:08 INFO - Running post-action listener: _resource_record_post_action 00:43:08 INFO - Running post-action listener: _start_resource_monitoring 00:43:08 INFO - Starting resource monitoring. 00:43:08 INFO - [mozharness: 2016-11-22 08:43:08.153155Z] Finished create-virtualenv step (success) 00:43:08 INFO - [mozharness: 2016-11-22 08:43:08.154141Z] Running install step. 00:43:08 INFO - Running pre-action listener: _resource_record_pre_action 00:43:08 INFO - Running main action method: install 00:43:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:43:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:43:08 INFO - Reading from file tmpfile_stdout 00:43:08 INFO - Detecting whether we're running mozinstall >=1.0... 00:43:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:43:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:43:08 INFO - Reading from file tmpfile_stdout 00:43:08 INFO - Output received: 00:43:08 INFO - Usage: mozinstall [options] installer 00:43:08 INFO - Options: 00:43:08 INFO - -h, --help show this help message and exit 00:43:08 INFO - -d DEST, --destination=DEST 00:43:08 INFO - Directory to install application into. [default: 00:43:08 INFO - "/builds/slave/test"] 00:43:08 INFO - --app=APP Application being installed. [default: firefox] 00:43:08 INFO - mkdir: /builds/slave/test/build/application 00:43:08 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'] 00:43:08 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 00:43:41 INFO - Reading from file tmpfile_stdout 00:43:41 INFO - Output received: 00:43:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 00:43:41 INFO - Running post-action listener: _resource_record_post_action 00:43:41 INFO - [mozharness: 2016-11-22 08:43:41.277746Z] Finished install step (success) 00:43:41 INFO - [mozharness: 2016-11-22 08:43:41.277908Z] Running run-tests step. 00:43:41 INFO - Running pre-action listener: _resource_record_pre_action 00:43:41 INFO - Running main action method: run_tests 00:43:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:43:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:43:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:43:41 INFO - 2016-11-22 00:43:41.292 screenresolution[1627:7795] starting screenresolution argv=screenresolution get 00:43:41 INFO - 2016-11-22 00:43:41.294 screenresolution[1627:7795] Display 0: 1600x1200x32@60 00:43:41 INFO - 2016-11-22 00:43:41.300 screenresolution[1628:7797] starting screenresolution argv=screenresolution list 00:43:41 INFO - Available Modes on Display 0 00:43:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:43:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:43:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:43:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:43:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:43:41 INFO - Intel Iris: 00:43:41 INFO - Chipset Model: Intel Iris 00:43:41 INFO - Type: GPU 00:43:41 INFO - Bus: Built-In 00:43:41 INFO - VRAM (Dynamic, Max): 1536 MB 00:43:41 INFO - Vendor: Intel (0x8086) 00:43:41 INFO - Device ID: 0x0a2e 00:43:41 INFO - Revision ID: 0x0009 00:43:41 INFO - Displays: 00:43:41 INFO - Display: 00:43:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:43:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:43:41 INFO - Display Serial Number: 1600x1200 60 00:43:41 INFO - Main Display: Yes 00:43:41 INFO - Mirror: Off 00:43:41 INFO - Online: Yes 00:43:41 INFO - Rotation: Supported 00:43:41 INFO - Return code: 0 00:43:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:43:41 INFO - Python 2.7.3 00:43:41 INFO - Return code: 0 00:43:41 INFO - grabbing minidump binary from tooltool 00:43:41 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')]} 00:43:41 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 0x1073a8d30>, '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 0x10720fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073a0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:43:41 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 00:43:41 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 00:43:41 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 00:43:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:43:41 INFO - Return code: 0 00:43:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:43:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:43:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:43:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:43:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:43:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--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 00:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0332 --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 00:43:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7zZTIhR3oc/Render', 00:43:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:43:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:43:41 INFO - 'HOME': '/Users/cltbld', 00:43:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:43:41 INFO - 'LOGNAME': 'cltbld', 00:43:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:43:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:43:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:43:41 INFO - 'MOZ_NO_REMOTE': '1', 00:43:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:43:41 INFO - 'NO_EM_RESTART': '1', 00:43:41 INFO - 'PAGER': '/bin/cat', 00:43:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:43:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:43:41 INFO - 'PWD': '/builds/slave/test', 00:43:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:43:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:43:41 INFO - 'SHELL': '/bin/bash', 00:43:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8uh3y90RaR/Listeners', 00:43:41 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 00:43:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:43:41 INFO - 'USER': 'cltbld', 00:43:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:43:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:43:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:43:41 INFO - 'XPC_FLAGS': '0x0', 00:43:41 INFO - 'XPC_SERVICE_NAME': '0', 00:43:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:43:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--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 00:43:41 INFO - mozversion application_buildid: 20161121214150 00:43:41 INFO - mozversion application_changeset: 805d42464705b0c6d7db1101c1550dc4855b4329 00:43:41 INFO - mozversion application_display_name: Nightly 00:43:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:43:41 INFO - mozversion application_name: Firefox 00:43:41 INFO - mozversion application_remotingname: firefox 00:43:41 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 00:43:41 INFO - mozversion application_vendor: Mozilla 00:43:41 INFO - mozversion application_version: 53.0a1 00:43:41 INFO - mozversion platform_buildid: 20161121214150 00:43:41 INFO - mozversion platform_changeset: 805d42464705b0c6d7db1101c1550dc4855b4329 00:43:41 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 00:43:41 INFO - mozversion platform_version: 53.0a1 00:43:41 INFO - using testdate: 1479804221 00:43:41 INFO - actual date: 1479804221 00:43:41 INFO - starting webserver on 'localhost:49251' 00:43:41 INFO - SUITE-START | Running 2 tests 00:43:41 INFO - TEST-START | dromaeo_css 00:43:41 INFO - operating with platform_type : mac_ 00:43:41 INFO - Initialising browser for dromaeo_css test... 00:43:41 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpQoZFde/profile http://localhost:49251/getInfo.html) 00:43:44 INFO - PROCESS | 1638 | 2016-11-22 00:43:44.897 plugin-container[1640:7967] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e37, name = 'com.apple.tsm.portname' 00:43:44 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:43:44 INFO - PROCESS | 1638 | 2016-11-22 00:43:44.899 plugin-container[1640:7967] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 00:43:44 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:43:44 INFO - PROCESS | 1638 | 2016-11-22 00:43:44.899 plugin-container[1640:7967] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:43:44 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 00:43:44 INFO - PROCESS | 1638 | colorDepth:24 00:43:44 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 00:43:44 INFO - PROCESS | 1638 | __metrics 00:43:45 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 00:43:45 INFO - TEST-INFO | 1638: exit 0 00:43:45 INFO - Browser initialized. 00:43:45 INFO - Running cycle 1/1 for dromaeo_css test... 00:43:45 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpQoZFde/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:43:48 INFO - PROCESS | 1641 | 2016-11-22 00:43:48.423 plugin-container[1642:8087] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8933, name = 'com.apple.tsm.portname' 00:43:48 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:43:48 INFO - PROCESS | 1641 | 2016-11-22 00:43:48.424 plugin-container[1642:8087] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 00:43:48 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:43:48 INFO - PROCESS | 1641 | 2016-11-22 00:43:48.424 plugin-container[1642:8087] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:43:58 INFO - PROCESS | 1641 | RSS: Main: 243417088 00:43:58 INFO - PROCESS | 1641 | 00:44:47 INFO - PROCESS | 1641 | 1479804287733 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] 00:47:01 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 00:47:03 INFO - PROCESS | 1641 | RSS: Main: 248868864 00:47:03 INFO - PROCESS | 1641 | 00:50:05 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 00:50:06 INFO - PROCESS | 1641 | RSS: Main: 259969024 00:50:06 INFO - PROCESS | 1641 | 00:53:08 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 00:53:09 INFO - PROCESS | 1641 | RSS: Main: 264323072 00:53:09 INFO - PROCESS | 1641 | 00:56:11 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 00:56:11 INFO - PROCESS | 1641 | RSS: Main: 263221248 00:56:11 INFO - PROCESS | 1641 | 00:59:14 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 00:59:15 INFO - PROCESS | 1641 | RSS: Main: 268152832 00:59:15 INFO - PROCESS | 1641 | 01:02:18 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 01:02:18 INFO - PROCESS | 1641 | RSS: Main: 271642624 01:02:18 INFO - PROCESS | 1641 | 01:02:18 INFO - PROCESS | 1641 | __start_tp_report 01:02:18 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 01:02:18 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 01:02:18 INFO - PROCESS | 1641 | |i|pagename|runs| 01:02:18 INFO - PROCESS | 1641 | |0;dojo.html;2862.756665683417;3192.8563214655337;3411.5564976553046;3417.67532084452;3436.037909385373;5519.503244707986;5633.718314084294;5646.124850648146;5692.430756924306;5711.600187986843;4782.880427989302;5559.054960656688;5629.521490673287;5779.450952159548;5781.075027995522;5089.618278629099;5144.09978253806;5225.761862379978;5268.789248430058;5291.444398338158;5235.397929238129;5288.471152884719;5297.788088476456;5307.761150748206;5358.839234822942;5087.542121209109;5173.586113110942;5212.348456442945;5222.791088356461;5253.60597955153;5198;5204.895902081975;5208.869778255574;5212.270282160501;5213;5135.8202462914;5140.228965655144;5146.099432599318;5188.766505507261;5194.065068287707;5458.61789674723;5555.749991250363;5581.05122129239;5582.106862901956;5653.208550802891;6121.571489995702;6136.182108626207;6140.841692977875;6170.413810687977;6174.197354343906;6222.875542489171;6225.595336303171;6226.066090086478;6230.127782110508;6261.749530018794;5848.64908105515;5850.882982340372;5851.941480585225;5877.853053673693;5886.470217680387;6142.682951206299;6143.358355906914;6160.507159427235;6191.226096737908;6213.875722485525;4897.755112244374;6054.757809687563;6059.969805133138;6072.149899014142;6088.634681919099;6051.0923361496125;6074.088886667036;6173.84565385869;6207.193064901534;6217.533684973645;6029.125776762345;6066.4540191382985;6094.817155485254;6108.144859719643;6111.816645500642;6121.724522396503;6126.792505495304;6130.816075517741;6138.785142519991;6142.539309551801;4844.273358996108;4855.854324370274;4862.513748625109;4865.659403841697;4872.926906096412;4371.846985355497;4376.584224498668;4402.273624851928;4417.293233082692;4423.60187583119;5558.249636299048;5718.170865224519;5738.569607279471;5755.913661295084;5846.269216347957;5431.755570999236;5729.455701708414;6231.906521402183;6246.843828904223;6263.311035786151;5850.531957443394;5851.912221316684;5856.912146317809;5866.442687944638;5868.119782032644;5296.179092240632;5356;5366.731663416814;5373.543248823894;5393.568514518829;5650.745716442852;5904.760000399879;6153.538484613582;6216.689165541705;6296.33888441707;6233;6237.500999919905;6246.968765156327;6252.5935814171935;6257.874842503215;6237.563370564017;6262.780802671885;6271;6273.62358258506;6303.590266632654;5188.766505507337;5231.529162375405;5397.406285308534;6078.088286757022;6093.238345206849;5040.243963405518;5083.771230294572;5621.297337832771;5723.456271654186;6219.378062193925;4901.215805471107;6034.974054410673;6106.551676171802;6132.63204207749;6153.261608606996;5089.898202036013;6109.541784366279;6117.755289788537;6135.53983451234;6139.171211886338;4928.334674818853;5787.479126878601;5837.67892765902;5884.705764711662;5989.131575921554;4985.651004429655;6062.6665533394325;6082.513398928164;6089;6119.082137679384;5953.077273022688;5981.790637327673;5988.28140623128;5990.371011043778;6002.249718785152 01:02:18 INFO - PROCESS | 1641 | |1;ext.html;484.44772958826934;491.01061361356864;531.2721571447119;553.5543887170828;570.3526497425426;36531.817340913294;38869.80565097174;38928;39016.21967560647;39058;39804;40383.19233615326;40408.59591404085;40442;40531.18937621246;9185.49479778612;9335;9377.718668439958;9414.52927353633;9424.481653509054;9229.400088994194;9231.399959002676;9303.488308143049;9312.534373281344;9322.62709491623;9230.953845230799;9247.982721900586;9295.860562091573;9333.160015598594;9345.158935695787;9272.953635231814;9285.953570232174;9304.8604270936;9321.347505674537;9343.626254949793;9247.49138797366;9247.953760231223;9320.30097742672;9354.204892584139;9360.90639093614;38206;38239.808800956096;38396;38562.80718596417;38864.63973760933;3340.1148695595443;3344.916377090568;3357.143928298293;3486.337595856779;3492.9870337602065;29657.851710741525;30004.8499757502;30224.24439389011;30690.38619227625;30691.539626905615;3178.8410579471165;3183.220111072802;3232.2565809863627;3248.8213148276836;3351.765376423651;459112;463012;468548;469532;470787;53151.734241328544;53362.46637533575;53368;53390;53483.93032139335;33377.3324533508;34726;34761.65238347584;35794.64205357965;60138;15674.216289185495;15898.774171615984;16099.436519721989;16151.919240403722;16160;12191.390430478617;12235.816462753066;12305.56930507428;12345.444454999546;12357.691057723629;18860.905695471436;18885.43343699691;18965.862048277148;18966.241350346107;19040.143193556323;6656.73373065072;7799.596072706967;7919.5248285101925;7929.5242285463055;7999.480033797785;772.3203580848817;931.1852129386787;976.770458942148;979.7011911366959;1036.891126431729;28817;29209.977650782545;29710;29901.551476727866;30002.549961750592;1000.5747557288123;1002.9799404012025;1049.9107575855985;1054.2778196935124;1115.654147214366;14765.926170369079;15293.694126117416;15331.156786376856;15533.84466155324;15632.514911083656;652907;666735;667654;667910;667977;56166.438335616935;56169.71915140398;56609.71695141498;56688.86622267614;56927.43072569304;1310.1461432073509;1350.783874580082;1371.5199680111882;1371.7530844448095;1379.8482166961626;28498.857505713167;29232.83068677229;29287.560686590143;29748.702512974593;29800.55299170471;44488.777556112014;44623.77688111539;44631.776841115585;49645;49772.25341619806;526712;530248;534714;535961;537287;25824;25848.612270815578;26189.345266368495;26225.4754904897;26249;44807.65577032694;45055.32417013682;45124.87187820331;45803.77098114488;46144.307835383166;36119.916402507966;37939.05152371213;38025.239495210495;38898.0275493115;38980.6101938977;37237;37405.812970934974;37432.06419839417;38285;38468.6153138465;31633.367332653677;32069.87755428549;34799.65200347964;37002.814985924895;37529.62470375261;27607.03375381852;32446.864359747295;34085;34124.82937585296;34318.48522272218;23536.88231558831;24968.625470617593;25424.618630720182;25546.23361299164;26171.345716357242 01:02:18 INFO - PROCESS | 1641 | |2;jquery.html;292.85210968460933;298.3853262279704;320.95185722141673;355.1122194513716;390.89836642472966;17099.999999999985;18657.34699285525;19350.612987740238;19519.316823911166;19974.400767976967;18830.52923676909;19400.417987460365;19922;20298.39104826854;20344.389668309937;4667.276572131325;5777.133429985493;5962.04607262838;6073.7874174403905;6090.786822461215;5012.3483947086825;5752.7986520471795;5886.587938844283;5951.196588460567;5987.221661184061;5367.597430192732;5691.630044047144;5883.735231914553;5905.99196273267;5945.464908158265;5647.463490968352;5813.476787089182;5860.355360910296;5867.589268751189;5955.106733989893;5591.692456914868;5657.349404818417;5677.120046392815;5680.233168522238;5688.061469857468;23638.215927444402;23707.40731481727;24249.272521824372;24467.510649787084;24637.384065398328;2060.7939206079423;2220.123051394706;2253.3915842722536;2259.8757068361233;2642.2073377986526;9441.244700424018;9777.217822574177;9807.31348805584;10132.290739648228;10342.948285258526;1854.944351669452;1862.5157459060606;1869.3924474545827;1890.4706682129026;1892.6877065284211;123824;124451.75548244492;127276.99999999907;127621;128806.3559682205;11173.944130279458;11548.538058477734;11563;11597.884021159849;11988.820167697491;14387.712245755027;14396.496122635657;14418;14432.855671443362;14433.917456190828;8127.878081828777;8367.07962124178;8374.330053595697;8548.700795472128;8555.700550480704;6847.554908930903;8289.544075075928;8297.83404331922;8323.417360784808;8460.280876125475;3747.5315585551807;4369.80335884886;4468.374427580141;4598.770061496979;4672.976635116803;1815.709486482183;1870.1116969439408;1929.7745931333423;1947.4157752674425;1967.6261510312995;801.8195905921121;861.9742506417343;909.8134882349102;930.5765876526165;991.613270824379;15544.834137439737;19258.90370548138;20993.58012839765;21025;21075.94620269011;835.1898658301438;855.8758979947872;922.3128769066954;934.4673536084366;935.8629265442383;10287.74280642975;10823.891761082446;10831.400463888767;10859.262517997082;10873.945630271955;566810;566873;569971.1501442466;571648;576884;125528;128181;128878;132748;133291.66708332792;945.5177859291673;981.259149342242;995.85560093786;996.3324572536418;1009.1018993096151;16342.346306147621;16736.56900335404;17445.389411370543;18038.278468861623;18485.075746212904;37775.81112094422;52458;52500.9499809994;53260;55134.172987406026;59588;77068.22931770611;77688;78320.60839695766;79948.80076798968;6412.775552855627;7760.961195193988;7789.122071001917;7935.325497332791;8116.675332986614;9042.367034307536;11839.704007399883;12474.18917770342;12732.554360597334;12778.424970876147;9598.66404675861;9926.759155105612;10529.420881851265;10880.183986200907;11136.220464567403;10702.304350217211;10871.67384978452;11000.504977276201;11133.610323638633;11153.553857845594;10051.899481005097;11107.333559986426;11177.161712871404;11400.486978086172;11567.88432115668;7761.146273909978;7922.366210703015;7927.286544211049;7947.006624171979;8008;7274.6726397313305;10599.417032063002;10815.405152716676;11057.004869561779;11066.170037247077 01:02:18 INFO - PROCESS | 1641 | |3;mootools.html;1450.0574626492778;1470.5808844479322;1614.9838501614981;1723.6638855423182;1744.694678431275;6752.189737231527;7864.567448790329;8181.263686268235;8432.36757243207;8652.783680407993;8116.472429292092;8432.83134337313;8509.744707658765;8544.316454683625;8547.743567692964;2300.470891694911;2307.3654744945106;2308.746037935834;2312.8843557822124;2314.525522267931;2338.1115176233006;2368.0882860098814;2389.4026493376655;2397.077125306752;2415.323709361373;2325.9185928492507;2328.382978510696;2352.141468364052;2353.6940197774265;2356.257778799681;2298.2071185440996;2304.746477887431;2325.2094287942095;2325.837191396586;2334.124703236278;1920.5678722287514;1940.7962163972861;2328.8020518255967;2355.316958082154;2362.598358279097;8338.166183381674;8443.240108390213;8451.197136272045;8495.447795893248;8583.356248281341;5126.256692779563;5294.073537130987;5297.682139071668;5326.414094449608;5377.220303056035;3094.5977022987095;3337.3158502506926;3395.9320813583836;3632.2735452909524;3681.1349332906743;1046.0480962324325;1052.6578861869846;1073.613499140309;1091.4542728635681;1094.2340361746808;11160.107191424666;11161.218714689974;11224.66326010221;11241.775164496747;11278.154138439568;4409.801558929856;4626.676132670681;4656.301554766745;4715.434147902205;4715.622750180046;9070.047644997312;9166.633334666538;9170.495622740813;9172.174504294511;9206.953965230265;5126.6923984561045;5141.228815677603;5152.278680984665;5165.9225111623355;5168.8449346519665;4329.437173167468;4336.046069864625;4745.810167593258;4830.686005409637;4839.758012099381;3788.3559794834946;3789.981050094787;3882.4176373544196;3921.647051765298;3959.9604003960167;1258.3456602566614;1270.142653708743;1275.5344299330677;1282.4549566434227;1284.062634276983;708.4757279613049;773.5590713293482;776.9494982826099;858.3390789092489;890.7440508882445;5291.11623680713;5478.45215478457;5627.296587926509;5651.378348381675;5774.191613174159;744.985100297991;858.6050416808213;890.3633901760272;907.682311191091;939.3659279985982;3860.285847118292;3898.434726964574;3931.6461518463475;3994.1811928554575;4020.798960052044;9900.455474948803;10295.330803497749;10306.948465257627;10309.587616495257;10322.845157322647;10198;10252.974702529837;10253.84619230712;10289.485525723683;10301.639442619622;7560.168381478033;7726;7909.406794490436;7909.7231596893835;8078.757637270891;5602.355729091044;5707.4577915098;5725.141228815711;5790.044642633919;5826.329972053268;9280.628774849201;9376.20302274287;9503.097205765442;9656.806863862825;9729.124378805658;7445.4043676506835;7517.511361761468;8204.753857384288;9594.520273986413;9624.75938101553;5738.856528586652;6708.966455167693;6756.594604323756;7098.964505177441;7317.926820731725;7941.523508589272;8088.838223235384;8149.674013039649;8166.714165004434;8170.182981701782;6005.3694362091355;6073.726682299219;6229.781957631461;7126.073610430818;7221;6953.165620125617;6959.269276726073;7092.822679433056;7097.325754053357;7129.251428599922;6615.338466153346;6727.798166055026;6745.9662701686175;6754.3921047106;6872.312768723287;4830.758462076952;4856.417229932431;4871.439784424837;4895.951040489549;4989.351384320015;4834.468208497133;4862.90274194507;4900.338454308623;4991.575716064175;5019.397672279204 01:02:18 INFO - PROCESS | 1641 | |4;prototype.html;238.40993540986062;253.2592167908866;292.3786952725458;308.8403046560166;317.26236500137185;5133.255677926699;5288.603354748399;5354.571634269254;5434.592405569583;5445.754941027658;4255.9574404255945;5124.461931497186;5320.920186197211;5364.892702145955;5372.57019438445;3921.0590757574896;4108.917821643573;4119.794010299488;4124.278251306025;4142.171565686875;3243.869665772529;3597.585633621396;3984.5816189299994;4104.445899803533;4155.729877557964;4056.5334986476637;4065.9796701016453;4074.653654439376;4080.775557344345;4086.3257562502154;3085.9999999999886;3244.7832062976386;3506.160753974394;3985.7117877750825;4080.2047099276224;2825.8869645214168;3582.5342705448124;3600.3879340512176;3607.8376473058775;3628.3468975584383;4188.81150348235;4999.32509111269;5156.92264616031;5206.453322401131;5301.3373328333955;3860.4209368594657;4006.6994975376965;4463.910721785546;4673.836415725467;4694.248920172756;4592;4789.449213340476;5497.780088796443;5665.886682266373;5677.716114194273;3470.0914497026533;3586;3847.480590120354;3972.3644216925154;3982.8805135845682;4878.926456256112;5578.30271216098;5889.1755154278435;5919.467247947706;5963.36689038032;3432.8798492052656;3571.714262858965;3594.676479116892;3758.0229140423685;3897.2780994150517;3559.982200088983;3786.3941769316775;3956.7032472564674;3965.861194858224;3975.741576797499;4007.318755811519;4039.899002524902;4538.954610453853;4591.747453890068;4609.492955774862;5741.043362668229;5751.9424805751405;5764.74058667361;5772.826815195551;5777.913331300034;1053.5943661690305;1107.4462768615692;1245.5640525816073;1280.571008712092;1286.414681319997;856.6402111113347;876.8334016536837;878.9560521973875;880.300161371711;889.9421537600035;4603.95396046042;4604.04052514837;4990.950090499048;5082.941408729471;5088.414832294334;5306.708131052829;5627.718614069362;5629.324481062299;5659.43405659439;5676.4323567642905;4585.28928016158;4693.413323334583;4946.579540739005;5100.132977393852;5104.3874735031295;2449.963250551243;2511.0709037656184;2900.332308922378;2907.97092029077;2935.3688956874375;5217.921731174036;5395.003848576052;5504.91742623861;5778.5377169826315;5856.531477481792;5208.427073021964;5617.72482144732;5715.514181294637;5743.798967036218;5767.769289228384;4678.719276843468;5169.974150129226;5422.674639521641;5630.791517845976;5835.066389377679;4338.58783415575;4811.735354555533;5455.399906010256;5478.890422191614;5874.295084589877;4977.8257760978195;5643.576731745054;5874.4139082448355;5881.206037185097;5902.2622172228475;4938.8765280868265;5162.793488260428;5610.990021795953;6222.879881621352;6239.719212635533;3828.2343531293295;3901.308097412776;3901.9904749054826;3914.256291304643;3922.6077392260545;5109.514596113363;5204.74703310431;5561.943230786138;5940.673262970578;5946.583739138218;5445;5615.691136987505;5728.54171666274;5730.885382292247;5770.8845823082465;4757.52516719818;5569.275994120739;5657.264555607745;5672.602917795714;5716.314042314949;4422.46930368345;4552.704074235164;4702.294655801657;4770.666053376231;5568.552176434076;4931.3589233399425;4962.975185124052;5134.919048570828;5685.573581981451;5714.285714285715;4618.4367675940175;5406.9723853554915;5604.579656525859;5635.802746903839;5691.658500489983 01:02:18 INFO - PROCESS | 1641 | |5;yui.html;603.6770327874586;638.75088715401;694.4513834071084;708.3695510995216;798.5168972771471;14101.365438555264;14288.71422571548;14818.40726370944;14880.181590012571;14906.403743850247;13164.27596482193;13454.932725336359;13460.192388456699;13514.256715880623;13575.389107490162;652.3606865272036;674.1842370731397;815.3803109636689;831.6382373667444;832.629479881453;653.1867824558432;685.8765422223997;698.3679769808306;707.5153519838926;709.6735501669239;853.769482239798;874.8250349930007;879.5030807593721;879.7272845417909;880.4761167105594;870.7344260000697;873.2620935309653;873.89076365454;874.5059041641452;877.8595424732044;647.355880898509;649.8732747114315;650.2976785072111;651.6318280171688;654.0744846042844;13564.525241616497;13864.792028119586;13923;14070.155790652594;14106.647333816634;13083.280419576919;13136.146150500283;13171;13365.398557064858;13529.32353382327;11202.663920082328;11220.214584979054;11412.315261084279;11416.086713062936;11669.533218671242;3304.107890869476;3537.451694987281;3574.3744844652083;3776.0371105367954;3782.375907975181;88621;89414.55292723495;89441.55279223627;89801;89992.20093588746;10114.54484548197;10199.163513244403;10293.176545876462;10307.433091179908;10548.314359566604;16988.150592470327;20159.99200039992;20228.19087236494;20239.08924098419;20271.986400679907;7329.083864516935;7490.812729681802;7509.849803003909;7510.962445187739;7564.962175189089;4144.937825932613;4159.376093585986;4215.388768628593;4254.914901701949;4315.352697095398;3298.835058247078;3363.9495407568907;3415.8804441844914;3416.521686963827;3418.8632454701533;542.1136441917494;599.7600959616188;612.6293592376637;623.2209737827716;627.8712863862897;537.4786457136571;634.9936500635027;639.9392057754505;642.3448082955359;644.5166125405946;8455.069942306342;8621.568921553897;8659.740207793777;8842.46945183291;9198.908010919939;585.3297973819953;633.37612451735;662.4634046422414;663.366485006827;675.1560549313358;3505.719542436599;4255.87232383029;4421.717701866487;4469.821207151677;4562.338460923214;102086;102133.85063045664;105125.89748204993;107276.46361768297;107713;69922.21089047537;72661.27338726545;94635;95071;95799;50551.10345205478;74280.62859685667;75773.9999999989;77395;80380;7892.447528672938;8717;9090.954545227232;9139.77150571241;9420.811583768424;43753;45062.098758024;45446.318305224784;49408.01183976372;52691.47308527019;47022.52977470181;63890;65588.6720566394;72869.63565182353;74448.62775686086;10400.58397664085;12068.818967715315;12399.318037508023;12459.190152640049;12654.810177847156;13530.458781648624;13648.86351136476;13749.793753093913;13837.238951857744;13912.095713778572;12536.498540058661;12618.242905425705;12739.55411560591;12961.351932403531;13119.73760524765;12785.232886026868;12904.548340808027;12935.611931642066;12978.415971281127;13564.083721999423;11744;11781.646550603497;11877.406129693654;12352.320622365856;12392.194507357353;9659.951700241454;10430.42632655181;10526.000867609686;10531.104856087319;10877.945610271898;10816.729581760519;10880.945595271973;10981.61564345244;11018.393988330397;11131.443427828739 01:02:18 INFO - PROCESS | 1641 | __end_tp_report 01:02:18 INFO - PROCESS | 1641 | __start_cc_report 01:02:18 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,371 01:02:18 INFO - PROCESS | 1641 | __end_cc_report 01:02:18 INFO - PROCESS | 1641 | __startTimestamp1479805338298__endTimestamp 01:02:18 INFO - PROCESS | 1641 | 01:02:18 INFO - PROCESS | 1641 | ------- Summary: start ------- 01:02:18 INFO - PROCESS | 1641 | Number of tests: 6 01:02:18 INFO - PROCESS | 1641 | 01:02:18 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5631.54 Median:5848.65 stddev:644.48 (11.0%) stddev-sans-first:608.76 01:02:18 INFO - PROCESS | 1641 | Values: 2862.8 3192.9 3411.6 3417.7 3436.0 5519.5 5633.7 5646.1 5692.4 5711.6 4782.9 5559.1 5629.5 5779.5 5781.1 5089.6 5144.1 5225.8 5268.8 5291.4 5235.4 5288.5 5297.8 5307.8 5358.8 5087.5 5173.6 5212.3 5222.8 5253.6 5198.0 5204.9 5208.9 5212.3 5213.0 5135.8 5140.2 5146.1 5188.8 5194.1 5458.6 5555.7 5581.1 5582.1 5653.2 6121.6 6136.2 6140.8 6170.4 6174.2 6222.9 6225.6 6226.1 6230.1 6261.7 5848.6 5850.9 5851.9 5877.9 5886.5 6142.7 6143.4 6160.5 6191.2 6213.9 4897.8 6054.8 6060.0 6072.1 6088.6 6051.1 6074.1 6173.8 6207.2 6217.5 6029.1 6066.5 6094.8 6108.1 6111.8 6121.7 6126.8 6130.8 6138.8 6142.5 4844.3 4855.9 4862.5 4865.7 4872.9 4371.8 4376.6 4402.3 4417.3 4423.6 5558.2 5718.2 5738.6 5755.9 5846.3 5431.8 5729.5 6231.9 6246.8 6263.3 5850.5 5851.9 5856.9 5866.4 5868.1 5296.2 5356.0 5366.7 5373.5 5393.6 5650.7 5904.8 6153.5 6216.7 6296.3 6233.0 6237.5 6247.0 6252.6 6257.9 6237.6 6262.8 6271.0 6273.6 6303.6 5188.8 5231.5 5397.4 6078.1 6093.2 5040.2 5083.8 5621.3 5723.5 6219.4 4901.2 6035.0 6106.6 6132.6 6153.3 5089.9 6109.5 6117.8 6135.5 6139.2 4928.3 5787.5 5837.7 5884.7 5989.1 4985.7 6062.7 6082.5 6089.0 6119.1 5953.1 5981.8 5988.3 5990.4 6002.2 01:02:18 INFO - PROCESS | 1641 | 01:02:18 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:67586.80 Median:38432.31 stddev:150041.47 (390.4%) stddev-sans-first:150377.79 01:02:18 INFO - PROCESS | 1641 | Values: 484.4 491.0 531.3 553.6 570.4 36531.8 38869.8 38928.0 39016.2 39058.0 39804.0 40383.2 40408.6 40442.0 40531.2 9185.5 9335.0 9377.7 9414.5 9424.5 9229.4 9231.4 9303.5 9312.5 9322.6 9231.0 9248.0 9295.9 9333.2 9345.2 9273.0 9286.0 9304.9 9321.3 9343.6 9247.5 9248.0 9320.3 9354.2 9360.9 38206.0 38239.8 38396.0 38562.8 38864.6 3340.1 3344.9 3357.1 3486.3 3493.0 29657.9 30004.8 30224.2 30690.4 30691.5 3178.8 3183.2 3232.3 3248.8 3351.8 459112.0 463012.0 468548.0 469532.0 470787.0 53151.7 53362.5 53368.0 53390.0 53483.9 33377.3 34726.0 34761.7 35794.6 60138.0 15674.2 15898.8 16099.4 16151.9 16160.0 12191.4 12235.8 12305.6 12345.4 12357.7 18860.9 18885.4 18965.9 18966.2 19040.1 6656.7 7799.6 7919.5 7929.5 7999.5 772.3 931.2 976.8 979.7 1036.9 28817.0 29210.0 29710.0 29901.6 30002.5 1000.6 1003.0 1049.9 1054.3 1115.7 14765.9 15293.7 15331.2 15533.8 15632.5 652907.0 666735.0 667654.0 667910.0 667977.0 56166.4 56169.7 56609.7 56688.9 56927.4 1310.1 1350.8 1371.5 1371.8 1379.8 28498.9 29232.8 29287.6 29748.7 29800.6 44488.8 44623.8 44631.8 49645.0 49772.3 526712.0 530248.0 534714.0 535961.0 537287.0 25824.0 25848.6 26189.3 26225.5 26249.0 44807.7 45055.3 45124.9 45803.8 46144.3 36119.9 37939.1 38025.2 38898.0 38980.6 37237.0 37405.8 37432.1 38285.0 38468.6 31633.4 32069.9 34799.7 37002.8 37529.6 27607.0 32446.9 34085.0 34124.8 34318.5 23536.9 24968.6 25424.6 25546.2 26171.3 01:02:18 INFO - PROCESS | 1641 | 01:02:18 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:34064.12 Median:309.67 stddev:95756.58 (30922.3%) stddev-sans-first:95991.63 01:02:18 INFO - PROCESS | 1641 | Values: 292.9 298.4 321.0 355.1 390.9 17100.0 18657.3 19350.6 19519.3 19974.4 18830.5 19400.4 19922.0 20298.4 20344.4 4667.3 5777.1 5962.0 6073.8 6090.8 5012.3 5752.8 5886.6 5951.2 5987.2 5367.6 5691.6 5883.7 5906.0 5945.5 5647.5 5813.5 5860.4 5867.6 5955.1 5591.7 5657.3 5677.1 5680.2 5688.1 23638.2 23707.4 24249.3 24467.5 24637.4 2060.8 2220.1 2253.4 2259.9 2642.2 9441.2 9777.2 9807.3 10132.3 10342.9 1854.9 1862.5 1869.4 1890.5 1892.7 123824.0 124451.8 127277.0 127621.0 128806.4 11173.9 11548.5 11563.0 11597.9 11988.8 14387.7 14396.5 14418.0 14432.9 14433.9 8127.9 8367.1 8374.3 8548.7 8555.7 6847.6 8289.5 8297.8 8323.4 8460.3 3747.5 4369.8 4468.4 4598.8 4673.0 1815.7 1870.1 1929.8 1947.4 1967.6 801.8 862.0 909.8 930.6 991.6 15544.8 19258.9 20993.6 21025.0 21075.9 835.2 855.9 922.3 934.5 935.9 10287.7 10823.9 10831.4 10859.3 10873.9 566810.0 566873.0 569971.2 571648.0 576884.0 125528.0 128181.0 128878.0 132748.0 133291.7 945.5 981.3 995.9 996.3 1009.1 16342.3 16736.6 17445.4 18038.3 18485.1 37775.8 52458.0 52500.9 53260.0 55134.2 59588.0 77068.2 77688.0 78320.6 79948.8 6412.8 7761.0 7789.1 7935.3 8116.7 9042.4 11839.7 12474.2 12732.6 12778.4 9598.7 9926.8 10529.4 10880.2 11136.2 10702.3 10871.7 11000.5 11133.6 11153.6 10051.9 11107.3 11177.2 11400.5 11567.9 7761.1 7922.4 7927.3 7947.0 8008.0 7274.7 10599.4 10815.4 11057.0 11066.2 01:02:18 INFO - PROCESS | 1641 | 01:02:18 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5440.73 Median:5133.96 stddev:2987.21 (58.2%) stddev-sans-first:2980.53 01:02:18 INFO - PROCESS | 1641 | Values: 1450.1 1470.6 1615.0 1723.7 1744.7 6752.2 7864.6 8181.3 8432.4 8652.8 8116.5 8432.8 8509.7 8544.3 8547.7 2300.5 2307.4 2308.7 2312.9 2314.5 2338.1 2368.1 2389.4 2397.1 2415.3 2325.9 2328.4 2352.1 2353.7 2356.3 2298.2 2304.7 2325.2 2325.8 2334.1 1920.6 1940.8 2328.8 2355.3 2362.6 8338.2 8443.2 8451.2 8495.4 8583.4 5126.3 5294.1 5297.7 5326.4 5377.2 3094.6 3337.3 3395.9 3632.3 3681.1 1046.0 1052.7 1073.6 1091.5 1094.2 11160.1 11161.2 11224.7 11241.8 11278.2 4409.8 4626.7 4656.3 4715.4 4715.6 9070.0 9166.6 9170.5 9172.2 9207.0 5126.7 5141.2 5152.3 5165.9 5168.8 4329.4 4336.0 4745.8 4830.7 4839.8 3788.4 3790.0 3882.4 3921.6 3960.0 1258.3 1270.1 1275.5 1282.5 1284.1 708.5 773.6 776.9 858.3 890.7 5291.1 5478.5 5627.3 5651.4 5774.2 745.0 858.6 890.4 907.7 939.4 3860.3 3898.4 3931.6 3994.2 4020.8 9900.5 10295.3 10306.9 10309.6 10322.8 10198.0 10253.0 10253.8 10289.5 10301.6 7560.2 7726.0 7909.4 7909.7 8078.8 5602.4 5707.5 5725.1 5790.0 5826.3 9280.6 9376.2 9503.1 9656.8 9729.1 7445.4 7517.5 8204.8 9594.5 9624.8 5738.9 6709.0 6756.6 7099.0 7317.9 7941.5 8088.8 8149.7 8166.7 8170.2 6005.4 6073.7 6229.8 7126.1 7221.0 6953.2 6959.3 7092.8 7097.3 7129.3 6615.3 6727.8 6746.0 6754.4 6872.3 4830.8 4856.4 4871.4 4896.0 4989.4 4834.5 4862.9 4900.3 4991.6 5019.4 01:02:18 INFO - PROCESS | 1641 | 01:02:18 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4458.30 Median:4954.78 stddev:1400.53 (28.3%) stddev-sans-first:1368.17 01:02:18 INFO - PROCESS | 1641 | Values: 238.4 253.3 292.4 308.8 317.3 5133.3 5288.6 5354.6 5434.6 5445.8 4256.0 5124.5 5320.9 5364.9 5372.6 3921.1 4108.9 4119.8 4124.3 4142.2 3243.9 3597.6 3984.6 4104.4 4155.7 4056.5 4066.0 4074.7 4080.8 4086.3 3086.0 3244.8 3506.2 3985.7 4080.2 2825.9 3582.5 3600.4 3607.8 3628.3 4188.8 4999.3 5156.9 5206.5 5301.3 3860.4 4006.7 4463.9 4673.8 4694.2 4592.0 4789.4 5497.8 5665.9 5677.7 3470.1 3586.0 3847.5 3972.4 3982.9 4878.9 5578.3 5889.2 5919.5 5963.4 3432.9 3571.7 3594.7 3758.0 3897.3 3560.0 3786.4 3956.7 3965.9 3975.7 4007.3 4039.9 4539.0 4591.7 4609.5 5741.0 5751.9 5764.7 5772.8 5777.9 1053.6 1107.4 1245.6 1280.6 1286.4 856.6 876.8 879.0 880.3 889.9 4604.0 4604.0 4991.0 5082.9 5088.4 5306.7 5627.7 5629.3 5659.4 5676.4 4585.3 4693.4 4946.6 5100.1 5104.4 2450.0 2511.1 2900.3 2908.0 2935.4 5217.9 5395.0 5504.9 5778.5 5856.5 5208.4 5617.7 5715.5 5743.8 5767.8 4678.7 5170.0 5422.7 5630.8 5835.1 4338.6 4811.7 5455.4 5478.9 5874.3 4977.8 5643.6 5874.4 5881.2 5902.3 4938.9 5162.8 5611.0 6222.9 6239.7 3828.2 3901.3 3902.0 3914.3 3922.6 5109.5 5204.7 5561.9 5940.7 5946.6 5445.0 5615.7 5728.5 5730.9 5770.9 4757.5 5569.3 5657.3 5672.6 5716.3 4422.5 4552.7 4702.3 4770.7 5568.6 4931.4 4963.0 5134.9 5685.6 5714.3 4618.4 5407.0 5604.6 5635.8 5691.7 01:02:18 INFO - PROCESS | 1641 | 01:02:18 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19222.06 Median:24034.18 stddev:27796.24 (115.7%) stddev-sans-first:27839.06 01:02:18 INFO - PROCESS | 1641 | Values: 603.7 638.8 694.5 708.4 798.5 14101.4 14288.7 14818.4 14880.2 14906.4 13164.3 13454.9 13460.2 13514.3 13575.4 652.4 674.2 815.4 831.6 832.6 653.2 685.9 698.4 707.5 709.7 853.8 874.8 879.5 879.7 880.5 870.7 873.3 873.9 874.5 877.9 647.4 649.9 650.3 651.6 654.1 13564.5 13864.8 13923.0 14070.2 14106.6 13083.3 13136.1 13171.0 13365.4 13529.3 11202.7 11220.2 11412.3 11416.1 11669.5 3304.1 3537.5 3574.4 3776.0 3782.4 88621.0 89414.6 89441.6 89801.0 89992.2 10114.5 10199.2 10293.2 10307.4 10548.3 16988.2 20160.0 20228.2 20239.1 20272.0 7329.1 7490.8 7509.8 7511.0 7565.0 4144.9 4159.4 4215.4 4254.9 4315.4 3298.8 3363.9 3415.9 3416.5 3418.9 542.1 599.8 612.6 623.2 627.9 537.5 635.0 639.9 642.3 644.5 8455.1 8621.6 8659.7 8842.5 9198.9 585.3 633.4 662.5 663.4 675.2 3505.7 4255.9 4421.7 4469.8 4562.3 102086.0 102133.9 105125.9 107276.5 107713.0 69922.2 72661.3 94635.0 95071.0 95799.0 50551.1 74280.6 75774.0 77395.0 80380.0 7892.4 8717.0 9091.0 9139.8 9420.8 43753.0 45062.1 45446.3 49408.0 52691.5 47022.5 63890.0 65588.7 72869.6 74448.6 10400.6 12068.8 12399.3 12459.2 12654.8 13530.5 13648.9 13749.8 13837.2 13912.1 12536.5 12618.2 12739.6 12961.4 13119.7 12785.2 12904.5 12935.6 12978.4 13564.1 11744.0 11781.6 11877.4 12352.3 12392.2 9660.0 10430.4 10526.0 10531.1 10877.9 10816.7 10880.9 10981.6 11018.4 11131.4 01:02:18 INFO - PROCESS | 1641 | -------- Summary: end -------- 01:02:18 INFO - PROCESS | 1641 | 01:02:18 INFO - TEST-INFO | 1641: exit 0 01:02:18 INFO - TEST-OK | dromaeo_css | took 1117043ms 01:02:18 INFO - TEST-START | kraken 01:02:18 INFO - operating with platform_type : mac_ 01:02:18 INFO - Initialising browser for kraken test... 01:02:18 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmp_DN8Tv/profile http://localhost:49251/getInfo.html) 01:02:22 INFO - PROCESS | 1652 | 2016-11-22 01:02:22.054 plugin-container[1653:10414] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 01:02:22 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:02:22 INFO - PROCESS | 1652 | 2016-11-22 01:02:22.057 plugin-container[1653:10414] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 01:02:22 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:02:22 INFO - PROCESS | 1652 | 2016-11-22 01:02:22.058 plugin-container[1653:10414] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:02:22 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 01:02:22 INFO - PROCESS | 1652 | colorDepth:24 01:02:22 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 01:02:22 INFO - PROCESS | 1652 | __metrics 01:02:22 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 01:02:23 INFO - TEST-INFO | 1652: exit 0 01:02:23 INFO - Browser initialized. 01:02:23 INFO - Running cycle 1/1 for kraken test... 01:02:23 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmp_DN8Tv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:02:25 INFO - PROCESS | 1654 | 2016-11-22 01:02:25.741 plugin-container[1655:10527] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 01:02:25 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:02:25 INFO - PROCESS | 1654 | 2016-11-22 01:02:25.742 plugin-container[1655:10527] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 01:02:25 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:02:25 INFO - PROCESS | 1654 | 2016-11-22 01:02:25.742 plugin-container[1655:10527] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:02:35 INFO - PROCESS | 1654 | RSS: Main: 243384320 01:02:35 INFO - PROCESS | 1654 | 01:02:38 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 01:02:39 INFO - PROCESS | 1654 | RSS: Main: 255651840 01:02:39 INFO - PROCESS | 1654 | 01:02:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 01:02:42 INFO - PROCESS | 1654 | RSS: Main: 263188480 01:02:42 INFO - PROCESS | 1654 | 01:02:46 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 01:02:46 INFO - PROCESS | 1654 | RSS: Main: 265994240 01:02:46 INFO - PROCESS | 1654 | 01:02:48 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 01:02:49 INFO - PROCESS | 1654 | RSS: Main: 267096064 01:02:49 INFO - PROCESS | 1654 | 01:02:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:02:52 INFO - PROCESS | 1654 | RSS: Main: 268754944 01:02:52 INFO - PROCESS | 1654 | 01:02:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 01:02:55 INFO - PROCESS | 1654 | RSS: Main: 274202624 01:02:55 INFO - PROCESS | 1654 | 01:02:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 01:02:59 INFO - PROCESS | 1654 | RSS: Main: 273940480 01:02:59 INFO - PROCESS | 1654 | 01:03:02 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 01:03:03 INFO - PROCESS | 1654 | RSS: Main: 274243584 01:03:03 INFO - PROCESS | 1654 | 01:03:04 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:03:05 INFO - PROCESS | 1654 | RSS: Main: 273367040 01:03:05 INFO - PROCESS | 1654 | 01:03:07 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:03:08 INFO - PROCESS | 1654 | RSS: Main: 273240064 01:03:08 INFO - PROCESS | 1654 | 01:03:10 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:03:11 INFO - PROCESS | 1654 | RSS: Main: 273293312 01:03:11 INFO - PROCESS | 1654 | 01:03:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:03:14 INFO - PROCESS | 1654 | RSS: Main: 273645568 01:03:14 INFO - PROCESS | 1654 | 01:03:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:03:18 INFO - PROCESS | 1654 | RSS: Main: 273514496 01:03:18 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 01:03:20 INFO - PROCESS | 1654 | RSS: Main: 275517440 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | __start_tp_report 01:03:20 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 01:03:20 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 01:03:20 INFO - PROCESS | 1654 | |i|pagename|runs| 01:03:20 INFO - PROCESS | 1654 | |0;ai-astar;153;173;120;114;159;122;117;119;117;119 01:03:20 INFO - PROCESS | 1654 | |1;audio-beat-detection;183;117;115;180;121;118;133;107;133;150 01:03:20 INFO - PROCESS | 1654 | |2;audio-dft;205;247;208;195;194;196;200;195;197;195 01:03:20 INFO - PROCESS | 1654 | |3;audio-fft;81;59;61;84;63;51;58;71;108;87 01:03:20 INFO - PROCESS | 1654 | |4;audio-oscillator;88;75;104;59;92;101;122;71;70;89 01:03:20 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;137;133;106;88;89;88;98;132;136;86 01:03:20 INFO - PROCESS | 1654 | |6;imaging-darkroom;96;100;96;97;97;96;96;96;96;99 01:03:20 INFO - PROCESS | 1654 | |7;imaging-desaturate;92;96;93;114;145;86;145;146;115;92 01:03:20 INFO - PROCESS | 1654 | |8;json-parse-financial;58;57;57;56;57;59;87;91;88;88 01:03:20 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;73;72;76;77;74;70;68;71;70;71 01:03:20 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;92;106;78;111;79;105;81;73;89;109 01:03:20 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;163;151;153;161;155;151;139;161;219;194 01:03:20 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;176;176;177;177;176;184;231;183;176;175 01:03:20 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;66;81;84;68;69;69;68;69;72;87 01:03:20 INFO - PROCESS | 1654 | __end_tp_report 01:03:20 INFO - PROCESS | 1654 | __start_cc_report 01:03:20 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,953 01:03:20 INFO - PROCESS | 1654 | __end_cc_report 01:03:20 INFO - PROCESS | 1654 | __startTimestamp1479805400548__endTimestamp 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | ------- Summary: start ------- 01:03:20 INFO - PROCESS | 1654 | Number of tests: 14 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:131.30 Median:121.00 stddev:21.61 (17.9%) stddev-sans-first:21.44 01:03:20 INFO - PROCESS | 1654 | Values: 153.0 173.0 120.0 114.0 159.0 122.0 117.0 119.0 117.0 119.0 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:133.00 stddev:26.98 (20.3%) stddev-sans-first:22.54 01:03:20 INFO - PROCESS | 1654 | Values: 183.0 117.0 115.0 180.0 121.0 118.0 133.0 107.0 133.0 150.0 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:203.20 Median:198.50 stddev:16.10 (8.1%) stddev-sans-first:17.06 01:03:20 INFO - PROCESS | 1654 | Values: 205.0 247.0 208.0 195.0 194.0 196.0 200.0 195.0 197.0 195.0 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:72.30 Median:67.00 stddev:17.49 (26.1%) stddev-sans-first:18.27 01:03:20 INFO - PROCESS | 1654 | Values: 81.0 59.0 61.0 84.0 63.0 51.0 58.0 71.0 108.0 87.0 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:73.00 stddev:18.89 (25.9%) stddev-sans-first:20.04 01:03:20 INFO - PROCESS | 1654 | Values: 88.0 75.0 104.0 59.0 92.0 101.0 122.0 71.0 70.0 89.0 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:87.00 stddev:22.50 (25.9%) stddev-sans-first:21.52 01:03:20 INFO - PROCESS | 1654 | Values: 137.0 133.0 106.0 88.0 89.0 88.0 98.0 132.0 136.0 86.0 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:96.90 Median:96.00 stddev:1.45 (1.5%) stddev-sans-first:1.50 01:03:20 INFO - PROCESS | 1654 | Values: 96.0 100.0 96.0 97.0 97.0 96.0 96.0 96.0 96.0 99.0 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:112.40 Median:89.00 stddev:24.58 (27.6%) stddev-sans-first:24.94 01:03:20 INFO - PROCESS | 1654 | Values: 92.0 96.0 93.0 114.0 145.0 86.0 145.0 146.0 115.0 92.0 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:69.80 Median:73.00 stddev:16.14 (22.1%) stddev-sans-first:16.55 01:03:20 INFO - PROCESS | 1654 | Values: 58.0 57.0 57.0 56.0 57.0 59.0 87.0 91.0 88.0 88.0 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:72.50 stddev:2.82 (3.9%) stddev-sans-first:2.98 01:03:20 INFO - PROCESS | 1654 | Values: 73.0 72.0 76.0 77.0 74.0 70.0 68.0 71.0 70.0 71.0 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:92.30 Median:78.50 stddev:14.41 (18.4%) stddev-sans-first:15.29 01:03:20 INFO - PROCESS | 1654 | Values: 92.0 106.0 78.0 111.0 79.0 105.0 81.0 73.0 89.0 109.0 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:164.70 Median:161.00 stddev:23.81 (14.8%) stddev-sans-first:25.25 01:03:20 INFO - PROCESS | 1654 | Values: 163.0 151.0 153.0 161.0 155.0 151.0 139.0 161.0 219.0 194.0 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.10 Median:177.00 stddev:17.12 (9.7%) stddev-sans-first:17.96 01:03:20 INFO - PROCESS | 1654 | Values: 176.0 176.0 177.0 177.0 176.0 184.0 231.0 183.0 176.0 175.0 01:03:20 INFO - PROCESS | 1654 | 01:03:20 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:70.50 stddev:7.66 (10.9%) stddev-sans-first:7.66 01:03:20 INFO - PROCESS | 1654 | Values: 66.0 81.0 84.0 68.0 69.0 69.0 68.0 69.0 72.0 87.0 01:03:20 INFO - PROCESS | 1654 | -------- Summary: end -------- 01:03:20 INFO - PROCESS | 1654 | 01:03:21 INFO - TEST-INFO | 1654: exit 0 01:03:21 INFO - TEST-OK | kraken | took 62871ms 01:03:21 INFO - SUITE-END | took 1179s 01:03:22 INFO - Completed test suite (00:19:40) 01:03:22 INFO - javascript benchmark 01:03:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7058.782522754274, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2862.756665683417, 3192.8563214655337, 3411.5564976553046, 3417.67532084452, 3436.037909385373, 5519.503244707986, 5633.718314084294, 5646.124850648146, 5692.430756924306, 5711.600187986843, 4782.880427989302, 5559.054960656688, 5629.521490673287, 5779.450952159548, 5781.075027995522, 5089.618278629099, 5144.09978253806, 5225.761862379978, 5268.789248430058, 5291.444398338158, 5235.397929238129, 5288.471152884719, 5297.788088476456, 5307.761150748206, 5358.839234822942, 5087.542121209109, 5173.586113110942, 5212.348456442945, 5222.791088356461, 5253.60597955153, 5198.0, 5204.895902081975, 5208.869778255574, 5212.270282160501, 5213.0, 5135.8202462914, 5140.228965655144, 5146.099432599318, 5188.766505507261, 5194.065068287707, 5458.61789674723, 5555.749991250363, 5581.05122129239, 5582.106862901956, 5653.208550802891, 6121.571489995702, 6136.182108626207, 6140.841692977875, 6170.413810687977, 6174.197354343906, 6222.875542489171, 6225.595336303171, 6226.066090086478, 6230.127782110508, 6261.749530018794, 5848.64908105515, 5850.882982340372, 5851.941480585225, 5877.853053673693, 5886.470217680387, 6142.682951206299, 6143.358355906914, 6160.507159427235, 6191.226096737908, 6213.875722485525, 4897.755112244374, 6054.757809687563, 6059.969805133138, 6072.149899014142, 6088.634681919099, 6051.0923361496125, 6074.088886667036, 6173.84565385869, 6207.193064901534, 6217.533684973645, 6029.125776762345, 6066.4540191382985, 6094.817155485254, 6108.144859719643, 6111.816645500642, 6121.724522396503, 6126.792505495304, 6130.816075517741, 6138.785142519991, 6142.539309551801, 4844.273358996108, 4855.854324370274, 4862.513748625109, 4865.659403841697, 4872.926906096412, 4371.846985355497, 4376.584224498668, 4402.273624851928, 4417.293233082692, 4423.60187583119, 5558.249636299048, 5718.170865224519, 5738.569607279471, 5755.913661295084, 5846.269216347957, 5431.755570999236, 5729.455701708414, 6231.906521402183, 6246.843828904223, 6263.311035786151, 5850.531957443394, 5851.912221316684, 5856.912146317809, 5866.442687944638, 5868.119782032644, 5296.179092240632, 5356.0, 5366.731663416814, 5373.543248823894, 5393.568514518829, 5650.745716442852, 5904.760000399879, 6153.538484613582, 6216.689165541705, 6296.33888441707, 6233.0, 6237.500999919905, 6246.968765156327, 6252.5935814171935, 6257.874842503215, 6237.563370564017, 6262.780802671885, 6271.0, 6273.62358258506, 6303.590266632654, 5188.766505507337, 5231.529162375405, 5397.406285308534, 6078.088286757022, 6093.238345206849, 5040.243963405518, 5083.771230294572, 5621.297337832771, 5723.456271654186, 6219.378062193925, 4901.215805471107, 6034.974054410673, 6106.551676171802, 6132.63204207749, 6153.261608606996, 5089.898202036013, 6109.541784366279, 6117.755289788537, 6135.53983451234, 6139.171211886338, 4928.334674818853, 5787.479126878601, 5837.67892765902, 5884.705764711662, 5989.131575921554, 4985.651004429655, 6062.6665533394325, 6082.513398928164, 6089.0, 6119.082137679384, 5953.077273022688, 5981.790637327673, 5988.28140623128, 5990.371011043778, 6002.249718785152], "value": 5592.856611176096, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.44772958826934, 491.01061361356864, 531.2721571447119, 553.5543887170828, 570.3526497425426, 36531.817340913294, 38869.80565097174, 38928.0, 39016.21967560647, 39058.0, 39804.0, 40383.19233615326, 40408.59591404085, 40442.0, 40531.18937621246, 9185.49479778612, 9335.0, 9377.718668439958, 9414.52927353633, 9424.481653509054, 9229.400088994194, 9231.399959002676, 9303.488308143049, 9312.534373281344, 9322.62709491623, 9230.953845230799, 9247.982721900586, 9295.860562091573, 9333.160015598594, 9345.158935695787, 9272.953635231814, 9285.953570232174, 9304.8604270936, 9321.347505674537, 9343.626254949793, 9247.49138797366, 9247.953760231223, 9320.30097742672, 9354.204892584139, 9360.90639093614, 38206.0, 38239.808800956096, 38396.0, 38562.80718596417, 38864.63973760933, 3340.1148695595443, 3344.916377090568, 3357.143928298293, 3486.337595856779, 3492.9870337602065, 29657.851710741525, 30004.8499757502, 30224.24439389011, 30690.38619227625, 30691.539626905615, 3178.8410579471165, 3183.220111072802, 3232.2565809863627, 3248.8213148276836, 3351.765376423651, 459112.0, 463012.0, 468548.0, 469532.0, 470787.0, 53151.734241328544, 53362.46637533575, 53368.0, 53390.0, 53483.93032139335, 33377.3324533508, 34726.0, 34761.65238347584, 35794.64205357965, 60138.0, 15674.216289185495, 15898.774171615984, 16099.436519721989, 16151.919240403722, 16160.0, 12191.390430478617, 12235.816462753066, 12305.56930507428, 12345.444454999546, 12357.691057723629, 18860.905695471436, 18885.43343699691, 18965.862048277148, 18966.241350346107, 19040.143193556323, 6656.73373065072, 7799.596072706967, 7919.5248285101925, 7929.5242285463055, 7999.480033797785, 772.3203580848817, 931.1852129386787, 976.770458942148, 979.7011911366959, 1036.891126431729, 28817.0, 29209.977650782545, 29710.0, 29901.551476727866, 30002.549961750592, 1000.5747557288123, 1002.9799404012025, 1049.9107575855985, 1054.2778196935124, 1115.654147214366, 14765.926170369079, 15293.694126117416, 15331.156786376856, 15533.84466155324, 15632.514911083656, 652907.0, 666735.0, 667654.0, 667910.0, 667977.0, 56166.438335616935, 56169.71915140398, 56609.71695141498, 56688.86622267614, 56927.43072569304, 1310.1461432073509, 1350.783874580082, 1371.5199680111882, 1371.7530844448095, 1379.8482166961626, 28498.857505713167, 29232.83068677229, 29287.560686590143, 29748.702512974593, 29800.55299170471, 44488.777556112014, 44623.77688111539, 44631.776841115585, 49645.0, 49772.25341619806, 526712.0, 530248.0, 534714.0, 535961.0, 537287.0, 25824.0, 25848.612270815578, 26189.345266368495, 26225.4754904897, 26249.0, 44807.65577032694, 45055.32417013682, 45124.87187820331, 45803.77098114488, 46144.307835383166, 36119.916402507966, 37939.05152371213, 38025.239495210495, 38898.0275493115, 38980.6101938977, 37237.0, 37405.812970934974, 37432.06419839417, 38285.0, 38468.6153138465, 31633.367332653677, 32069.87755428549, 34799.65200347964, 37002.814985924895, 37529.62470375261, 27607.03375381852, 32446.864359747295, 34085.0, 34124.82937585296, 34318.48522272218, 23536.88231558831, 24968.625470617593, 25424.618630720182, 25546.23361299164, 26171.345716357242], "value": 19218.981478210524, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.85210968460933, 298.3853262279704, 320.95185722141673, 355.1122194513716, 390.89836642472966, 17099.999999999985, 18657.34699285525, 19350.612987740238, 19519.316823911166, 19974.400767976967, 18830.52923676909, 19400.417987460365, 19922.0, 20298.39104826854, 20344.389668309937, 4667.276572131325, 5777.133429985493, 5962.04607262838, 6073.7874174403905, 6090.786822461215, 5012.3483947086825, 5752.7986520471795, 5886.587938844283, 5951.196588460567, 5987.221661184061, 5367.597430192732, 5691.630044047144, 5883.735231914553, 5905.99196273267, 5945.464908158265, 5647.463490968352, 5813.476787089182, 5860.355360910296, 5867.589268751189, 5955.106733989893, 5591.692456914868, 5657.349404818417, 5677.120046392815, 5680.233168522238, 5688.061469857468, 23638.215927444402, 23707.40731481727, 24249.272521824372, 24467.510649787084, 24637.384065398328, 2060.7939206079423, 2220.123051394706, 2253.3915842722536, 2259.8757068361233, 2642.2073377986526, 9441.244700424018, 9777.217822574177, 9807.31348805584, 10132.290739648228, 10342.948285258526, 1854.944351669452, 1862.5157459060606, 1869.3924474545827, 1890.4706682129026, 1892.6877065284211, 123824.0, 124451.75548244492, 127276.99999999907, 127621.0, 128806.3559682205, 11173.944130279458, 11548.538058477734, 11563.0, 11597.884021159849, 11988.820167697491, 14387.712245755027, 14396.496122635657, 14418.0, 14432.855671443362, 14433.917456190828, 8127.878081828777, 8367.07962124178, 8374.330053595697, 8548.700795472128, 8555.700550480704, 6847.554908930903, 8289.544075075928, 8297.83404331922, 8323.417360784808, 8460.280876125475, 3747.5315585551807, 4369.80335884886, 4468.374427580141, 4598.770061496979, 4672.976635116803, 1815.709486482183, 1870.1116969439408, 1929.7745931333423, 1947.4157752674425, 1967.6261510312995, 801.8195905921121, 861.9742506417343, 909.8134882349102, 930.5765876526165, 991.613270824379, 15544.834137439737, 19258.90370548138, 20993.58012839765, 21025.0, 21075.94620269011, 835.1898658301438, 855.8758979947872, 922.3128769066954, 934.4673536084366, 935.8629265442383, 10287.74280642975, 10823.891761082446, 10831.400463888767, 10859.262517997082, 10873.945630271955, 566810.0, 566873.0, 569971.1501442466, 571648.0, 576884.0, 125528.0, 128181.0, 128878.0, 132748.0, 133291.66708332792, 945.5177859291673, 981.259149342242, 995.85560093786, 996.3324572536418, 1009.1018993096151, 16342.346306147621, 16736.56900335404, 17445.389411370543, 18038.278468861623, 18485.075746212904, 37775.81112094422, 52458.0, 52500.9499809994, 53260.0, 55134.172987406026, 59588.0, 77068.22931770611, 77688.0, 78320.60839695766, 79948.80076798968, 6412.775552855627, 7760.961195193988, 7789.122071001917, 7935.325497332791, 8116.675332986614, 9042.367034307536, 11839.704007399883, 12474.18917770342, 12732.554360597334, 12778.424970876147, 9598.66404675861, 9926.759155105612, 10529.420881851265, 10880.183986200907, 11136.220464567403, 10702.304350217211, 10871.67384978452, 11000.504977276201, 11133.610323638633, 11153.553857845594, 10051.899481005097, 11107.333559986426, 11177.161712871404, 11400.486978086172, 11567.88432115668, 7761.146273909978, 7922.366210703015, 7927.286544211049, 7947.006624171979, 8008.0, 7274.6726397313305, 10599.417032063002, 10815.405152716676, 11057.004869561779, 11066.170037247077], "value": 9284.213206511144, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.0574626492778, 1470.5808844479322, 1614.9838501614981, 1723.6638855423182, 1744.694678431275, 6752.189737231527, 7864.567448790329, 8181.263686268235, 8432.36757243207, 8652.783680407993, 8116.472429292092, 8432.83134337313, 8509.744707658765, 8544.316454683625, 8547.743567692964, 2300.470891694911, 2307.3654744945106, 2308.746037935834, 2312.8843557822124, 2314.525522267931, 2338.1115176233006, 2368.0882860098814, 2389.4026493376655, 2397.077125306752, 2415.323709361373, 2325.9185928492507, 2328.382978510696, 2352.141468364052, 2353.6940197774265, 2356.257778799681, 2298.2071185440996, 2304.746477887431, 2325.2094287942095, 2325.837191396586, 2334.124703236278, 1920.5678722287514, 1940.7962163972861, 2328.8020518255967, 2355.316958082154, 2362.598358279097, 8338.166183381674, 8443.240108390213, 8451.197136272045, 8495.447795893248, 8583.356248281341, 5126.256692779563, 5294.073537130987, 5297.682139071668, 5326.414094449608, 5377.220303056035, 3094.5977022987095, 3337.3158502506926, 3395.9320813583836, 3632.2735452909524, 3681.1349332906743, 1046.0480962324325, 1052.6578861869846, 1073.613499140309, 1091.4542728635681, 1094.2340361746808, 11160.107191424666, 11161.218714689974, 11224.66326010221, 11241.775164496747, 11278.154138439568, 4409.801558929856, 4626.676132670681, 4656.301554766745, 4715.434147902205, 4715.622750180046, 9070.047644997312, 9166.633334666538, 9170.495622740813, 9172.174504294511, 9206.953965230265, 5126.6923984561045, 5141.228815677603, 5152.278680984665, 5165.9225111623355, 5168.8449346519665, 4329.437173167468, 4336.046069864625, 4745.810167593258, 4830.686005409637, 4839.758012099381, 3788.3559794834946, 3789.981050094787, 3882.4176373544196, 3921.647051765298, 3959.9604003960167, 1258.3456602566614, 1270.142653708743, 1275.5344299330677, 1282.4549566434227, 1284.062634276983, 708.4757279613049, 773.5590713293482, 776.9494982826099, 858.3390789092489, 890.7440508882445, 5291.11623680713, 5478.45215478457, 5627.296587926509, 5651.378348381675, 5774.191613174159, 744.985100297991, 858.6050416808213, 890.3633901760272, 907.682311191091, 939.3659279985982, 3860.285847118292, 3898.434726964574, 3931.6461518463475, 3994.1811928554575, 4020.798960052044, 9900.455474948803, 10295.330803497749, 10306.948465257627, 10309.587616495257, 10322.845157322647, 10198.0, 10252.974702529837, 10253.84619230712, 10289.485525723683, 10301.639442619622, 7560.168381478033, 7726.0, 7909.406794490436, 7909.7231596893835, 8078.757637270891, 5602.355729091044, 5707.4577915098, 5725.141228815711, 5790.044642633919, 5826.329972053268, 9280.628774849201, 9376.20302274287, 9503.097205765442, 9656.806863862825, 9729.124378805658, 7445.4043676506835, 7517.511361761468, 8204.753857384288, 9594.520273986413, 9624.75938101553, 5738.856528586652, 6708.966455167693, 6756.594604323756, 7098.964505177441, 7317.926820731725, 7941.523508589272, 8088.838223235384, 8149.674013039649, 8166.714165004434, 8170.182981701782, 6005.3694362091355, 6073.726682299219, 6229.781957631461, 7126.073610430818, 7221.0, 6953.165620125617, 6959.269276726073, 7092.822679433056, 7097.325754053357, 7129.251428599922, 6615.338466153346, 6727.798166055026, 6745.9662701686175, 6754.3921047106, 6872.312768723287, 4830.758462076952, 4856.417229932431, 4871.439784424837, 4895.951040489549, 4989.351384320015, 4834.468208497133, 4862.90274194507, 4900.338454308623, 4991.575716064175, 5019.397672279204], "value": 4404.684363170047, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [238.40993540986062, 253.2592167908866, 292.3786952725458, 308.8403046560166, 317.26236500137185, 5133.255677926699, 5288.603354748399, 5354.571634269254, 5434.592405569583, 5445.754941027658, 4255.9574404255945, 5124.461931497186, 5320.920186197211, 5364.892702145955, 5372.57019438445, 3921.0590757574896, 4108.917821643573, 4119.794010299488, 4124.278251306025, 4142.171565686875, 3243.869665772529, 3597.585633621396, 3984.5816189299994, 4104.445899803533, 4155.729877557964, 4056.5334986476637, 4065.9796701016453, 4074.653654439376, 4080.775557344345, 4086.3257562502154, 3085.9999999999886, 3244.7832062976386, 3506.160753974394, 3985.7117877750825, 4080.2047099276224, 2825.8869645214168, 3582.5342705448124, 3600.3879340512176, 3607.8376473058775, 3628.3468975584383, 4188.81150348235, 4999.32509111269, 5156.92264616031, 5206.453322401131, 5301.3373328333955, 3860.4209368594657, 4006.6994975376965, 4463.910721785546, 4673.836415725467, 4694.248920172756, 4592.0, 4789.449213340476, 5497.780088796443, 5665.886682266373, 5677.716114194273, 3470.0914497026533, 3586.0, 3847.480590120354, 3972.3644216925154, 3982.8805135845682, 4878.926456256112, 5578.30271216098, 5889.1755154278435, 5919.467247947706, 5963.36689038032, 3432.8798492052656, 3571.714262858965, 3594.676479116892, 3758.0229140423685, 3897.2780994150517, 3559.982200088983, 3786.3941769316775, 3956.7032472564674, 3965.861194858224, 3975.741576797499, 4007.318755811519, 4039.899002524902, 4538.954610453853, 4591.747453890068, 4609.492955774862, 5741.043362668229, 5751.9424805751405, 5764.74058667361, 5772.826815195551, 5777.913331300034, 1053.5943661690305, 1107.4462768615692, 1245.5640525816073, 1280.571008712092, 1286.414681319997, 856.6402111113347, 876.8334016536837, 878.9560521973875, 880.300161371711, 889.9421537600035, 4603.95396046042, 4604.04052514837, 4990.950090499048, 5082.941408729471, 5088.414832294334, 5306.708131052829, 5627.718614069362, 5629.324481062299, 5659.43405659439, 5676.4323567642905, 4585.28928016158, 4693.413323334583, 4946.579540739005, 5100.132977393852, 5104.3874735031295, 2449.963250551243, 2511.0709037656184, 2900.332308922378, 2907.97092029077, 2935.3688956874375, 5217.921731174036, 5395.003848576052, 5504.91742623861, 5778.5377169826315, 5856.531477481792, 5208.427073021964, 5617.72482144732, 5715.514181294637, 5743.798967036218, 5767.769289228384, 4678.719276843468, 5169.974150129226, 5422.674639521641, 5630.791517845976, 5835.066389377679, 4338.58783415575, 4811.735354555533, 5455.399906010256, 5478.890422191614, 5874.295084589877, 4977.8257760978195, 5643.576731745054, 5874.4139082448355, 5881.206037185097, 5902.2622172228475, 4938.8765280868265, 5162.793488260428, 5610.990021795953, 6222.879881621352, 6239.719212635533, 3828.2343531293295, 3901.308097412776, 3901.9904749054826, 3914.256291304643, 3922.6077392260545, 5109.514596113363, 5204.74703310431, 5561.943230786138, 5940.673262970578, 5946.583739138218, 5445.0, 5615.691136987505, 5728.54171666274, 5730.885382292247, 5770.8845823082465, 4757.52516719818, 5569.275994120739, 5657.264555607745, 5672.602917795714, 5716.314042314949, 4422.46930368345, 4552.704074235164, 4702.294655801657, 4770.666053376231, 5568.552176434076, 4931.3589233399425, 4962.975185124052, 5134.919048570828, 5685.573581981451, 5714.285714285715, 4618.4367675940175, 5406.9723853554915, 5604.579656525859, 5635.802746903839, 5691.658500489983], "value": 4004.774569712777, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.6770327874586, 638.75088715401, 694.4513834071084, 708.3695510995216, 798.5168972771471, 14101.365438555264, 14288.71422571548, 14818.40726370944, 14880.181590012571, 14906.403743850247, 13164.27596482193, 13454.932725336359, 13460.192388456699, 13514.256715880623, 13575.389107490162, 652.3606865272036, 674.1842370731397, 815.3803109636689, 831.6382373667444, 832.629479881453, 653.1867824558432, 685.8765422223997, 698.3679769808306, 707.5153519838926, 709.6735501669239, 853.769482239798, 874.8250349930007, 879.5030807593721, 879.7272845417909, 880.4761167105594, 870.7344260000697, 873.2620935309653, 873.89076365454, 874.5059041641452, 877.8595424732044, 647.355880898509, 649.8732747114315, 650.2976785072111, 651.6318280171688, 654.0744846042844, 13564.525241616497, 13864.792028119586, 13923.0, 14070.155790652594, 14106.647333816634, 13083.280419576919, 13136.146150500283, 13171.0, 13365.398557064858, 13529.32353382327, 11202.663920082328, 11220.214584979054, 11412.315261084279, 11416.086713062936, 11669.533218671242, 3304.107890869476, 3537.451694987281, 3574.3744844652083, 3776.0371105367954, 3782.375907975181, 88621.0, 89414.55292723495, 89441.55279223627, 89801.0, 89992.20093588746, 10114.54484548197, 10199.163513244403, 10293.176545876462, 10307.433091179908, 10548.314359566604, 16988.150592470327, 20159.99200039992, 20228.19087236494, 20239.08924098419, 20271.986400679907, 7329.083864516935, 7490.812729681802, 7509.849803003909, 7510.962445187739, 7564.962175189089, 4144.937825932613, 4159.376093585986, 4215.388768628593, 4254.914901701949, 4315.352697095398, 3298.835058247078, 3363.9495407568907, 3415.8804441844914, 3416.521686963827, 3418.8632454701533, 542.1136441917494, 599.7600959616188, 612.6293592376637, 623.2209737827716, 627.8712863862897, 537.4786457136571, 634.9936500635027, 639.9392057754505, 642.3448082955359, 644.5166125405946, 8455.069942306342, 8621.568921553897, 8659.740207793777, 8842.46945183291, 9198.908010919939, 585.3297973819953, 633.37612451735, 662.4634046422414, 663.366485006827, 675.1560549313358, 3505.719542436599, 4255.87232383029, 4421.717701866487, 4469.821207151677, 4562.338460923214, 102086.0, 102133.85063045664, 105125.89748204993, 107276.46361768297, 107713.0, 69922.21089047537, 72661.27338726545, 94635.0, 95071.0, 95799.0, 50551.10345205478, 74280.62859685667, 75773.9999999989, 77395.0, 80380.0, 7892.447528672938, 8717.0, 9090.954545227232, 9139.77150571241, 9420.811583768424, 43753.0, 45062.098758024, 45446.318305224784, 49408.01183976372, 52691.47308527019, 47022.52977470181, 63890.0, 65588.6720566394, 72869.63565182353, 74448.62775686086, 10400.58397664085, 12068.818967715315, 12399.318037508023, 12459.190152640049, 12654.810177847156, 13530.458781648624, 13648.86351136476, 13749.793753093913, 13837.238951857744, 13912.095713778572, 12536.498540058661, 12618.242905425705, 12739.55411560591, 12961.351932403531, 13119.73760524765, 12785.232886026868, 12904.548340808027, 12935.611931642066, 12978.415971281127, 13564.083721999423, 11744.0, 11781.646550603497, 11877.406129693654, 12352.320622365856, 12392.194507357353, 9659.951700241454, 10430.42632655181, 10526.000867609686, 10531.104856087319, 10877.945610271898, 10816.729581760519, 10880.945595271973, 10981.61564345244, 11018.393988330397, 11131.443427828739], "value": 7026.35473222298, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1603.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 173.0, 120.0, 114.0, 159.0, 122.0, 117.0, 119.0, 117.0, 119.0], "value": 131.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 117.0, 115.0, 180.0, 121.0, 118.0, 133.0, 107.0, 133.0, 150.0], "value": 135.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 247.0, 208.0, 195.0, 194.0, 196.0, 200.0, 195.0, 197.0, 195.0], "value": 203.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 61.0, 84.0, 63.0, 51.0, 58.0, 71.0, 108.0, 87.0], "value": 72.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 75.0, 104.0, 59.0, 92.0, 101.0, 122.0, 71.0, 70.0, 89.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 133.0, 106.0, 88.0, 89.0, 88.0, 98.0, 132.0, 136.0, 86.0], "value": 109.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 100.0, 96.0, 97.0, 97.0, 96.0, 96.0, 96.0, 96.0, 99.0], "value": 96.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 96.0, 93.0, 114.0, 145.0, 86.0, 145.0, 146.0, 115.0, 92.0], "value": 112.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 56.0, 57.0, 59.0, 87.0, 91.0, 88.0, 88.0], "value": 69.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 76.0, 77.0, 74.0, 70.0, 68.0, 71.0, 70.0, 71.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 106.0, 78.0, 111.0, 79.0, 105.0, 81.0, 73.0, 89.0, 109.0], "value": 92.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 151.0, 153.0, 161.0, 155.0, 151.0, 139.0, 161.0, 219.0, 194.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 177.0, 177.0, 176.0, 184.0, 231.0, 183.0, 176.0, 175.0], "value": 183.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 81.0, 84.0, 68.0, 69.0, 69.0, 68.0, 69.0, 72.0, 87.0], "value": 73.3, "unit": "score"}]}]} 01:03:22 INFO - Return code: 0 01:03:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:03:22 ERROR - # TBPL SUCCESS # 01:03:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:03:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:03:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:03:22 INFO - 2016-11-22 01:03:22.335 screenresolution[1659:10915] starting screenresolution argv=screenresolution get 01:03:22 INFO - 2016-11-22 01:03:22.338 screenresolution[1659:10915] Display 0: 1600x1200x32@60 01:03:22 INFO - 2016-11-22 01:03:22.344 screenresolution[1660:10918] starting screenresolution argv=screenresolution list 01:03:22 INFO - Available Modes on Display 0 01:03:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:03:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:03:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:03:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:03:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:03:22 INFO - Intel Iris: 01:03:22 INFO - Chipset Model: Intel Iris 01:03:22 INFO - Type: GPU 01:03:22 INFO - Bus: Built-In 01:03:22 INFO - VRAM (Dynamic, Max): 1536 MB 01:03:22 INFO - Vendor: Intel (0x8086) 01:03:22 INFO - Device ID: 0x0a2e 01:03:22 INFO - Revision ID: 0x0009 01:03:22 INFO - Displays: 01:03:22 INFO - Display: 01:03:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:03:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:03:22 INFO - Display Serial Number: 1600x1200 60 01:03:22 INFO - Main Display: Yes 01:03:22 INFO - Mirror: Off 01:03:22 INFO - Online: Yes 01:03:22 INFO - Rotation: Supported 01:03:22 INFO - Return code: 0 01:03:22 INFO - Running post-action listener: _resource_record_post_action 01:03:22 INFO - [mozharness: 2016-11-22 09:03:22.579598Z] Finished run-tests step (success) 01:03:22 INFO - Running post-run listener: _resource_record_post_run 01:03:22 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 38636032; Write bytes: 689050624; Read time: 241; Write time: 1956 01:03:22 INFO - TinderboxPrint: CPU usage
54.5% 01:03:22 INFO - TinderboxPrint: I/O read bytes / time
38,636,032 / 241 01:03:22 INFO - TinderboxPrint: I/O write bytes / time
689,050,624 / 1,956 01:03:22 INFO - TinderboxPrint: CPU idle
2,209.7 (45.5%) 01:03:22 INFO - TinderboxPrint: CPU system
981.4 (20.2%) 01:03:22 INFO - TinderboxPrint: CPU user
1,662.5 (34.3%) 01:03:22 INFO - TinderboxPrint: Swap in / out
421,695,488 / 0 01:03:22 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 384100864; Write bytes: 384804352; Read time: 29217; Write time: 817 01:03:22 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 35359232; Write bytes: 302738944; Read time: 218; Write time: 1133 01:03:23 INFO - Running post-run listener: _upload_blobber_files 01:03:23 INFO - Blob upload gear active. 01:03:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:03:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 01:03:23 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:03:23 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:03:23 INFO - (blobuploader) - INFO - Open directory for files ... 01:03:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:03:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:03:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:03:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:03:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:03:24 INFO - (blobuploader) - INFO - Done attempting. 01:03:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:03:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:03:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:03:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:03:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:03:25 INFO - (blobuploader) - INFO - Done attempting. 01:03:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:03:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:03:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:03:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:03:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:03:28 INFO - (blobuploader) - INFO - Done attempting. 01:03:28 INFO - (blobuploader) - INFO - Iteration through files over. 01:03:28 INFO - Return code: 0 01:03:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:03:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:03:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a2a89eb58756c05cad1f3385cbe569faa12f7900a48a051fde696c261c6e5e35cff88b20c7f41eba3fb121fce58f6915c8e27e6aef8d664b93c0ff45a53741e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7d0a111da25149bf283444b240b40c305784b443abbf966e38fce6deb1d9041dcec04011624767eeba322eb34792e6b7df29a73ab03c01e3940f6e519fb4d8e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bec198a012c4a3e00a34b34c375a5dfe493e6bb6984ac6ee2f38b63c58c71a3610b3c6aeb291542e39c783b6da502762b495a7d617b245d575fc7278090abb42"} 01:03:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:03:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:03:28 INFO - Contents: 01:03:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a2a89eb58756c05cad1f3385cbe569faa12f7900a48a051fde696c261c6e5e35cff88b20c7f41eba3fb121fce58f6915c8e27e6aef8d664b93c0ff45a53741e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7d0a111da25149bf283444b240b40c305784b443abbf966e38fce6deb1d9041dcec04011624767eeba322eb34792e6b7df29a73ab03c01e3940f6e519fb4d8e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bec198a012c4a3e00a34b34c375a5dfe493e6bb6984ac6ee2f38b63c58c71a3610b3c6aeb291542e39c783b6da502762b495a7d617b245d575fc7278090abb42"} 01:03:28 INFO - Running post-run listener: copy_logs_to_upload_dir 01:03:28 INFO - Copying logs to upload dir... 01:03:28 INFO - mkdir: /builds/slave/test/build/upload/logs 01:03:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.511420 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-22 01:03:28.698127) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 01:03:28.701205) ========= 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.7zZTIhR3oc/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.8uh3y90RaR/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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/Graphics/sha512/a2a89eb58756c05cad1f3385cbe569faa12f7900a48a051fde696c261c6e5e35cff88b20c7f41eba3fb121fce58f6915c8e27e6aef8d664b93c0ff45a53741e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7d0a111da25149bf283444b240b40c305784b443abbf966e38fce6deb1d9041dcec04011624767eeba322eb34792e6b7df29a73ab03c01e3940f6e519fb4d8e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bec198a012c4a3e00a34b34c375a5dfe493e6bb6984ac6ee2f38b63c58c71a3610b3c6aeb291542e39c783b6da502762b495a7d617b245d575fc7278090abb42"} build_url:https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011179 build_url: 'https://queue.taskcluster.net/v1/task/WAhYyxDPTTSvejqCAXAcTQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a2a89eb58756c05cad1f3385cbe569faa12f7900a48a051fde696c261c6e5e35cff88b20c7f41eba3fb121fce58f6915c8e27e6aef8d664b93c0ff45a53741e7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7d0a111da25149bf283444b240b40c305784b443abbf966e38fce6deb1d9041dcec04011624767eeba322eb34792e6b7df29a73ab03c01e3940f6e519fb4d8e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bec198a012c4a3e00a34b34c375a5dfe493e6bb6984ac6ee2f38b63c58c71a3610b3c6aeb291542e39c783b6da502762b495a7d617b245d575fc7278090abb42"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 01:03:28.735672) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 01:03:28.736000) ========= 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.7zZTIhR3oc/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.8uh3y90RaR/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.005297 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 01:03:28.784551) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 01:03:28.784881) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 01:03:28.785175) ========= ========= Total master_lag: 0.26 =========