builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0267 starttime: 1476238044.09 results: success (0) buildid: 20161011165047 builduid: 233846436164451f8be5262d4eab8e40 revision: fac9c1da5d81a2d0f7ff8fb721ddebc2fbb16b61 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-11 19:07:24.088993) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-11 19:07:24.089396) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-11 19:07:24.089664) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-11 19:07:24.303590) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 19:07:24.303939) ========= 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.MOpldjxdGp/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.zI3aJ9SPML/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-11 19:07:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 171M=0s 2016-10-11 19:07:24 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.175185 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 19:07:24.519409) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 19:07:24.519727) ========= 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.MOpldjxdGp/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.zI3aJ9SPML/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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.026132 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 19:07:24.597147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-11 19:07:24.597519) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev fac9c1da5d81a2d0f7ff8fb721ddebc2fbb16b61 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev fac9c1da5d81a2d0f7ff8fb721ddebc2fbb16b61 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MOpldjxdGp/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.zI3aJ9SPML/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-11 19:07:24,691 truncating revision to first 12 chars 2016-10-11 19:07:24,691 Setting DEBUG logging. 2016-10-11 19:07:24,691 attempt 1/10 2016-10-11 19:07:24,691 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/fac9c1da5d81?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-11 19:07:25,777 unpacking tar archive at: autoland-fac9c1da5d81/testing/mozharness/ program finished with exit code 0 elapsedTime=1.359828 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-11 19:07:26.001364) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-11 19:07:26.001643) ========= script_repo_revision: fac9c1da5d81a2d0f7ff8fb721ddebc2fbb16b61 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-11 19:07:26.002090) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-11 19:07:26.002351) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-11 19:07:26.159597) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-11 19:07:26.159922) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MOpldjxdGp/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.zI3aJ9SPML/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 19:07:26 INFO - MultiFileLogger online at 20161011 19:07:26 in /builds/slave/test 19:07:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 19:07:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:07:26 INFO - {'append_to_log': False, 19:07:26 INFO - 'base_work_dir': '/builds/slave/test', 19:07:26 INFO - 'blob_upload_branch': 'Autoland', 19:07:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:07:26 INFO - 'branch': 'Autoland', 19:07:26 INFO - 'buildbot_json_path': 'buildprops.json', 19:07:26 INFO - 'config_files': ('talos/mac_config.py',), 19:07:26 INFO - 'default_actions': ('clobber', 19:07:26 INFO - 'read-buildbot-config', 19:07:26 INFO - 'download-and-extract', 19:07:26 INFO - 'populate-webroot', 19:07:26 INFO - 'create-virtualenv', 19:07:26 INFO - 'install', 19:07:26 INFO - 'run-tests'), 19:07:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:07:26 INFO - 'download_minidump_stackwalk': True, 19:07:26 INFO - 'download_symbols': 'ondemand', 19:07:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:07:26 INFO - 'tooltool.py': '/tools/tooltool.py', 19:07:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:07:26 INFO - '/tools/misc-python/virtualenv.py')}, 19:07:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:07:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:07:26 INFO - 'installer_path': 'installer.exe', 19:07:26 INFO - 'log_level': 'info', 19:07:26 INFO - 'log_name': 'talos', 19:07:26 INFO - 'log_to_console': True, 19:07:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:07:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:07:26 INFO - 'opt_config_files': (), 19:07:26 INFO - 'pip_index': False, 19:07:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:07:26 INFO - 'cmd': ('bash', 19:07:26 INFO - '-c', 19:07:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:07:26 INFO - 'enabled': True, 19:07:26 INFO - 'halt_on_failure': False, 19:07:26 INFO - 'name': 'check_screen_resolution'},), 19:07:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:07:26 INFO - 'cmd': ('bash', 19:07:26 INFO - '-c', 19:07:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:07:26 INFO - 'enabled': True, 19:07:26 INFO - 'halt_on_failure': False, 19:07:26 INFO - 'name': 'check_screen_resolution'},), 19:07:26 INFO - 'run_cmd_checks_enabled': True, 19:07:26 INFO - 'sps_profile': False, 19:07:26 INFO - 'sps_profile_interval': 0, 19:07:26 INFO - 'suite': 'dromaeojs-e10s', 19:07:26 INFO - 'system_bits': '32', 19:07:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:07:26 INFO - 'title': 't-yosemite-r7-0267', 19:07:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:07:26 INFO - 'use_talos_json': True, 19:07:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:07:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:07:26 INFO - 'work_dir': 'build'} 19:07:26 INFO - [mozharness: 2016-10-12 02:07:26.370928Z] Running clobber step. 19:07:26 INFO - Running pre-action listener: _resource_record_pre_action 19:07:26 INFO - Running main action method: clobber 19:07:26 INFO - rmtree: /builds/slave/test/build 19:07:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:07:28 INFO - Running post-action listener: _resource_record_post_action 19:07:28 INFO - [mozharness: 2016-10-12 02:07:28.627998Z] Finished clobber step (success) 19:07:28 INFO - [mozharness: 2016-10-12 02:07:28.628249Z] Running read-buildbot-config step. 19:07:28 INFO - Running pre-action listener: _resource_record_pre_action 19:07:28 INFO - Running main action method: read_buildbot_config 19:07:28 INFO - Using buildbot properties: 19:07:28 INFO - { 19:07:28 INFO - "project": "", 19:07:28 INFO - "product": "firefox", 19:07:28 INFO - "script_repo_revision": "production", 19:07:28 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 19:07:28 INFO - "repository": "", 19:07:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 19:07:28 INFO - "buildid": "20161011165047", 19:07:28 INFO - "pgo_build": "False", 19:07:28 INFO - "basedir": "/builds/slave/test", 19:07:28 INFO - "buildnumber": 240, 19:07:28 INFO - "slavename": "t-yosemite-r7-0267", 19:07:28 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 19:07:28 INFO - "platform": "yosemite_r7", 19:07:28 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 19:07:28 INFO - "branch": "autoland", 19:07:28 INFO - "repo_path": "integration/autoland", 19:07:28 INFO - "revision": "fac9c1da5d81a2d0f7ff8fb721ddebc2fbb16b61", 19:07:28 INFO - "stage_platform": "macosx64", 19:07:28 INFO - "builduid": "233846436164451f8be5262d4eab8e40", 19:07:28 INFO - "slavebuilddir": "test" 19:07:28 INFO - } 19:07:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 19:07:28 INFO - Running post-action listener: _resource_record_post_action 19:07:28 INFO - [mozharness: 2016-10-12 02:07:28.633687Z] Finished read-buildbot-config step (success) 19:07:28 INFO - [mozharness: 2016-10-12 02:07:28.633889Z] Running download-and-extract step. 19:07:28 INFO - Running pre-action listener: _resource_record_pre_action 19:07:28 INFO - Running main action method: download_and_extract 19:07:28 INFO - mkdir: /builds/slave/test/build/tests 19:07:28 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')]} 19:07:28 INFO - trying https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 19:07:28 INFO - Downloading https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:07:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 19:07:29 INFO - Downloaded 1199 bytes. 19:07:29 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:07:29 INFO - Using the following test package requirements: 19:07:29 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:07:29 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:07:29 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 19:07:29 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:07:29 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 19:07:29 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:07:29 INFO - u'jsshell-mac.zip'], 19:07:29 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:07:29 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 19:07:29 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:07:29 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:07:29 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 19:07:29 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:07:29 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 19:07:29 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:07:29 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 19:07:29 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:07:29 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 19:07:29 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:07:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:07:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:07:29 INFO - Fetch https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:07:30 INFO - Http code: 200 19:07:30 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 19:07:30 INFO - x-amz-cf-id: vNKf0ZEtVHBqecUJcmsTy0wiC2UDU99kW7KjD6k8uTpYEcxkC0eXEg== 19:07:30 INFO - x-amz-version-id: PnCKa_87K6GAEiwsWe6bcMR9OlQMDvOR 19:07:30 INFO - Expected file size: 16508513 19:07:30 INFO - Obtained file size: 16508513 19:07:37 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:07:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:07:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:07:37 INFO - Fetch https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:07:37 INFO - Http code: 200 19:07:37 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 19:07:37 INFO - x-amz-cf-id: mKv-2oTKaCdWO2RkL4wIqBNbFSz9OKQVv_eFoobI5AC2rIN5YSzThA== 19:07:37 INFO - x-amz-version-id: PnCKa_87K6GAEiwsWe6bcMR9OlQMDvOR 19:07:38 INFO - Expected file size: 16508513 19:07:38 INFO - Obtained file size: 16508513 19:07:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 19:07:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:07:44 INFO - Fetch https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 19:07:45 INFO - Http code: 200 19:07:45 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 19:07:45 INFO - x-amz-cf-id: RuKtSfhmLiBscR_aA8-XKRGmkQ29SST8fcbLUcZdMA2IuhWBQwfxNg== 19:07:45 INFO - x-amz-version-id: XO67zzxeHNZ1B.yn1.2Sl10EeG5ECdp7 19:07:45 INFO - Expected file size: 14409006 19:07:45 INFO - Obtained file size: 14409006 19:07: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')]} 19:07:45 INFO - trying https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:07:45 INFO - Downloading https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 19:07:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 19:07:49 INFO - Downloaded 111706468 bytes. 19:07:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:07:49 INFO - mkdir: /builds/slave/test/properties 19:07:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:07:49 INFO - Writing to file /builds/slave/test/properties/build_url 19:07:49 INFO - Contents: 19:07:49 INFO - build_url:https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:07:50 INFO - Running post-action listener: _resource_record_post_action 19:07:50 INFO - Running post-action listener: set_extra_try_arguments 19:07:50 INFO - [mozharness: 2016-10-12 02:07:50.540542Z] Finished download-and-extract step (success) 19:07:50 INFO - [mozharness: 2016-10-12 02:07:50.540689Z] Running populate-webroot step. 19:07:50 INFO - Running pre-action listener: _resource_record_pre_action 19:07:50 INFO - Running main action method: populate_webroot 19:07:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:07:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:07:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:07:50 INFO - '--tppagecycles', 19:07:50 INFO - '7'], 19:07:50 INFO - 'tests': ['tsvgm']}}, 19:07:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:07:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:07:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:07:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:07:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:07:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:07:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:50 INFO - 'talos_options': ['--disable-e10s'], 19:07:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:07:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:07:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:50 INFO - 'talos_options': ['--disable-e10s'], 19:07:50 INFO - 'tests': ['damp', 'tps']}, 19:07:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:50 INFO - 'tests': ['damp', 'tps']}, 19:07:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:07:50 INFO - 'tests': ['dromaeo_dom']}, 19:07:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:07:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:07:50 INFO - 'tests': ['basic_compositor_video']}, 19:07:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:07:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:07:50 INFO - 'tests': ['a11yr', 19:07:50 INFO - 'ts_paint', 19:07:50 INFO - 'tpaint', 19:07:50 INFO - 'sessionrestore', 19:07:50 INFO - 'sessionrestore_no_auto_restore', 19:07:50 INFO - 'tabpaint']}, 19:07:50 INFO - 'other-e10s': {'tests': ['a11yr', 19:07:50 INFO - 'ts_paint', 19:07:50 INFO - 'tpaint', 19:07:50 INFO - 'sessionrestore', 19:07:50 INFO - 'sessionrestore_no_auto_restore', 19:07:50 INFO - 'tabpaint']}, 19:07:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:07:50 INFO - 'ts_paint', 19:07:50 INFO - 'tpaint', 19:07:50 INFO - 'sessionrestore', 19:07:50 INFO - 'sessionrestore_no_auto_restore', 19:07:50 INFO - 'tabpaint']}, 19:07:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:07:50 INFO - 'ts_paint', 19:07:50 INFO - 'tpaint', 19:07:50 INFO - 'sessionrestore', 19:07:50 INFO - 'sessionrestore_no_auto_restore', 19:07:50 INFO - 'tabpaint']}, 19:07:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:07:50 INFO - 'tests': ['a11yr', 19:07:50 INFO - 'ts_paint', 19:07:50 INFO - 'tpaint', 19:07:50 INFO - 'sessionrestore', 19:07:50 INFO - 'sessionrestore_no_auto_restore', 19:07:50 INFO - 'tabpaint']}, 19:07:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:07:50 INFO - 'tests': ['a11yr', 19:07:50 INFO - 'ts_paint', 19:07:50 INFO - 'tpaint', 19:07:50 INFO - 'sessionrestore', 19:07:50 INFO - 'sessionrestore_no_auto_restore', 19:07:50 INFO - 'tabpaint']}, 19:07:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:07:50 INFO - 'tests': ['tsvgx', 19:07:50 INFO - 'tsvgr_opacity', 19:07:50 INFO - 'tart', 19:07:50 INFO - 'tscrollx', 19:07:50 INFO - 'cart']}, 19:07:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:07:50 INFO - 'tsvgr_opacity', 19:07:50 INFO - 'tart', 19:07:50 INFO - 'tscrollx', 19:07:50 INFO - 'cart']}, 19:07:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:50 INFO - 'talos_options': ['--disable-e10s'], 19:07:50 INFO - 'tests': ['tp5o']}, 19:07:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:50 INFO - 'tests': ['tp5o']}, 19:07:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:50 INFO - 'talos_options': ['--disable-e10s', 19:07:50 INFO - '--xperf_path', 19:07:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:07:50 INFO - 'tests': ['tp5n']}, 19:07:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:50 INFO - 'talos_options': ['--xperf_path', 19:07:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:07:50 INFO - 'tests': ['tp5n']}}, 19:07:50 INFO - 'talos.zip': {'path': '', 19:07:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:07:50 INFO - Running post-action listener: _resource_record_post_action 19:07:50 INFO - [mozharness: 2016-10-12 02:07:50.559756Z] Finished populate-webroot step (success) 19:07:50 INFO - [mozharness: 2016-10-12 02:07:50.559888Z] Running create-virtualenv step. 19:07:50 INFO - Running pre-action listener: _resource_record_pre_action 19:07:50 INFO - Running main action method: create_virtualenv 19:07:50 INFO - Creating virtualenv /builds/slave/test/build/venv 19:07:50 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 19:07:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:07:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:07:50 INFO - Using real prefix '/tools/python27' 19:07:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:07:51 INFO - Installing distribute.............................................................................................................................................................................................done. 19:07:54 INFO - Installing pip.................done. 19:07:54 INFO - Return code: 0 19:07:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:07:54 INFO - Reading from file tmpfile_stdout 19:07:54 INFO - Output received: 19:07:54 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) 19:07:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f06c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf0596aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef0ad30>, '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 0x10ed30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02b20>, '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.MOpldjxdGp/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.zI3aJ9SPML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 19:07:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:07:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MOpldjxdGp/Render', 19:07:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:54 INFO - 'HOME': '/Users/cltbld', 19:07:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:54 INFO - 'LOGNAME': 'cltbld', 19:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:54 INFO - 'MOZ_NO_REMOTE': '1', 19:07:54 INFO - 'NO_EM_RESTART': '1', 19:07:54 INFO - 'PAGER': '/bin/cat', 19:07:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:54 INFO - 'PWD': '/builds/slave/test', 19:07:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:54 INFO - 'SHELL': '/bin/bash', 19:07:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zI3aJ9SPML/Listeners', 19:07:54 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 19:07:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:54 INFO - 'USER': 'cltbld', 19:07:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:54 INFO - 'XPC_FLAGS': '0x0', 19:07:54 INFO - 'XPC_SERVICE_NAME': '0', 19:07:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:54 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 19:07:54 INFO - Cleaning up... 19:07:54 INFO - Return code: 0 19:07:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f0f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f06c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf0596aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef0ad30>, '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 0x10ed30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02b20>, '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.MOpldjxdGp/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.zI3aJ9SPML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 19:07:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:07:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MOpldjxdGp/Render', 19:07:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:54 INFO - 'HOME': '/Users/cltbld', 19:07:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:54 INFO - 'LOGNAME': 'cltbld', 19:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:54 INFO - 'MOZ_NO_REMOTE': '1', 19:07:54 INFO - 'NO_EM_RESTART': '1', 19:07:54 INFO - 'PAGER': '/bin/cat', 19:07:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:54 INFO - 'PWD': '/builds/slave/test', 19:07:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:54 INFO - 'SHELL': '/bin/bash', 19:07:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zI3aJ9SPML/Listeners', 19:07:54 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 19:07:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:54 INFO - 'USER': 'cltbld', 19:07:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:54 INFO - 'XPC_FLAGS': '0x0', 19:07:54 INFO - 'XPC_SERVICE_NAME': '0', 19:07:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:55 INFO - Downloading/unpacking psutil>=3.1.1 19:07: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 19:07: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 19:07: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 19:07: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 19:07: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 19:07: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 19:07:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:07:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:07:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:07:59 INFO - Installing collected packages: psutil 19:07:59 INFO - Running setup.py install for psutil 19:07:59 INFO - building 'psutil._psutil_osx' extension 19:07:59 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 19:07:59 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 19:07:59 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 19:07:59 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 19:07:59 INFO - building 'psutil._psutil_posix' extension 19:07:59 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 19:08:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:08:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:08:00 INFO - ^ 19:08:00 INFO - 1 warning generated. 19:08:00 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 19:08:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:08:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:08:00 INFO - Successfully installed psutil 19:08:00 INFO - Cleaning up... 19:08:00 INFO - Return code: 0 19:08:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:08:00 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')]} 19:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:00 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')]} 19:08:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:00 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 0x10f0f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f06c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf0596aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef0ad30>, '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 0x10ed30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02b20>, '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.MOpldjxdGp/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.zI3aJ9SPML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 19:08:00 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 19:08:00 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 19:08:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MOpldjxdGp/Render', 19:08:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:00 INFO - 'HOME': '/Users/cltbld', 19:08:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:00 INFO - 'LOGNAME': 'cltbld', 19:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:00 INFO - 'MOZ_NO_REMOTE': '1', 19:08:00 INFO - 'NO_EM_RESTART': '1', 19:08:00 INFO - 'PAGER': '/bin/cat', 19:08:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:00 INFO - 'PWD': '/builds/slave/test', 19:08:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:00 INFO - 'SHELL': '/bin/bash', 19:08:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zI3aJ9SPML/Listeners', 19:08:00 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 19:08:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:00 INFO - 'USER': 'cltbld', 19:08:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:00 INFO - 'XPC_FLAGS': '0x0', 19:08:00 INFO - 'XPC_SERVICE_NAME': '0', 19:08:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:08:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:08:03 INFO - Installing collected packages: mozsystemmonitor 19:08:03 INFO - Running setup.py install for mozsystemmonitor 19:08:03 INFO - Successfully installed mozsystemmonitor 19:08:03 INFO - Cleaning up... 19:08:03 INFO - Return code: 0 19:08:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:03 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 0x10f0f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f06c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf0596aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef0ad30>, '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 0x10ed30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02b20>, '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.MOpldjxdGp/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.zI3aJ9SPML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 19:08:03 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 19:08:03 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 19:08:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MOpldjxdGp/Render', 19:08:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:03 INFO - 'HOME': '/Users/cltbld', 19:08:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:03 INFO - 'LOGNAME': 'cltbld', 19:08:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:03 INFO - 'MOZ_NO_REMOTE': '1', 19:08:03 INFO - 'NO_EM_RESTART': '1', 19:08:03 INFO - 'PAGER': '/bin/cat', 19:08:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:03 INFO - 'PWD': '/builds/slave/test', 19:08:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:03 INFO - 'SHELL': '/bin/bash', 19:08:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zI3aJ9SPML/Listeners', 19:08:03 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 19:08:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:03 INFO - 'USER': 'cltbld', 19:08:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:03 INFO - 'XPC_FLAGS': '0x0', 19:08:03 INFO - 'XPC_SERVICE_NAME': '0', 19:08:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:03 INFO - Downloading/unpacking jsonschema==2.5.1 19:08: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 19:08: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 19:08: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 19:08: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 19:08: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 19:08: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 19:08:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:08:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:08:06 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 19:08:06 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 19:08:06 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 19:08:06 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 19:08:06 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 19:08:06 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 19:08:06 INFO - Downloading functools32-3.2.3-2.tar.gz 19:08:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:08:06 INFO - warning: no files found matching '*.txt' 19:08:06 INFO - no previously-included directories found matching 'build' 19:08:06 INFO - no previously-included directories found matching 'dist' 19:08:06 INFO - no previously-included directories found matching '.git*' 19:08:06 INFO - Installing collected packages: jsonschema, functools32 19:08:06 INFO - Running setup.py install for functools32 19:08:06 INFO - warning: no files found matching '*.txt' 19:08:06 INFO - no previously-included directories found matching 'build' 19:08:06 INFO - no previously-included directories found matching 'dist' 19:08:06 INFO - no previously-included directories found matching '.git*' 19:08:06 INFO - Successfully installed jsonschema functools32 19:08:06 INFO - Cleaning up... 19:08:06 INFO - Return code: 0 19:08:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:08:06 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')]} 19:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:06 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')]} 19:08:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:06 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 0x10f0f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f06c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf0596aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef0ad30>, '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 0x10ed30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02b20>, '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.MOpldjxdGp/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.zI3aJ9SPML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 19:08:06 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 19:08:06 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 19:08:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MOpldjxdGp/Render', 19:08:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:06 INFO - 'HOME': '/Users/cltbld', 19:08:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:06 INFO - 'LOGNAME': 'cltbld', 19:08:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:06 INFO - 'MOZ_NO_REMOTE': '1', 19:08:06 INFO - 'NO_EM_RESTART': '1', 19:08:06 INFO - 'PAGER': '/bin/cat', 19:08:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:06 INFO - 'PWD': '/builds/slave/test', 19:08:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:06 INFO - 'SHELL': '/bin/bash', 19:08:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zI3aJ9SPML/Listeners', 19:08:06 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 19:08:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:06 INFO - 'USER': 'cltbld', 19:08:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:06 INFO - 'XPC_FLAGS': '0x0', 19:08:06 INFO - 'XPC_SERVICE_NAME': '0', 19:08:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:08:07 INFO - Cleaning up... 19:08:07 INFO - Return code: 0 19:08:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:08: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')]} 19:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08: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')]} 19:08:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08: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', '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 0x10f0f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f06c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf0596aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef0ad30>, '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 0x10ed30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02b20>, '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.MOpldjxdGp/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.zI3aJ9SPML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 19:08: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:08: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 blobuploader==1.2.4 19:08:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MOpldjxdGp/Render', 19:08:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:07 INFO - 'HOME': '/Users/cltbld', 19:08:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:07 INFO - 'LOGNAME': 'cltbld', 19:08:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:07 INFO - 'MOZ_NO_REMOTE': '1', 19:08:07 INFO - 'NO_EM_RESTART': '1', 19:08:07 INFO - 'PAGER': '/bin/cat', 19:08:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:07 INFO - 'PWD': '/builds/slave/test', 19:08:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:07 INFO - 'SHELL': '/bin/bash', 19:08:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zI3aJ9SPML/Listeners', 19:08:07 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 19:08:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:07 INFO - 'USER': 'cltbld', 19:08:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:07 INFO - 'XPC_FLAGS': '0x0', 19:08:07 INFO - 'XPC_SERVICE_NAME': '0', 19:08:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:07 INFO - Downloading/unpacking blobuploader==1.2.4 19:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:10 INFO - Downloading blobuploader-1.2.4.tar.gz 19:08:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:08:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:08:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:11 INFO - Downloading docopt-0.6.1.tar.gz 19:08:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:08:11 INFO - Installing collected packages: blobuploader, requests, docopt 19:08:11 INFO - Running setup.py install for blobuploader 19:08:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:08:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:08:11 INFO - Running setup.py install for requests 19:08:11 INFO - Running setup.py install for docopt 19:08:12 INFO - Successfully installed blobuploader requests docopt 19:08:12 INFO - Cleaning up... 19:08:12 INFO - Return code: 0 19:08:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:12 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 0x10f0f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f06c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf0596aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef0ad30>, '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 0x10ed30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02b20>, '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.MOpldjxdGp/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.zI3aJ9SPML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 19:08:12 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 19:08:12 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 19:08:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MOpldjxdGp/Render', 19:08:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:12 INFO - 'HOME': '/Users/cltbld', 19:08:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:12 INFO - 'LOGNAME': 'cltbld', 19:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:12 INFO - 'MOZ_NO_REMOTE': '1', 19:08:12 INFO - 'NO_EM_RESTART': '1', 19:08:12 INFO - 'PAGER': '/bin/cat', 19:08:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:12 INFO - 'PWD': '/builds/slave/test', 19:08:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:12 INFO - 'SHELL': '/bin/bash', 19:08:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zI3aJ9SPML/Listeners', 19:08:12 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 19:08:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:12 INFO - 'USER': 'cltbld', 19:08:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:12 INFO - 'XPC_FLAGS': '0x0', 19:08:12 INFO - 'XPC_SERVICE_NAME': '0', 19:08:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:08:12 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-xteDN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:08:12 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-boL1_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:08:12 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-vo7TLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:08:12 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-dGE0D6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:08:12 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-Q1leve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:08:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:08:12 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-VjZkpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:08:13 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-j9QWbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:08:13 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-UOgS_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:08:13 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-S1Wwjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:08:13 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-8bzPUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:08:13 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-PTWwx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:08:13 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-ET7WS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:08:13 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-bhTGA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:08:13 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-U4esJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:13 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-qVGIDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:08:13 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-TxsEft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:08:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:08:14 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-gOqhvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:08:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:08:14 INFO - Running setup.py install for manifestparser 19:08:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:08:14 INFO - Running setup.py install for mozcrash 19:08:14 INFO - Running setup.py install for mozdebug 19:08:14 INFO - Running setup.py install for mozdevice 19:08:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:08:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:08:14 INFO - Running setup.py install for mozfile 19:08:14 INFO - Running setup.py install for mozhttpd 19:08:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:08:15 INFO - Running setup.py install for mozinfo 19:08:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:08:15 INFO - Running setup.py install for mozInstall 19:08:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:08:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:08:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:08:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:08:15 INFO - Running setup.py install for mozleak 19:08:15 INFO - Running setup.py install for mozlog 19:08:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:08:15 INFO - Running setup.py install for moznetwork 19:08:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:08:15 INFO - Running setup.py install for mozprocess 19:08:15 INFO - Running setup.py install for mozprofile 19:08:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:08:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:08:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:08:16 INFO - Running setup.py install for mozrunner 19:08:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:08:16 INFO - Running setup.py install for mozscreenshot 19:08:16 INFO - Running setup.py install for moztest 19:08:16 INFO - Running setup.py install for mozversion 19:08:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:08:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:08:16 INFO - Cleaning up... 19:08:16 INFO - Return code: 0 19:08:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:16 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 0x10f0f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f06c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf0596aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef0ad30>, '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 0x10ed30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02b20>, '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.MOpldjxdGp/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.zI3aJ9SPML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 19:08:16 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 19:08:16 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 19:08:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MOpldjxdGp/Render', 19:08:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:16 INFO - 'HOME': '/Users/cltbld', 19:08:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:16 INFO - 'LOGNAME': 'cltbld', 19:08:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:16 INFO - 'MOZ_NO_REMOTE': '1', 19:08:16 INFO - 'NO_EM_RESTART': '1', 19:08:16 INFO - 'PAGER': '/bin/cat', 19:08:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:16 INFO - 'PWD': '/builds/slave/test', 19:08:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:16 INFO - 'SHELL': '/bin/bash', 19:08:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zI3aJ9SPML/Listeners', 19:08:16 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 19:08:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:16 INFO - 'USER': 'cltbld', 19:08:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:16 INFO - 'XPC_FLAGS': '0x0', 19:08:16 INFO - 'XPC_SERVICE_NAME': '0', 19:08:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:08:17 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-JIQuDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:08:17 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)) 19:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:08:17 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-GrzPsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:08:17 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-wmvvv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:08:17 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)) 19:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:08:17 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-Lcz78H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:08:17 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-mOb6Db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:08:17 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)) 19:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:08:17 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-KO8UVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:08:17 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)) 19:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:08:17 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-oE_sEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:08:17 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)) 19:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:08:17 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-PUzyrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:08:17 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)) 19:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:08:17 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-a1GNDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:08:17 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)) 19:08:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:08:17 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-SiQwNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:08:18 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)) 19:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:08:18 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-EqwGUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:08:18 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)) 19:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:08:18 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-__jXY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:08:18 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)) 19:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:08:18 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-ccB9uT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:08:18 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)) 19:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:08:18 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-RETsKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:08:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:18 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-90rckM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:08:18 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)) 19:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:08:18 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-4xNo9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:08:18 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)) 19:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:08:18 INFO - Running setup.py (path:/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/pip-DuvK0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:08:18 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)) 19:08:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:08:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:08:18 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)) 19:08:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:08:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:08:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:21 INFO - Downloading blessings-1.6.tar.gz 19:08:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:08:21 INFO - Installing collected packages: blessings 19:08:21 INFO - Running setup.py install for blessings 19:08:21 INFO - Successfully installed blessings 19:08:21 INFO - Cleaning up... 19:08:21 INFO - Return code: 0 19:08:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:08:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:08:21 INFO - Reading from file tmpfile_stdout 19:08:21 INFO - Current package versions: 19:08:21 INFO - blessings == 1.6 19:08:21 INFO - blobuploader == 1.2.4 19:08:21 INFO - docopt == 0.6.1 19:08:21 INFO - functools32 == 3.2.3-2 19:08:21 INFO - jsonschema == 2.5.1 19:08:21 INFO - manifestparser == 1.1 19:08:21 INFO - mozInstall == 1.12 19:08:21 INFO - mozcrash == 0.17 19:08:21 INFO - mozdebug == 0.1 19:08:21 INFO - mozdevice == 0.48 19:08:21 INFO - mozfile == 1.2 19:08:21 INFO - mozhttpd == 0.7 19:08:21 INFO - mozinfo == 0.9 19:08:21 INFO - mozleak == 0.1 19:08:21 INFO - mozlog == 3.4 19:08:21 INFO - moznetwork == 0.27 19:08:21 INFO - mozprocess == 0.23 19:08:21 INFO - mozprofile == 0.28 19:08:21 INFO - mozrunner == 6.12 19:08:21 INFO - mozscreenshot == 0.1 19:08:21 INFO - mozsystemmonitor == 0.3 19:08:21 INFO - moztest == 0.7 19:08:21 INFO - mozversion == 1.4 19:08:21 INFO - psutil == 3.1.1 19:08:21 INFO - requests == 1.2.3 19:08:21 INFO - wsgiref == 0.1.2 19:08:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:21 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 0x10f0f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f06c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf0596aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef0ad30>, '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 0x10ed30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02b20>, '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.MOpldjxdGp/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.zI3aJ9SPML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 19:08:21 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 19:08:21 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 19:08:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MOpldjxdGp/Render', 19:08:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:21 INFO - 'HOME': '/Users/cltbld', 19:08:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:21 INFO - 'LOGNAME': 'cltbld', 19:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:21 INFO - 'MOZ_NO_REMOTE': '1', 19:08:21 INFO - 'NO_EM_RESTART': '1', 19:08:21 INFO - 'PAGER': '/bin/cat', 19:08:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:21 INFO - 'PWD': '/builds/slave/test', 19:08:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:21 INFO - 'SHELL': '/bin/bash', 19:08:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zI3aJ9SPML/Listeners', 19:08:21 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 19:08:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:21 INFO - 'USER': 'cltbld', 19:08:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:21 INFO - 'XPC_FLAGS': '0x0', 19:08:21 INFO - 'XPC_SERVICE_NAME': '0', 19:08:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:22 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)) 19:08:22 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)) 19:08:22 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)) 19:08:22 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)) 19:08:22 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)) 19:08:22 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)) 19:08:22 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)) 19:08:22 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)) 19:08:22 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)) 19:08:22 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)) 19:08:22 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)) 19:08:22 INFO - Cleaning up... 19:08:22 INFO - Return code: 0 19:08:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:08:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:22 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 0x10f0f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f06c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf0596aa0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef0ad30>, '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 0x10ed30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02b20>, '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.MOpldjxdGp/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.zI3aJ9SPML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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 19:08:22 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 19:08:22 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 19:08:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MOpldjxdGp/Render', 19:08:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:22 INFO - 'HOME': '/Users/cltbld', 19:08:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:22 INFO - 'LOGNAME': 'cltbld', 19:08:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:22 INFO - 'MOZ_NO_REMOTE': '1', 19:08:22 INFO - 'NO_EM_RESTART': '1', 19:08:22 INFO - 'PAGER': '/bin/cat', 19:08:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:22 INFO - 'PWD': '/builds/slave/test', 19:08:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:22 INFO - 'SHELL': '/bin/bash', 19:08:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zI3aJ9SPML/Listeners', 19:08:22 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 19:08:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:22 INFO - 'USER': 'cltbld', 19:08:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:22 INFO - 'XPC_FLAGS': '0x0', 19:08:22 INFO - 'XPC_SERVICE_NAME': '0', 19:08:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:08:22 INFO - Cleaning up... 19:08:22 INFO - Return code: 0 19:08:22 INFO - Running post-action listener: _resource_record_post_action 19:08:22 INFO - Running post-action listener: _start_resource_monitoring 19:08:22 INFO - Starting resource monitoring. 19:08:22 INFO - [mozharness: 2016-10-12 02:08:22.513772Z] Finished create-virtualenv step (success) 19:08:22 INFO - [mozharness: 2016-10-12 02:08:22.514775Z] Running install step. 19:08:22 INFO - Running pre-action listener: _resource_record_pre_action 19:08:22 INFO - Running main action method: install 19:08:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:08:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:08:22 INFO - Reading from file tmpfile_stdout 19:08:22 INFO - Detecting whether we're running mozinstall >=1.0... 19:08:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:08:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:08:22 INFO - Reading from file tmpfile_stdout 19:08:22 INFO - Output received: 19:08:22 INFO - Usage: mozinstall [options] installer 19:08:22 INFO - Options: 19:08:22 INFO - -h, --help show this help message and exit 19:08:22 INFO - -d DEST, --destination=DEST 19:08:22 INFO - Directory to install application into. [default: 19:08:22 INFO - "/builds/slave/test"] 19:08:22 INFO - --app=APP Application being installed. [default: firefox] 19:08:22 INFO - mkdir: /builds/slave/test/build/application 19:08:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:08:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:08:56 INFO - Reading from file tmpfile_stdout 19:08:56 INFO - Output received: 19:08:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:08:56 INFO - Running post-action listener: _resource_record_post_action 19:08:56 INFO - [mozharness: 2016-10-12 02:08:56.992145Z] Finished install step (success) 19:08:56 INFO - [mozharness: 2016-10-12 02:08:56.992288Z] Running run-tests step. 19:08:56 INFO - Running pre-action listener: _resource_record_pre_action 19:08:56 INFO - Running main action method: run_tests 19:08:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:08:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:08:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:08:57 INFO - 2016-10-11 19:08:57.006 screenresolution[1655:9362] starting screenresolution argv=screenresolution get 19:08:57 INFO - 2016-10-11 19:08:57.009 screenresolution[1655:9362] Display 0: 1600x1200x32@60 19:08:57 INFO - 2016-10-11 19:08:57.015 screenresolution[1656:9364] starting screenresolution argv=screenresolution list 19:08:57 INFO - Available Modes on Display 0 19:08:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:08:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:08:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:08:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:08:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:08:57 INFO - Intel Iris: 19:08:57 INFO - Chipset Model: Intel Iris 19:08:57 INFO - Type: GPU 19:08:57 INFO - Bus: Built-In 19:08:57 INFO - VRAM (Dynamic, Max): 1536 MB 19:08:57 INFO - Vendor: Intel (0x8086) 19:08:57 INFO - Device ID: 0x0a2e 19:08:57 INFO - Revision ID: 0x0009 19:08:57 INFO - Displays: 19:08:57 INFO - Display: 19:08:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:08:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:08:57 INFO - Display Serial Number: 1600x1200 60 19:08:57 INFO - Main Display: Yes 19:08:57 INFO - Mirror: Off 19:08:57 INFO - Online: Yes 19:08:57 INFO - Rotation: Supported 19:08:57 INFO - Return code: 0 19:08:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:08:57 INFO - Python 2.7.3 19:08:57 INFO - Return code: 0 19:08:57 INFO - grabbing minidump binary from tooltool 19:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:57 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 0x10ef0ad30>, '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 0x10ed30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef02b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:08:57 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 19:08:57 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 19:08:57 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 19:08:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:08:57 INFO - Return code: 0 19:08:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:08:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:08:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:08:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:08:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:08:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0267', '--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 19:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0267 --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 19:08:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MOpldjxdGp/Render', 19:08:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:57 INFO - 'HOME': '/Users/cltbld', 19:08:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:57 INFO - 'LOGNAME': 'cltbld', 19:08:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:08:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:57 INFO - 'MOZ_NO_REMOTE': '1', 19:08:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:08:57 INFO - 'NO_EM_RESTART': '1', 19:08:57 INFO - 'PAGER': '/bin/cat', 19:08:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:57 INFO - 'PWD': '/builds/slave/test', 19:08:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:08:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:57 INFO - 'SHELL': '/bin/bash', 19:08:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zI3aJ9SPML/Listeners', 19:08:57 INFO - 'TMPDIR': '/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/', 19:08:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:57 INFO - 'USER': 'cltbld', 19:08:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:57 INFO - 'XPC_FLAGS': '0x0', 19:08:57 INFO - 'XPC_SERVICE_NAME': '0', 19:08:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0267', '--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 19:08:57 INFO - mozversion application_buildid: 20161011165047 19:08:57 INFO - mozversion application_changeset: fac9c1da5d81a2d0f7ff8fb721ddebc2fbb16b61 19:08:57 INFO - mozversion application_display_name: Nightly 19:08:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:08:57 INFO - mozversion application_name: Firefox 19:08:57 INFO - mozversion application_remotingname: firefox 19:08:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 19:08:57 INFO - mozversion application_vendor: Mozilla 19:08:57 INFO - mozversion application_version: 52.0a1 19:08:57 INFO - mozversion platform_buildid: 20161011165047 19:08:57 INFO - mozversion platform_changeset: fac9c1da5d81a2d0f7ff8fb721ddebc2fbb16b61 19:08:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 19:08:57 INFO - mozversion platform_version: 52.0a1 19:08:57 INFO - using testdate: 1476238137 19:08:57 INFO - actual date: 1476238137 19:08:57 INFO - starting webserver on 'localhost:49268' 19:08:57 INFO - SUITE-START | Running 2 tests 19:08:57 INFO - TEST-START | dromaeo_css 19:08:57 INFO - operating with platform_type : mac_ 19:08:57 INFO - Initialising browser for dromaeo_css test... 19:08:57 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmpNRYv7z/profile http://localhost:49268/getInfo.html) 19:09:00 INFO - PROCESS | 1666 | 2016-10-11 19:09:00.609 plugin-container[1668:9537] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d4b, name = 'com.apple.tsm.portname' 19:09:00 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:09:00 INFO - PROCESS | 1666 | 2016-10-11 19:09:00.612 plugin-container[1668:9537] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 19:09:00 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:09:00 INFO - PROCESS | 1666 | 2016-10-11 19:09:00.612 plugin-container[1668:9537] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:09:00 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 19:09:00 INFO - PROCESS | 1666 | colorDepth:24 19:09:00 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 19:09:00 INFO - PROCESS | 1666 | __metrics 19:09:00 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 19:09:01 INFO - TEST-INFO | 1666: exit 0 19:09:01 INFO - Browser initialized. 19:09:01 INFO - Running cycle 1/1 for dromaeo_css test... 19:09:01 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmpNRYv7z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:09:03 INFO - PROCESS | 1670 | 2016-10-11 19:09:03.830 plugin-container[1671:9661] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 19:09:03 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:09:03 INFO - PROCESS | 1670 | 2016-10-11 19:09:03.831 plugin-container[1671:9661] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 19:09:03 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:09:03 INFO - PROCESS | 1670 | 2016-10-11 19:09:03.831 plugin-container[1671:9661] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:09:13 INFO - PROCESS | 1670 | RSS: Main: 238686208 19:09:13 INFO - PROCESS | 1670 | 19:10:03 INFO - PROCESS | 1670 | 1476238203188 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] 19:12:15 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 19:12:17 INFO - PROCESS | 1670 | RSS: Main: 254582784 19:12:17 INFO - PROCESS | 1670 | 19:15:19 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 19:15:20 INFO - PROCESS | 1670 | RSS: Main: 263897088 19:15:20 INFO - PROCESS | 1670 | 19:18:22 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 19:18:23 INFO - PROCESS | 1670 | RSS: Main: 269262848 19:18:23 INFO - PROCESS | 1670 | 19:21:24 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 19:21:25 INFO - PROCESS | 1670 | RSS: Main: 270102528 19:21:25 INFO - PROCESS | 1670 | 19:24:28 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 19:24:29 INFO - PROCESS | 1670 | RSS: Main: 275308544 19:24:29 INFO - PROCESS | 1670 | 19:27:31 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 19:27:32 INFO - PROCESS | 1670 | RSS: Main: 278056960 19:27:32 INFO - PROCESS | 1670 | 19:27:32 INFO - PROCESS | 1670 | __start_tp_report 19:27:32 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 19:27:32 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 19:27:32 INFO - PROCESS | 1670 | |i|pagename|runs| 19:27:32 INFO - PROCESS | 1670 | |0;dojo.html;2789.4421115776845;2814.2964258935267;3020.48651729206;3371.342588195303;3428.9999999999986;5710.257666503359;5723.971380143099;5769.596128271022;5779.855503612412;5810.767569297233;4911.435184953732;5721.628094173876;5823.359430462657;5867.589268751189;5974.611650242731;4440.644748420119;5219.243209734586;5329.467053294659;5334.573234141279;5340.412554619008;5218.139007063849;5372.059889519338;5397.541208997221;5404.594655400841;5474.562035037207;4753;5196.428392876781;5337.599680023995;5354.250404943311;5365.168398898177;5179.300794392747;5191.091558977164;5216.634835561512;5238;5346.331708536433;5167.121589329823;5220.556252718524;5242.449542797989;5269.288646032813;5272.525472707436;4934.950650493521;5628.324601047901;5734.512566431858;5793.13103034544;5826.417358264182;6046.697665116727;6059.484943779784;6120.294761999575;6145.310039739063;6170.228721409824;6191.938080619181;6194.752209911598;6201.193844800147;6203;6243.094751261087;4824.34871292378;5927.081302398135;5965.105234214902;5977.820665380045;5997.790077347316;6112.1387513176505;6130.693465326716;6142.253225532421;6152.384761523812;6292.905606415908;5306.071437498423;6069.514438844882;6155.969220153871;6180.351063138306;6192.845178870474;6184.9485587450245;6187.969060154761;6190.845228869314;6190.907136392958;6194.907076393858;5615.213870058195;5621.915671264935;6105.297890742532;6132.141360525245;6144.41628045335;5433.293671822637;6115.571909966259;6135.263768347738;6135.601185922901;6157.476614487819;4955.4548999610015;4964.950350496449;4968.8757781055765;4994.425641051253;5028.874278143003;4338.806828122291;4348.152110338454;4413.095315460387;4413.271810151289;4442.844500442534;5816.94183058164;5853.03424934881;5860.619059761102;5872.29532456108;5903.35063143054;6140.2017737694705;6203.379662033761;6210.720517576629;6251.249850017936;6255.499560035186;5583.637063590853;5692.6299790513485;5755.6834374108985;5820.854478638068;5878.353381128072;5418.593605479605;5470.9452905471235;5472.863178420572;5530.640508366943;5533.142362933751;5346.251524786455;6321.8735625288155;6333.556651034568;6348.650824204713;6367.044943258548;6058.727357269022;6161.045038019114;6190.783322583688;6221.09794079865;6233.96883015582;6219.782307619212;6221.657808820558;6278.968605156945;6288.811335659938;6349.07938348946;5940.732667030081;6147.631142131487;6164.537659675632;6173.660448675366;6179.81460556184;5353.97323013398;6190.50475961931;6197.566170368031;6203.627782333075;6209;5947.553933454921;6069.393060693896;6093.177421048102;6127.234095738033;6146.508279337731;5151.5363617274625;6103.9025602783995;6127.353222718542;6137.9286238596105;6152.415520525543;5809.186713859978;5959.255093113361;5971.0744834550715;5997.760089596367;6018.518518518596;6091;6095.207623008981;6103.034636161374;6116.518381401602;6120.873812997581;5974.22335096452;6033.607815491979;6037.45662890342;6052.213212282375;6116.14373987651 19:27:32 INFO - PROCESS | 1670 | |1;ext.html;490.0468588595182;496.47869736776374;528.5137673340527;533.362631655172;541.063959350324;39557;41110.58889411105;41219.793901030454;41221;41377.758667239956;42278;42698;42761.57238427622;42789;42811;9436.622535098588;9601.567929443192;9602.855957160647;9606.759831004247;9607.2314214863;9474.763130921714;9560;9568.234541236685;9578.425294482318;9615.278854085936;9554.522273886278;9577.090176433228;9588.760280992961;9598.904010959906;9621.18219951305;9515.524223788818;9532.332736708364;9548.37935534188;9563.999999999965;9574.377665451793;9515.809683806285;9568.90431095687;9597.664081757104;9609.51952402384;9615.038496150399;40096.79709608716;40123;40412;40444.393334100016;40588.59411405907;3359.4960755886814;3443.8622455101768;3492.1618811485314;3496.580410350749;3819.1597848473293;29962.85018574915;30465;30566.84716576425;31753.206169845707;31755.364892702248;3198.296374797541;3249.675032496755;3265.575475188234;3279.622843372995;3347.815870127142;480479;481195.9999999965;481554;483527;484292.73560896615;60940.69529652412;60944;61242.0813687795;61309;61334.38665613376;63114.36885631177;63159.368406316265;63606;63663.40841479;64079.35920640827;15955.680886382208;16059.839401606068;16179.676406471805;16185.352585896668;16190.514284571245;12338.136330456782;12482.126251162514;12528.997680185563;12545.811812822814;12552.497900084078;16351.83648163527;17353.30586776515;17608.9119554404;17686.469405917585;17714.202860871294;3208.6149662040702;3290.4735242361107;6209.379062093754;7143.4999550031;7163.140423149255;878.6704985630389;947.8625649220825;987.7283746969641;991.2813210422386;1077.7251800790748;28480.71519284822;29603.703962960524;29887.551686724717;30067.699323006927;30582.541261881088;962.3071388600197;1068.0974576482859;1080.6703955293553;1088.1947358954476;1189.7798907202023;14613.78079328811;15086.547403577692;15284.770728439082;15816.52550423489;15893.524749513936;632979;641845;645520;647789.9378552781;651046;57113.42886571081;57565.712171439714;57809;57913.420865791646;57943.4205657938;1293.1142167615214;1344.0390121063488;1351.9526816561176;1359;1367.541873472378;29858.552121718625;29979;30025;30147.397052059274;30324;41976.790116049226;50738;51194.48805512046;51259.74370128274;51485.7425712869;441317;443248;465076;490323;492249;23631.172908948793;26542.601860971714;26739;27027.459450811268;27130.593041104003;45518.77240613776;46022;46464.767676161406;46946.06107877755;47647.76176119097;37659.81170094132;39129.2174156521;39182;39231;40463.393049103695;29733.405331893675;35136.4188611508;37781.81109094437;38334.14150099279;38431.231375372896;37459.43810842894;38279.808600956814;38316.425253621775;38412.42381364226;38449.80775096107;31890.68109318877;32429.675703242665;34176.316473670886;34551.790687325825;34740.305193896485;22370.414065116285;24406.87796561006;25442.109526166496;25563.744362556135;26512.554086628683 19:27:32 INFO - PROCESS | 1670 | |2;jquery.html;359.4338916206974;374.16187739463595;385.7936004237732;389.8810862686879;407.9861544063005;18386.0806959652;20309.49226269344;21412.464688382777;21530;21592.460188495297;20204.8989755051;20450.795492045076;20679.759214447145;20733.792662073374;20944.371668849923;5668.943310566893;5740.425957404268;5945.464908158287;6036;6121.846953826168;3877.205172939555;4079.77561234132;6065.514758819306;6094.756209751605;6104.877902441948;5769.7115144242825;6043.153958445821;6077.908831367533;6102.755889764427;6121.693915304239;5896.616719913192;6054.303755068157;6068.271807383098;6079.665618390986;6095.359987201325;5346.796970681605;5555.333359996826;5943.524518038546;5959.702014899238;5963.850903727398;12936.547220847318;13088.607341779762;13208.801867971988;13310.600681979557;13387.933060334635;2280.2817112609473;2314.6412306092584;2338.9298321050396;2382.833201675883;2812.5921741347593;9459.24326053914;10319.793604127875;10425.739356516073;12750.553730619384;14797.112173269637;1584.8905765963757;1808.3670715249689;1894.0813705352894;1902.1915685833465;1957.765068191812;123479.3826030873;129429;132087.33956330252;132830.33584832013;133132;8418.494890306478;9741.512924353754;10006.049425304584;10906.454677266105;11462.426878656033;11979.76040479203;14618.926905365617;14643.12141271527;14677.119372837451;14681.853181468263;8209.548474833822;8489.575521223915;8539.743807685654;8591.6992905248;8614.22471977525;6300.8424789379715;8278.544680042656;8285.25432711059;8342.499450033018;8439.113893041142;4512.661550383668;4514.7291162530355;4524.6380289576755;4538.614217791524;4662.880908581916;1586.5161125856725;1880.3230836898706;1925.8844469331605;1941.417574727576;1949.8927558984392;794.1423262876195;847.9533625650525;867.626920424211;961.6778379242892;1002.4436437777103;16021.95857269297;17135.828641713422;18442.35451759182;21484.78515214859;21506.784932150476;868.3660927522943;888.395890794266;912.2519533982204;917.8760867282831;950.7195377363694;10803.621873234399;11366.261193022592;11372.31766094037;11407.25852819564;11583;570085;578424;584895.5286565035;595688;600067;95231;98096;98914;127491;131197;924.58393722825;951.5004622573066;952.1716106986827;954.0650162840594;955.9904400955901;14154.735242361585;15886.285117169524;16908.07005614703;18456.81543184551;19017.239310427427;54341.913161737335;54740.726296368266;55621.44378556163;58631.4136858643;59492.810143797746;70978.29021709716;76242;79817.2018279833;79829;87878.56060719656;7881.842363152819;8140.226678465537;8216.589170541329;8271.669133234604;8315.750527484186;12142.817857732318;13121.60635180908;13135;13237.139585926883;13289.601311960656;11225.968988373257;11412.82880756806;11578.594749183738;11675.299482031105;11679.532818687157;10419.42693151884;10614.097801686634;10867.673969780919;11035.172362073037;11474.483648235682;11359.886401135884;11664.066874649838;11687.298762074302;11697.2981621103;11789.941050294694;7663.850422436679;7953.880691789744;8238.176182381714;8256.380771442045;8396.874046889423;8896.822063558822;11028.944855275673;11213.831792522955;11268.04221641137;11438.42807859587 19:27:32 INFO - PROCESS | 1670 | |3;mootools.html;1448.7971683964247;1451.9128852268864;1636.9441710096987;1771.6899542580045;1785.589314457674;7866.252705992936;8117.472364296316;8368.581570921453;9004.819903601932;9006.189442950134;8399.28606068485;8677.219050285474;8689.86965195521;8846.646134154626;8854.557272136384;2348.365941195876;2374.489484760776;2376.310869847742;2381.833271670984;2385.153270588942;2065.834733221339;2333.8482998605123;2362.7400985891622;2368.2066507719846;2381.6070348392495;2339.263132113387;2342.7422983471806;2348.189874493297;2359.6578496118054;2372.810175185981;2326.3718795925006;2339.380064282958;2341.6136337504377;2354.6350315701093;2368.1237941961413;2341.1805867946255;2342.5666251743482;2345.0971376020184;2351.917682881091;2354.9991253717103;8843.60203790831;8920.598573064228;8922.152395522415;8979.775505612412;8984.191422771917;5201.531862132426;5209.1404918188455;5274.762635681365;5330.28041214435;5332.33345831771;2959.215807810932;3108.4715598348107;3602.6937710294656;3677;3677.0807298175455;916.3356566489308;1053.4384151254862;1087.12486448409;1111.4276147783899;1112.3826276416585;11466.369349685763;11471.827922581168;11584.884151158465;11596.884031159665;11602.897724716055;4540.704854184468;4557.316402539579;4780.067886762048;4783.1390349736685;4796.208625576741;9905.80188396228;9966.003399659976;10001.14990225839;10002.199824014207;10029.598816047277;5319.946800531945;5333.653312534673;5340.75966581497;5346.973265133727;5353.304070470814;4350.108227813291;4498.50516443191;4711.387519622427;4837.903241935212;4897.95102048975;3361.3277344531193;3863.265979463893;3894.7468414552964;3990.2618015667194;4025.4163146344204;1268.8033354830015;1282.51264519482;1283.890869276122;1290.825738525306;1297.7858653322285;823.8640624296924;885.84054870124;901.3870568013814;927.2937794458229;951.509972364233;5186.936677981005;5796;5959.344472108065;6012.038073908154;6133.6319820810895;411.95880411958564;427.6536005835283;435.6253621885176;438.75649014797096;439.1261389834207;3909.9472776792413;3927.3127202739406;4021.0148513614354;4036.777977211223;4045.838166473367;10386.532606032746;10684.091852192496;10786.78426431467;10788.298760580537;10860.968208020226;7363.889541656879;9569.952150239204;10509.894901051044;10748.78502429947;10762.192835537366;6796.082528858738;7117.3238542338395;7451.993980812629;8043.879341809878;8257.504549727035;5776.537876969916;5979.222701048836;6049.425304596057;6072.666003369681;6098.817035488765;9621.951890240503;9968.252381071594;10052.69841904744;10089.89910100919;10115.190784737351;9323.20752736025;9443.811123777623;10258.743531411774;10264.846027309446;10344.37933723979;6956.130483689539;7025.789226323219;7369.8157546061775;7478.214787447239;7601.467897247361;8189.836203276021;8338.874916876373;8409.453385529921;8419.326453883552;8450.070492245724;7340.853182936418;7457.589832559262;7487.101547814297;7537.660805263886;7555.320021198118;7301.4158867289425;7336.816579585339;7447.180810110775;7496.9250307498405;7502.399808015455;6870.931290687029;6894.172699275919;6942.583444993316;6975.058367120577;6985.1967023790885;4845.8061677533915;4945.7527123644395;5008.098542262282;5024.57291130258;5071.644984851025;4905.77923993414;5011.323471331323;5046.770067157096;5196.168613021899;5206.140986737146 19:27:32 INFO - PROCESS | 1670 | |4;prototype.html;295.96744358120606;304.35932362377196;312.62641143833116;318.2965645922511;319.73142560249386;4792.514320560625;5110.236968245949;5349.662584353911;5536.861578460535;5545.778168873246;4446.911061778763;4918.385201849769;5366.248725178469;5545.223668686377;5558.305211848519;3581.3911635021977;4271.508776490705;4272.807723652443;4274.850380236692;4288.335308027245;4302.612764851163;4308.353746937969;4309.82760689572;4313.331433627793;4323.962249060219;3901.985483774225;3989.660878825289;4130.463039804837;4148.398482220076;4193.433886425324;3491.3017396520545;3682.466042423847;3861.165946175572;4151.2112698587325;4160.251154792136;3155.7790954633183;3613.3495970725257;3614.5301110855685;3615.475756015363;3629.3830048891487;4732.574068333832;5198.246254293106;5388.78444862205;5433.886053359052;5517.475839795213;4170.47869016373;4289.4852617685765;4803.375561177059;4816.975915120437;4853.39332583427;5031.723255220962;5382.67703937765;5598.664080155204;5931.406859314077;5940.435658612425;3070.539419087155;3481.7214622830365;3647.7993710346186;3930.0764320384687;3968.305546529346;5816.214811000503;5995.580309378345;6109.480694140959;6186.721597528077;6233.252009758858;3727.2172843703124;3902.2780785554764;3978.8806335809973;3996.760194388346;4020.4572382727956;3348.547946027255;4067.247559201557;4075.347944328893;4081.5102187737066;4098.631123198867;4668.276417155346;4808.471068182497;4810.350602668665;4814.229723244334;4842.554509972441;5443.374011988672;5904.022152578848;5912.940870591239;5917.55618328627;5936.614120082181;1207.2997661356567;1237.5977807212512;1394.7071115065746;1396.9802044507567;1398.9300534973413;679.9932000680029;787.2875048081495;840.6087924484987;853.9743807685779;859.6518410043942;3945.191235796655;4143.6892233082635;4227.598378154132;4616.815327386867;4674.462436819741;4679.3916790816975;4742.5020372861045;4999.575206025075;5142.485751424828;5145.202493613496;3999.7600143991453;4223.68322375823;4294.935575966363;4560.087982403533;4691.319758635047;2199.9780002199773;2315.40957055953;2437.062535284453;2649.152271273174;2710.7289271073128;4276.358546218092;4367.82068841411;4774.856754297377;5337.279467271868;5341.065313570188;4883.413990321184;5047.697138171648;5184.770548664111;5187.922181167285;5205.813558305521;4127;5220.058855564025;5235.528802407725;5263.4473380295285;5360.303160589098;4697.812087516598;4934.703917764946;5180.637355385087;5200.531952124327;5238.559396166085;4326.329418940069;4665.790039448165;4687.8983438891155;4719.206701453426;5069.721165335942;5236.39781425141;5304.204369344629;6002;6169.784057557964;6174.722137503733;3646.5259516263777;3978.9204215916097;4193.077522945069;4214.009707718745;4217.325227963511;5084.923726144185;5810.5351571875;6114.327423983269;6115.300243352707;6135.570510064253;5216.66091704038;5511.724413779215;5570.941521110976;5629.662220266797;5852.795152169653;5449.455054494519;5534.603761332668;5905.35041145465;5941.881162376815;5943.64338139713;5239.4236633970995;5319.813806516753;5477.993492084064;5757.884842303214;5823.44677255677;5649.350324712711;5869.302339526072;5904.5163353619855;5921.970390148022;5975.40245975399;5635.182898479616;5741.196232527533;5839.773647533895;5847.883042339215;5866.445391971217 19:27:32 INFO - PROCESS | 1670 | |5;yui.html;708.6876357365431;719.1729511062281;719.2376081353765;755.5957562703954;812.4231795425246;14661.633459163526;15561.455349062786;15610.84389156108;15626.374945002215;15673.059616423008;13955.092918960287;14120.646983825409;14229.857701422983;14235.145891246533;14241.430342786276;642.2100815996328;647.2524234509126;798.4291231769283;813.8860559521672;836.0761358698642;804.6741069866684;840.1136800675316;845.3701992015926;847.766864112371;848.4569875279795;849.7875531117221;866.4671227195292;892.3664198419131;899.9235065019482;901.6979311930478;846.9661213551451;859.5659192108008;893.1202765276198;894.9955250223773;897.0312062972007;633.4615576759763;657.6744511466807;672.2034389248756;678.253920687245;679.9830004249884;14273.57179284623;14348.784768228485;14352.497662581864;14365.497207597684;14402.711945761026;11324.92413220751;14304.28478576067;14315.928420357935;14483.493077742229;14581.854181458155;11780.764384712344;11885.94057029718;11909.583164589198;11925.15160150178;12067.336296503689;3704.8888533344043;3737.794421306827;3754.3805272130066;3788.109794198361;3894.55212650546;109329.90670093357;109676;111426.44286778514;111667;113368;9251.398659087137;10969.122470202365;10976.835347469794;11076.889231107585;11273.576181120934;22098.889505552368;22357.55284894293;22554.548909022054;22592.887035564716;22688.432789180402;7889.8816517752275;7953.960230198812;8033.317168040782;8083.272505474535;8207.097219305871;3726.6087060858763;4220.683448741357;4274.893127671833;4282.143571285693;4367.563243675607;3168.811695614145;3555.7866528008403;3637.4907512948207;3667.853285868589;3677.7241706871555;646.5904328563776;650.3821369698805;662.3442791636245;676.6921050921819;678.4809620640215;595.3451203676009;679.8266442057343;686.6978529447027;697.745322957117;700.394159052416;8858.274599519467;9066.36535442526;9403.153716165578;9425.340226184102;9441;560.6860158311358;573.6099452372343;650.7299470719598;666.8332916770808;678.7115475922714;3899.29812633723;4314.870553883388;4570.758280647313;4574.862754117382;4643.930341044887;96131.0386896136;137940.6205937948;143925;148631.25684371724;151721.24139379233;106521.40217896737;121206.12160209735;125835.11247331297;125941;126255.73744262439;43165.35251971223;53482.662933426975;102888.97111028944;103772.96227037784;104606;8071.87892181606;8662.393632445668;8694.608742606724;8776.956115219638;9091.999760052779;51935.48064519306;53452.93094138173;57922.7103864478;59434;59991;90764;92826;93047.53476232846;93859;94633;11448.427578621202;12710.745785084431;12957.935210324265;13222.272774997467;13503.729925401634;13779.311034448438;13989.160650361011;14928.477503287333;14930.178840163895;15224.391024358902;13769.586912392644;13872.791908121588;14255.144691318554;14372;14458.493952711604;13642.59072227795;13747.862521374658;13828.516001939885;14191;14573.052751571115;13190.951019554386;13454.125481843648;13521.18872867631;14036.508722194674;14179.432822686977;11122.833157502482;11156.721081973015;11182.161337899528;11221.775564488502;11769.705757356134;7976.6410511525955;10025.949870250603;11513.597024104116;11556.479958401724;11772.293662380285 19:27:32 INFO - PROCESS | 1670 | __end_tp_report 19:27:32 INFO - PROCESS | 1670 | __start_cc_report 19:27:32 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,367 19:27:32 INFO - PROCESS | 1670 | __end_cc_report 19:27:32 INFO - PROCESS | 1670 | __startTimestamp1476239252174__endTimestamp 19:27:32 INFO - PROCESS | 1670 | 19:27:32 INFO - PROCESS | 1670 | ------- Summary: start ------- 19:27:32 INFO - PROCESS | 1670 | Number of tests: 6 19:27:32 INFO - PROCESS | 1670 | 19:27:32 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5700.08 Median:5965.11 stddev:668.79 (11.2%) stddev-sans-first:630.66 19:27:32 INFO - PROCESS | 1670 | Values: 2789.4 2814.3 3020.5 3371.3 3429.0 5710.3 5724.0 5769.6 5779.9 5810.8 4911.4 5721.6 5823.4 5867.6 5974.6 4440.6 5219.2 5329.5 5334.6 5340.4 5218.1 5372.1 5397.5 5404.6 5474.6 4753.0 5196.4 5337.6 5354.3 5365.2 5179.3 5191.1 5216.6 5238.0 5346.3 5167.1 5220.6 5242.4 5269.3 5272.5 4935.0 5628.3 5734.5 5793.1 5826.4 6046.7 6059.5 6120.3 6145.3 6170.2 6191.9 6194.8 6201.2 6203.0 6243.1 4824.3 5927.1 5965.1 5977.8 5997.8 6112.1 6130.7 6142.3 6152.4 6292.9 5306.1 6069.5 6156.0 6180.4 6192.8 6184.9 6188.0 6190.8 6190.9 6194.9 5615.2 5621.9 6105.3 6132.1 6144.4 5433.3 6115.6 6135.3 6135.6 6157.5 4955.5 4965.0 4968.9 4994.4 5028.9 4338.8 4348.2 4413.1 4413.3 4442.8 5816.9 5853.0 5860.6 5872.3 5903.4 6140.2 6203.4 6210.7 6251.2 6255.5 5583.6 5692.6 5755.7 5820.9 5878.4 5418.6 5470.9 5472.9 5530.6 5533.1 5346.3 6321.9 6333.6 6348.7 6367.0 6058.7 6161.0 6190.8 6221.1 6234.0 6219.8 6221.7 6279.0 6288.8 6349.1 5940.7 6147.6 6164.5 6173.7 6179.8 5354.0 6190.5 6197.6 6203.6 6209.0 5947.6 6069.4 6093.2 6127.2 6146.5 5151.5 6103.9 6127.4 6137.9 6152.4 5809.2 5959.3 5971.1 5997.8 6018.5 6091.0 6095.2 6103.0 6116.5 6120.9 5974.2 6033.6 6037.5 6052.2 6116.1 19:27:32 INFO - PROCESS | 1670 | 19:27:32 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:66898.58 Median:40453.89 stddev:143363.27 (354.4%) stddev-sans-first:143678.74 19:27:32 INFO - PROCESS | 1670 | Values: 490.0 496.5 528.5 533.4 541.1 39557.0 41110.6 41219.8 41221.0 41377.8 42278.0 42698.0 42761.6 42789.0 42811.0 9436.6 9601.6 9602.9 9606.8 9607.2 9474.8 9560.0 9568.2 9578.4 9615.3 9554.5 9577.1 9588.8 9598.9 9621.2 9515.5 9532.3 9548.4 9564.0 9574.4 9515.8 9568.9 9597.7 9609.5 9615.0 40096.8 40123.0 40412.0 40444.4 40588.6 3359.5 3443.9 3492.2 3496.6 3819.2 29962.9 30465.0 30566.8 31753.2 31755.4 3198.3 3249.7 3265.6 3279.6 3347.8 480479.0 481196.0 481554.0 483527.0 484292.7 60940.7 60944.0 61242.1 61309.0 61334.4 63114.4 63159.4 63606.0 63663.4 64079.4 15955.7 16059.8 16179.7 16185.4 16190.5 12338.1 12482.1 12529.0 12545.8 12552.5 16351.8 17353.3 17608.9 17686.5 17714.2 3208.6 3290.5 6209.4 7143.5 7163.1 878.7 947.9 987.7 991.3 1077.7 28480.7 29603.7 29887.6 30067.7 30582.5 962.3 1068.1 1080.7 1088.2 1189.8 14613.8 15086.5 15284.8 15816.5 15893.5 632979.0 641845.0 645520.0 647789.9 651046.0 57113.4 57565.7 57809.0 57913.4 57943.4 1293.1 1344.0 1352.0 1359.0 1367.5 29858.6 29979.0 30025.0 30147.4 30324.0 41976.8 50738.0 51194.5 51259.7 51485.7 441317.0 443248.0 465076.0 490323.0 492249.0 23631.2 26542.6 26739.0 27027.5 27130.6 45518.8 46022.0 46464.8 46946.1 47647.8 37659.8 39129.2 39182.0 39231.0 40463.4 29733.4 35136.4 37781.8 38334.1 38431.2 37459.4 38279.8 38316.4 38412.4 38449.8 31890.7 32429.7 34176.3 34551.8 34740.3 22370.4 24406.9 25442.1 25563.7 26512.6 19:27:32 INFO - PROCESS | 1670 | 19:27:32 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:34230.04 Median:379.98 stddev:97932.47 (25773.2%) stddev-sans-first:98174.18 19:27:32 INFO - PROCESS | 1670 | Values: 359.4 374.2 385.8 389.9 408.0 18386.1 20309.5 21412.5 21530.0 21592.5 20204.9 20450.8 20679.8 20733.8 20944.4 5668.9 5740.4 5945.5 6036.0 6121.8 3877.2 4079.8 6065.5 6094.8 6104.9 5769.7 6043.2 6077.9 6102.8 6121.7 5896.6 6054.3 6068.3 6079.7 6095.4 5346.8 5555.3 5943.5 5959.7 5963.9 12936.5 13088.6 13208.8 13310.6 13387.9 2280.3 2314.6 2338.9 2382.8 2812.6 9459.2 10319.8 10425.7 12750.6 14797.1 1584.9 1808.4 1894.1 1902.2 1957.8 123479.4 129429.0 132087.3 132830.3 133132.0 8418.5 9741.5 10006.0 10906.5 11462.4 11979.8 14618.9 14643.1 14677.1 14681.9 8209.5 8489.6 8539.7 8591.7 8614.2 6300.8 8278.5 8285.3 8342.5 8439.1 4512.7 4514.7 4524.6 4538.6 4662.9 1586.5 1880.3 1925.9 1941.4 1949.9 794.1 848.0 867.6 961.7 1002.4 16022.0 17135.8 18442.4 21484.8 21506.8 868.4 888.4 912.3 917.9 950.7 10803.6 11366.3 11372.3 11407.3 11583.0 570085.0 578424.0 584895.5 595688.0 600067.0 95231.0 98096.0 98914.0 127491.0 131197.0 924.6 951.5 952.2 954.1 956.0 14154.7 15886.3 16908.1 18456.8 19017.2 54341.9 54740.7 55621.4 58631.4 59492.8 70978.3 76242.0 79817.2 79829.0 87878.6 7881.8 8140.2 8216.6 8271.7 8315.8 12142.8 13121.6 13135.0 13237.1 13289.6 11226.0 11412.8 11578.6 11675.3 11679.5 10419.4 10614.1 10867.7 11035.2 11474.5 11359.9 11664.1 11687.3 11697.3 11789.9 7663.9 7953.9 8238.2 8256.4 8396.9 8896.8 11028.9 11213.8 11268.0 11438.4 19:27:32 INFO - PROCESS | 1670 | 19:27:32 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5640.90 Median:4871.88 stddev:3174.71 (65.2%) stddev-sans-first:3167.98 19:27:32 INFO - PROCESS | 1670 | Values: 1448.8 1451.9 1636.9 1771.7 1785.6 7866.3 8117.5 8368.6 9004.8 9006.2 8399.3 8677.2 8689.9 8846.6 8854.6 2348.4 2374.5 2376.3 2381.8 2385.2 2065.8 2333.8 2362.7 2368.2 2381.6 2339.3 2342.7 2348.2 2359.7 2372.8 2326.4 2339.4 2341.6 2354.6 2368.1 2341.2 2342.6 2345.1 2351.9 2355.0 8843.6 8920.6 8922.2 8979.8 8984.2 5201.5 5209.1 5274.8 5330.3 5332.3 2959.2 3108.5 3602.7 3677.0 3677.1 916.3 1053.4 1087.1 1111.4 1112.4 11466.4 11471.8 11584.9 11596.9 11602.9 4540.7 4557.3 4780.1 4783.1 4796.2 9905.8 9966.0 10001.1 10002.2 10029.6 5319.9 5333.7 5340.8 5347.0 5353.3 4350.1 4498.5 4711.4 4837.9 4898.0 3361.3 3863.3 3894.7 3990.3 4025.4 1268.8 1282.5 1283.9 1290.8 1297.8 823.9 885.8 901.4 927.3 951.5 5186.9 5796.0 5959.3 6012.0 6133.6 412.0 427.7 435.6 438.8 439.1 3909.9 3927.3 4021.0 4036.8 4045.8 10386.5 10684.1 10786.8 10788.3 10861.0 7363.9 9570.0 10509.9 10748.8 10762.2 6796.1 7117.3 7452.0 8043.9 8257.5 5776.5 5979.2 6049.4 6072.7 6098.8 9622.0 9968.3 10052.7 10089.9 10115.2 9323.2 9443.8 10258.7 10264.8 10344.4 6956.1 7025.8 7369.8 7478.2 7601.5 8189.8 8338.9 8409.5 8419.3 8450.1 7340.9 7457.6 7487.1 7537.7 7555.3 7301.4 7336.8 7447.2 7496.9 7502.4 6870.9 6894.2 6942.6 6975.1 6985.2 4845.8 4945.8 5008.1 5024.6 5071.6 4905.8 5011.3 5046.8 5196.2 5206.1 19:27:32 INFO - PROCESS | 1670 | 19:27:32 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4481.14 Median:4815.60 stddev:1400.15 (29.1%) stddev-sans-first:1368.39 19:27:32 INFO - PROCESS | 1670 | Values: 296.0 304.4 312.6 318.3 319.7 4792.5 5110.2 5349.7 5536.9 5545.8 4446.9 4918.4 5366.2 5545.2 5558.3 3581.4 4271.5 4272.8 4274.9 4288.3 4302.6 4308.4 4309.8 4313.3 4324.0 3902.0 3989.7 4130.5 4148.4 4193.4 3491.3 3682.5 3861.2 4151.2 4160.3 3155.8 3613.3 3614.5 3615.5 3629.4 4732.6 5198.2 5388.8 5433.9 5517.5 4170.5 4289.5 4803.4 4817.0 4853.4 5031.7 5382.7 5598.7 5931.4 5940.4 3070.5 3481.7 3647.8 3930.1 3968.3 5816.2 5995.6 6109.5 6186.7 6233.3 3727.2 3902.3 3978.9 3996.8 4020.5 3348.5 4067.2 4075.3 4081.5 4098.6 4668.3 4808.5 4810.4 4814.2 4842.6 5443.4 5904.0 5912.9 5917.6 5936.6 1207.3 1237.6 1394.7 1397.0 1398.9 680.0 787.3 840.6 854.0 859.7 3945.2 4143.7 4227.6 4616.8 4674.5 4679.4 4742.5 4999.6 5142.5 5145.2 3999.8 4223.7 4294.9 4560.1 4691.3 2200.0 2315.4 2437.1 2649.2 2710.7 4276.4 4367.8 4774.9 5337.3 5341.1 4883.4 5047.7 5184.8 5187.9 5205.8 4127.0 5220.1 5235.5 5263.4 5360.3 4697.8 4934.7 5180.6 5200.5 5238.6 4326.3 4665.8 4687.9 4719.2 5069.7 5236.4 5304.2 6002.0 6169.8 6174.7 3646.5 3978.9 4193.1 4214.0 4217.3 5084.9 5810.5 6114.3 6115.3 6135.6 5216.7 5511.7 5570.9 5629.7 5852.8 5449.5 5534.6 5905.4 5941.9 5943.6 5239.4 5319.8 5478.0 5757.9 5823.4 5649.4 5869.3 5904.5 5922.0 5975.4 5635.2 5741.2 5839.8 5847.9 5866.4 19:27:32 INFO - PROCESS | 1670 | 19:27:32 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:23373.40 Median:3715.75 stddev:36841.35 (991.5%) stddev-sans-first:36905.40 19:27:32 INFO - PROCESS | 1670 | Values: 708.7 719.2 719.2 755.6 812.4 14661.6 15561.5 15610.8 15626.4 15673.1 13955.1 14120.6 14229.9 14235.1 14241.4 642.2 647.3 798.4 813.9 836.1 804.7 840.1 845.4 847.8 848.5 849.8 866.5 892.4 899.9 901.7 847.0 859.6 893.1 895.0 897.0 633.5 657.7 672.2 678.3 680.0 14273.6 14348.8 14352.5 14365.5 14402.7 11324.9 14304.3 14315.9 14483.5 14581.9 11780.8 11885.9 11909.6 11925.2 12067.3 3704.9 3737.8 3754.4 3788.1 3894.6 109329.9 109676.0 111426.4 111667.0 113368.0 9251.4 10969.1 10976.8 11076.9 11273.6 22098.9 22357.6 22554.5 22592.9 22688.4 7889.9 7954.0 8033.3 8083.3 8207.1 3726.6 4220.7 4274.9 4282.1 4367.6 3168.8 3555.8 3637.5 3667.9 3677.7 646.6 650.4 662.3 676.7 678.5 595.3 679.8 686.7 697.7 700.4 8858.3 9066.4 9403.2 9425.3 9441.0 560.7 573.6 650.7 666.8 678.7 3899.3 4314.9 4570.8 4574.9 4643.9 96131.0 137940.6 143925.0 148631.3 151721.2 106521.4 121206.1 125835.1 125941.0 126255.7 43165.4 53482.7 102889.0 103773.0 104606.0 8071.9 8662.4 8694.6 8777.0 9092.0 51935.5 53452.9 57922.7 59434.0 59991.0 90764.0 92826.0 93047.5 93859.0 94633.0 11448.4 12710.7 12957.9 13222.3 13503.7 13779.3 13989.2 14928.5 14930.2 15224.4 13769.6 13872.8 14255.1 14372.0 14458.5 13642.6 13747.9 13828.5 14191.0 14573.1 13191.0 13454.1 13521.2 14036.5 14179.4 11122.8 11156.7 11182.2 11221.8 11769.7 7976.6 10025.9 11513.6 11556.5 11772.3 19:27:32 INFO - PROCESS | 1670 | -------- Summary: end -------- 19:27:32 INFO - PROCESS | 1670 | 19:27:32 INFO - TEST-INFO | 1670: exit 0 19:27:32 INFO - TEST-OK | dromaeo_css | took 1115246ms 19:27:32 INFO - TEST-START | kraken 19:27:32 INFO - operating with platform_type : mac_ 19:27:32 INFO - Initialising browser for kraken test... 19:27:32 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmpvIqLBo/profile http://localhost:49268/getInfo.html) 19:27:35 INFO - PROCESS | 1677 | 2016-10-11 19:27:35.497 plugin-container[1678:11788] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 19:27:35 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:27:35 INFO - PROCESS | 1677 | 2016-10-11 19:27:35.499 plugin-container[1678:11788] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 19:27:35 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:27:35 INFO - PROCESS | 1677 | 2016-10-11 19:27:35.499 plugin-container[1678:11788] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:27:35 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 19:27:35 INFO - PROCESS | 1677 | colorDepth:24 19:27:35 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 19:27:35 INFO - PROCESS | 1677 | __metrics 19:27:35 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 19:27:36 INFO - TEST-INFO | 1677: exit 0 19:27:36 INFO - Browser initialized. 19:27:36 INFO - Running cycle 1/1 for kraken test... 19:27:36 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/T/tmpvIqLBo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:27:38 INFO - PROCESS | 1679 | 2016-10-11 19:27:38.674 plugin-container[1680:11899] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 19:27:38 INFO - PROCESS | 1679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:27:38 INFO - PROCESS | 1679 | 2016-10-11 19:27:38.675 plugin-container[1680:11899] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 19:27:38 INFO - PROCESS | 1679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:27:38 INFO - PROCESS | 1679 | 2016-10-11 19:27:38.675 plugin-container[1680:11899] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:27:48 INFO - PROCESS | 1679 | RSS: Main: 239161344 19:27:48 INFO - PROCESS | 1679 | 19:27:51 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 19:27:52 INFO - PROCESS | 1679 | RSS: Main: 252694528 19:27:52 INFO - PROCESS | 1679 | 19:27:55 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 19:27:55 INFO - PROCESS | 1679 | RSS: Main: 260186112 19:27:55 INFO - PROCESS | 1679 | 19:27:58 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 19:27:59 INFO - PROCESS | 1679 | RSS: Main: 261009408 19:27:59 INFO - PROCESS | 1679 | 19:28:01 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 19:28:02 INFO - PROCESS | 1679 | RSS: Main: 262090752 19:28:02 INFO - PROCESS | 1679 | 19:28:04 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:28:04 INFO - PROCESS | 1679 | RSS: Main: 263733248 19:28:04 INFO - PROCESS | 1679 | 19:28:07 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 19:28:08 INFO - PROCESS | 1679 | RSS: Main: 264384512 19:28:08 INFO - PROCESS | 1679 | 19:28:11 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 19:28:11 INFO - PROCESS | 1679 | RSS: Main: 264548352 19:28:11 INFO - PROCESS | 1679 | 19:28:14 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 19:28:15 INFO - PROCESS | 1679 | RSS: Main: 265007104 19:28:15 INFO - PROCESS | 1679 | 19:28:16 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:28:17 INFO - PROCESS | 1679 | RSS: Main: 264458240 19:28:17 INFO - PROCESS | 1679 | 19:28:19 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:28:20 INFO - PROCESS | 1679 | RSS: Main: 264540160 19:28:20 INFO - PROCESS | 1679 | 19:28:22 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:28:23 INFO - PROCESS | 1679 | RSS: Main: 264536064 19:28:23 INFO - PROCESS | 1679 | 19:28:26 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:28:26 INFO - PROCESS | 1679 | RSS: Main: 264888320 19:28:26 INFO - PROCESS | 1679 | 19:28:30 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:28:30 INFO - PROCESS | 1679 | RSS: Main: 264949760 19:28:30 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 19:28:32 INFO - PROCESS | 1679 | RSS: Main: 266350592 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | __start_tp_report 19:28:32 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 19:28:32 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 19:28:32 INFO - PROCESS | 1679 | |i|pagename|runs| 19:28:32 INFO - PROCESS | 1679 | |0;ai-astar;103;96;176;171;132;120;118;119;118;116 19:28:32 INFO - PROCESS | 1679 | |1;audio-beat-detection;153;113;117;153;116;119;122;144;180;135 19:28:32 INFO - PROCESS | 1679 | |2;audio-dft;201;209;214;197;194;192;194;192;195;195 19:28:32 INFO - PROCESS | 1679 | |3;audio-fft;82;58;60;84;59;61;58;59;78;59 19:28:32 INFO - PROCESS | 1679 | |4;audio-oscillator;91;71;86;73;70;70;86;69;70;88 19:28:32 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;120;88;137;80;134;131;127;86;76;131 19:28:32 INFO - PROCESS | 1679 | |6;imaging-darkroom;95;94;95;117;125;95;95;96;95;96 19:28:32 INFO - PROCESS | 1679 | |7;imaging-desaturate;136;142;89;142;92;91;94;91;91;95 19:28:32 INFO - PROCESS | 1679 | |8;json-parse-financial;56;54;57;56;57;56;58;56;57;58 19:28:32 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;69;103;70;79;80;74;69;69;69;70 19:28:32 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;84;89;89;90;90;90;86;110;106;90 19:28:32 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;155;146;139;161;157;159;157;148;203;137 19:28:32 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;179;163;226;236;177;158;230;227;173;188 19:28:32 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;72;70;69;69;71;68;70;69;68;68 19:28:32 INFO - PROCESS | 1679 | __end_tp_report 19:28:32 INFO - PROCESS | 1679 | __start_cc_report 19:28:32 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,966 19:28:32 INFO - PROCESS | 1679 | __end_cc_report 19:28:32 INFO - PROCESS | 1679 | __startTimestamp1476239312613__endTimestamp 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | ------- Summary: start ------- 19:28:32 INFO - PROCESS | 1679 | Number of tests: 14 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:126.90 Median:126.00 stddev:26.45 (21.0%) stddev-sans-first:26.60 19:28:32 INFO - PROCESS | 1679 | Values: 103.0 96.0 176.0 171.0 132.0 120.0 118.0 119.0 118.0 116.0 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:139.50 stddev:21.98 (15.8%) stddev-sans-first:22.35 19:28:32 INFO - PROCESS | 1679 | Values: 153.0 113.0 117.0 153.0 116.0 119.0 122.0 144.0 180.0 135.0 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:198.30 Median:196.00 stddev:7.51 (3.8%) stddev-sans-first:7.91 19:28:32 INFO - PROCESS | 1679 | Values: 201.0 209.0 214.0 197.0 194.0 192.0 194.0 192.0 195.0 195.0 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:65.80 Median:60.50 stddev:10.85 (17.9%) stddev-sans-first:9.80 19:28:32 INFO - PROCESS | 1679 | Values: 82.0 58.0 60.0 84.0 59.0 61.0 58.0 59.0 78.0 59.0 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:79.50 stddev:9.07 (11.4%) stddev-sans-first:8.18 19:28:32 INFO - PROCESS | 1679 | Values: 91.0 71.0 86.0 73.0 70.0 70.0 86.0 69.0 70.0 88.0 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:111.00 Median:106.50 stddev:25.13 (23.6%) stddev-sans-first:26.44 19:28:32 INFO - PROCESS | 1679 | Values: 120.0 88.0 137.0 80.0 134.0 131.0 127.0 86.0 76.0 131.0 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:100.30 Median:95.00 stddev:11.09 (11.7%) stddev-sans-first:11.59 19:28:32 INFO - PROCESS | 1679 | Values: 95.0 94.0 95.0 117.0 125.0 95.0 95.0 96.0 95.0 96.0 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:106.30 Median:91.00 stddev:23.37 (25.7%) stddev-sans-first:22.18 19:28:32 INFO - PROCESS | 1679 | Values: 136.0 142.0 89.0 142.0 92.0 91.0 94.0 91.0 91.0 95.0 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:1.18 (2.1%) stddev-sans-first:1.24 19:28:32 INFO - PROCESS | 1679 | Values: 56.0 54.0 57.0 56.0 57.0 56.0 58.0 56.0 57.0 58.0 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:75.20 Median:70.00 stddev:10.64 (15.2%) stddev-sans-first:11.05 19:28:32 INFO - PROCESS | 1679 | Values: 69.0 103.0 70.0 79.0 80.0 74.0 69.0 69.0 69.0 70.0 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:92.40 Median:89.50 stddev:8.51 (9.5%) stddev-sans-first:8.47 19:28:32 INFO - PROCESS | 1679 | Values: 84.0 89.0 89.0 90.0 90.0 90.0 86.0 110.0 106.0 90.0 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:156.20 Median:157.00 stddev:18.44 (11.7%) stddev-sans-first:19.55 19:28:32 INFO - PROCESS | 1679 | Values: 155.0 146.0 139.0 161.0 157.0 159.0 157.0 148.0 203.0 137.0 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.70 Median:207.00 stddev:30.54 (14.8%) stddev-sans-first:31.78 19:28:32 INFO - PROCESS | 1679 | Values: 179.0 163.0 226.0 236.0 177.0 158.0 230.0 227.0 173.0 188.0 19:28:32 INFO - PROCESS | 1679 | 19:28:32 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.50 stddev:1.35 (1.9%) stddev-sans-first:1.05 19:28:32 INFO - PROCESS | 1679 | Values: 72.0 70.0 69.0 69.0 71.0 68.0 70.0 69.0 68.0 68.0 19:28:32 INFO - PROCESS | 1679 | -------- Summary: end -------- 19:28:32 INFO - PROCESS | 1679 | 19:28:33 INFO - TEST-INFO | 1679: exit 0 19:28:33 INFO - TEST-OK | kraken | took 60619ms 19:28:33 INFO - SUITE-END | took 1175s 19:28:33 INFO - Completed test suite (00:19:36) 19:28:33 INFO - javascript benchmark 19:28:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7223.806261412944, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2789.4421115776845, 2814.2964258935267, 3020.48651729206, 3371.342588195303, 3428.9999999999986, 5710.257666503359, 5723.971380143099, 5769.596128271022, 5779.855503612412, 5810.767569297233, 4911.435184953732, 5721.628094173876, 5823.359430462657, 5867.589268751189, 5974.611650242731, 4440.644748420119, 5219.243209734586, 5329.467053294659, 5334.573234141279, 5340.412554619008, 5218.139007063849, 5372.059889519338, 5397.541208997221, 5404.594655400841, 5474.562035037207, 4753.0, 5196.428392876781, 5337.599680023995, 5354.250404943311, 5365.168398898177, 5179.300794392747, 5191.091558977164, 5216.634835561512, 5238.0, 5346.331708536433, 5167.121589329823, 5220.556252718524, 5242.449542797989, 5269.288646032813, 5272.525472707436, 4934.950650493521, 5628.324601047901, 5734.512566431858, 5793.13103034544, 5826.417358264182, 6046.697665116727, 6059.484943779784, 6120.294761999575, 6145.310039739063, 6170.228721409824, 6191.938080619181, 6194.752209911598, 6201.193844800147, 6203.0, 6243.094751261087, 4824.34871292378, 5927.081302398135, 5965.105234214902, 5977.820665380045, 5997.790077347316, 6112.1387513176505, 6130.693465326716, 6142.253225532421, 6152.384761523812, 6292.905606415908, 5306.071437498423, 6069.514438844882, 6155.969220153871, 6180.351063138306, 6192.845178870474, 6184.9485587450245, 6187.969060154761, 6190.845228869314, 6190.907136392958, 6194.907076393858, 5615.213870058195, 5621.915671264935, 6105.297890742532, 6132.141360525245, 6144.41628045335, 5433.293671822637, 6115.571909966259, 6135.263768347738, 6135.601185922901, 6157.476614487819, 4955.4548999610015, 4964.950350496449, 4968.8757781055765, 4994.425641051253, 5028.874278143003, 4338.806828122291, 4348.152110338454, 4413.095315460387, 4413.271810151289, 4442.844500442534, 5816.94183058164, 5853.03424934881, 5860.619059761102, 5872.29532456108, 5903.35063143054, 6140.2017737694705, 6203.379662033761, 6210.720517576629, 6251.249850017936, 6255.499560035186, 5583.637063590853, 5692.6299790513485, 5755.6834374108985, 5820.854478638068, 5878.353381128072, 5418.593605479605, 5470.9452905471235, 5472.863178420572, 5530.640508366943, 5533.142362933751, 5346.251524786455, 6321.8735625288155, 6333.556651034568, 6348.650824204713, 6367.044943258548, 6058.727357269022, 6161.045038019114, 6190.783322583688, 6221.09794079865, 6233.96883015582, 6219.782307619212, 6221.657808820558, 6278.968605156945, 6288.811335659938, 6349.07938348946, 5940.732667030081, 6147.631142131487, 6164.537659675632, 6173.660448675366, 6179.81460556184, 5353.97323013398, 6190.50475961931, 6197.566170368031, 6203.627782333075, 6209.0, 5947.553933454921, 6069.393060693896, 6093.177421048102, 6127.234095738033, 6146.508279337731, 5151.5363617274625, 6103.9025602783995, 6127.353222718542, 6137.9286238596105, 6152.415520525543, 5809.186713859978, 5959.255093113361, 5971.0744834550715, 5997.760089596367, 6018.518518518596, 6091.0, 6095.207623008981, 6103.034636161374, 6116.518381401602, 6120.873812997581, 5974.22335096452, 6033.607815491979, 6037.45662890342, 6052.213212282375, 6116.14373987651], "value": 5655.762903589741, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.0468588595182, 496.47869736776374, 528.5137673340527, 533.362631655172, 541.063959350324, 39557.0, 41110.58889411105, 41219.793901030454, 41221.0, 41377.758667239956, 42278.0, 42698.0, 42761.57238427622, 42789.0, 42811.0, 9436.622535098588, 9601.567929443192, 9602.855957160647, 9606.759831004247, 9607.2314214863, 9474.763130921714, 9560.0, 9568.234541236685, 9578.425294482318, 9615.278854085936, 9554.522273886278, 9577.090176433228, 9588.760280992961, 9598.904010959906, 9621.18219951305, 9515.524223788818, 9532.332736708364, 9548.37935534188, 9563.999999999965, 9574.377665451793, 9515.809683806285, 9568.90431095687, 9597.664081757104, 9609.51952402384, 9615.038496150399, 40096.79709608716, 40123.0, 40412.0, 40444.393334100016, 40588.59411405907, 3359.4960755886814, 3443.8622455101768, 3492.1618811485314, 3496.580410350749, 3819.1597848473293, 29962.85018574915, 30465.0, 30566.84716576425, 31753.206169845707, 31755.364892702248, 3198.296374797541, 3249.675032496755, 3265.575475188234, 3279.622843372995, 3347.815870127142, 480479.0, 481195.9999999965, 481554.0, 483527.0, 484292.73560896615, 60940.69529652412, 60944.0, 61242.0813687795, 61309.0, 61334.38665613376, 63114.36885631177, 63159.368406316265, 63606.0, 63663.40841479, 64079.35920640827, 15955.680886382208, 16059.839401606068, 16179.676406471805, 16185.352585896668, 16190.514284571245, 12338.136330456782, 12482.126251162514, 12528.997680185563, 12545.811812822814, 12552.497900084078, 16351.83648163527, 17353.30586776515, 17608.9119554404, 17686.469405917585, 17714.202860871294, 3208.6149662040702, 3290.4735242361107, 6209.379062093754, 7143.4999550031, 7163.140423149255, 878.6704985630389, 947.8625649220825, 987.7283746969641, 991.2813210422386, 1077.7251800790748, 28480.71519284822, 29603.703962960524, 29887.551686724717, 30067.699323006927, 30582.541261881088, 962.3071388600197, 1068.0974576482859, 1080.6703955293553, 1088.1947358954476, 1189.7798907202023, 14613.78079328811, 15086.547403577692, 15284.770728439082, 15816.52550423489, 15893.524749513936, 632979.0, 641845.0, 645520.0, 647789.9378552781, 651046.0, 57113.42886571081, 57565.712171439714, 57809.0, 57913.420865791646, 57943.4205657938, 1293.1142167615214, 1344.0390121063488, 1351.9526816561176, 1359.0, 1367.541873472378, 29858.552121718625, 29979.0, 30025.0, 30147.397052059274, 30324.0, 41976.790116049226, 50738.0, 51194.48805512046, 51259.74370128274, 51485.7425712869, 441317.0, 443248.0, 465076.0, 490323.0, 492249.0, 23631.172908948793, 26542.601860971714, 26739.0, 27027.459450811268, 27130.593041104003, 45518.77240613776, 46022.0, 46464.767676161406, 46946.06107877755, 47647.76176119097, 37659.81170094132, 39129.2174156521, 39182.0, 39231.0, 40463.393049103695, 29733.405331893675, 35136.4188611508, 37781.81109094437, 38334.14150099279, 38431.231375372896, 37459.43810842894, 38279.808600956814, 38316.425253621775, 38412.42381364226, 38449.80775096107, 31890.68109318877, 32429.675703242665, 34176.316473670886, 34551.790687325825, 34740.305193896485, 22370.414065116285, 24406.87796561006, 25442.109526166496, 25563.744362556135, 26512.554086628683], "value": 19602.489076228958, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.4338916206974, 374.16187739463595, 385.7936004237732, 389.8810862686879, 407.9861544063005, 18386.0806959652, 20309.49226269344, 21412.464688382777, 21530.0, 21592.460188495297, 20204.8989755051, 20450.795492045076, 20679.759214447145, 20733.792662073374, 20944.371668849923, 5668.943310566893, 5740.425957404268, 5945.464908158287, 6036.0, 6121.846953826168, 3877.205172939555, 4079.77561234132, 6065.514758819306, 6094.756209751605, 6104.877902441948, 5769.7115144242825, 6043.153958445821, 6077.908831367533, 6102.755889764427, 6121.693915304239, 5896.616719913192, 6054.303755068157, 6068.271807383098, 6079.665618390986, 6095.359987201325, 5346.796970681605, 5555.333359996826, 5943.524518038546, 5959.702014899238, 5963.850903727398, 12936.547220847318, 13088.607341779762, 13208.801867971988, 13310.600681979557, 13387.933060334635, 2280.2817112609473, 2314.6412306092584, 2338.9298321050396, 2382.833201675883, 2812.5921741347593, 9459.24326053914, 10319.793604127875, 10425.739356516073, 12750.553730619384, 14797.112173269637, 1584.8905765963757, 1808.3670715249689, 1894.0813705352894, 1902.1915685833465, 1957.765068191812, 123479.3826030873, 129429.0, 132087.33956330252, 132830.33584832013, 133132.0, 8418.494890306478, 9741.512924353754, 10006.049425304584, 10906.454677266105, 11462.426878656033, 11979.76040479203, 14618.926905365617, 14643.12141271527, 14677.119372837451, 14681.853181468263, 8209.548474833822, 8489.575521223915, 8539.743807685654, 8591.6992905248, 8614.22471977525, 6300.8424789379715, 8278.544680042656, 8285.25432711059, 8342.499450033018, 8439.113893041142, 4512.661550383668, 4514.7291162530355, 4524.6380289576755, 4538.614217791524, 4662.880908581916, 1586.5161125856725, 1880.3230836898706, 1925.8844469331605, 1941.417574727576, 1949.8927558984392, 794.1423262876195, 847.9533625650525, 867.626920424211, 961.6778379242892, 1002.4436437777103, 16021.95857269297, 17135.828641713422, 18442.35451759182, 21484.78515214859, 21506.784932150476, 868.3660927522943, 888.395890794266, 912.2519533982204, 917.8760867282831, 950.7195377363694, 10803.621873234399, 11366.261193022592, 11372.31766094037, 11407.25852819564, 11583.0, 570085.0, 578424.0, 584895.5286565035, 595688.0, 600067.0, 95231.0, 98096.0, 98914.0, 127491.0, 131197.0, 924.58393722825, 951.5004622573066, 952.1716106986827, 954.0650162840594, 955.9904400955901, 14154.735242361585, 15886.285117169524, 16908.07005614703, 18456.81543184551, 19017.239310427427, 54341.913161737335, 54740.726296368266, 55621.44378556163, 58631.4136858643, 59492.810143797746, 70978.29021709716, 76242.0, 79817.2018279833, 79829.0, 87878.56060719656, 7881.842363152819, 8140.226678465537, 8216.589170541329, 8271.669133234604, 8315.750527484186, 12142.817857732318, 13121.60635180908, 13135.0, 13237.139585926883, 13289.601311960656, 11225.968988373257, 11412.82880756806, 11578.594749183738, 11675.299482031105, 11679.532818687157, 10419.42693151884, 10614.097801686634, 10867.673969780919, 11035.172362073037, 11474.483648235682, 11359.886401135884, 11664.066874649838, 11687.298762074302, 11697.2981621103, 11789.941050294694, 7663.850422436679, 7953.880691789744, 8238.176182381714, 8256.380771442045, 8396.874046889423, 8896.822063558822, 11028.944855275673, 11213.831792522955, 11268.04221641137, 11438.42807859587], "value": 9329.16052972765, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.7971683964247, 1451.9128852268864, 1636.9441710096987, 1771.6899542580045, 1785.589314457674, 7866.252705992936, 8117.472364296316, 8368.581570921453, 9004.819903601932, 9006.189442950134, 8399.28606068485, 8677.219050285474, 8689.86965195521, 8846.646134154626, 8854.557272136384, 2348.365941195876, 2374.489484760776, 2376.310869847742, 2381.833271670984, 2385.153270588942, 2065.834733221339, 2333.8482998605123, 2362.7400985891622, 2368.2066507719846, 2381.6070348392495, 2339.263132113387, 2342.7422983471806, 2348.189874493297, 2359.6578496118054, 2372.810175185981, 2326.3718795925006, 2339.380064282958, 2341.6136337504377, 2354.6350315701093, 2368.1237941961413, 2341.1805867946255, 2342.5666251743482, 2345.0971376020184, 2351.917682881091, 2354.9991253717103, 8843.60203790831, 8920.598573064228, 8922.152395522415, 8979.775505612412, 8984.191422771917, 5201.531862132426, 5209.1404918188455, 5274.762635681365, 5330.28041214435, 5332.33345831771, 2959.215807810932, 3108.4715598348107, 3602.6937710294656, 3677.0, 3677.0807298175455, 916.3356566489308, 1053.4384151254862, 1087.12486448409, 1111.4276147783899, 1112.3826276416585, 11466.369349685763, 11471.827922581168, 11584.884151158465, 11596.884031159665, 11602.897724716055, 4540.704854184468, 4557.316402539579, 4780.067886762048, 4783.1390349736685, 4796.208625576741, 9905.80188396228, 9966.003399659976, 10001.14990225839, 10002.199824014207, 10029.598816047277, 5319.946800531945, 5333.653312534673, 5340.75966581497, 5346.973265133727, 5353.304070470814, 4350.108227813291, 4498.50516443191, 4711.387519622427, 4837.903241935212, 4897.95102048975, 3361.3277344531193, 3863.265979463893, 3894.7468414552964, 3990.2618015667194, 4025.4163146344204, 1268.8033354830015, 1282.51264519482, 1283.890869276122, 1290.825738525306, 1297.7858653322285, 823.8640624296924, 885.84054870124, 901.3870568013814, 927.2937794458229, 951.509972364233, 5186.936677981005, 5796.0, 5959.344472108065, 6012.038073908154, 6133.6319820810895, 411.95880411958564, 427.6536005835283, 435.6253621885176, 438.75649014797096, 439.1261389834207, 3909.9472776792413, 3927.3127202739406, 4021.0148513614354, 4036.777977211223, 4045.838166473367, 10386.532606032746, 10684.091852192496, 10786.78426431467, 10788.298760580537, 10860.968208020226, 7363.889541656879, 9569.952150239204, 10509.894901051044, 10748.78502429947, 10762.192835537366, 6796.082528858738, 7117.3238542338395, 7451.993980812629, 8043.879341809878, 8257.504549727035, 5776.537876969916, 5979.222701048836, 6049.425304596057, 6072.666003369681, 6098.817035488765, 9621.951890240503, 9968.252381071594, 10052.69841904744, 10089.89910100919, 10115.190784737351, 9323.20752736025, 9443.811123777623, 10258.743531411774, 10264.846027309446, 10344.37933723979, 6956.130483689539, 7025.789226323219, 7369.8157546061775, 7478.214787447239, 7601.467897247361, 8189.836203276021, 8338.874916876373, 8409.453385529921, 8419.326453883552, 8450.070492245724, 7340.853182936418, 7457.589832559262, 7487.101547814297, 7537.660805263886, 7555.320021198118, 7301.4158867289425, 7336.816579585339, 7447.180810110775, 7496.9250307498405, 7502.399808015455, 6870.931290687029, 6894.172699275919, 6942.583444993316, 6975.058367120577, 6985.1967023790885, 4845.8061677533915, 4945.7527123644395, 5008.098542262282, 5024.57291130258, 5071.644984851025, 4905.77923993414, 5011.323471331323, 5046.770067157096, 5196.168613021899, 5206.140986737146], "value": 4458.949416910608, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.96744358120606, 304.35932362377196, 312.62641143833116, 318.2965645922511, 319.73142560249386, 4792.514320560625, 5110.236968245949, 5349.662584353911, 5536.861578460535, 5545.778168873246, 4446.911061778763, 4918.385201849769, 5366.248725178469, 5545.223668686377, 5558.305211848519, 3581.3911635021977, 4271.508776490705, 4272.807723652443, 4274.850380236692, 4288.335308027245, 4302.612764851163, 4308.353746937969, 4309.82760689572, 4313.331433627793, 4323.962249060219, 3901.985483774225, 3989.660878825289, 4130.463039804837, 4148.398482220076, 4193.433886425324, 3491.3017396520545, 3682.466042423847, 3861.165946175572, 4151.2112698587325, 4160.251154792136, 3155.7790954633183, 3613.3495970725257, 3614.5301110855685, 3615.475756015363, 3629.3830048891487, 4732.574068333832, 5198.246254293106, 5388.78444862205, 5433.886053359052, 5517.475839795213, 4170.47869016373, 4289.4852617685765, 4803.375561177059, 4816.975915120437, 4853.39332583427, 5031.723255220962, 5382.67703937765, 5598.664080155204, 5931.406859314077, 5940.435658612425, 3070.539419087155, 3481.7214622830365, 3647.7993710346186, 3930.0764320384687, 3968.305546529346, 5816.214811000503, 5995.580309378345, 6109.480694140959, 6186.721597528077, 6233.252009758858, 3727.2172843703124, 3902.2780785554764, 3978.8806335809973, 3996.760194388346, 4020.4572382727956, 3348.547946027255, 4067.247559201557, 4075.347944328893, 4081.5102187737066, 4098.631123198867, 4668.276417155346, 4808.471068182497, 4810.350602668665, 4814.229723244334, 4842.554509972441, 5443.374011988672, 5904.022152578848, 5912.940870591239, 5917.55618328627, 5936.614120082181, 1207.2997661356567, 1237.5977807212512, 1394.7071115065746, 1396.9802044507567, 1398.9300534973413, 679.9932000680029, 787.2875048081495, 840.6087924484987, 853.9743807685779, 859.6518410043942, 3945.191235796655, 4143.6892233082635, 4227.598378154132, 4616.815327386867, 4674.462436819741, 4679.3916790816975, 4742.5020372861045, 4999.575206025075, 5142.485751424828, 5145.202493613496, 3999.7600143991453, 4223.68322375823, 4294.935575966363, 4560.087982403533, 4691.319758635047, 2199.9780002199773, 2315.40957055953, 2437.062535284453, 2649.152271273174, 2710.7289271073128, 4276.358546218092, 4367.82068841411, 4774.856754297377, 5337.279467271868, 5341.065313570188, 4883.413990321184, 5047.697138171648, 5184.770548664111, 5187.922181167285, 5205.813558305521, 4127.0, 5220.058855564025, 5235.528802407725, 5263.4473380295285, 5360.303160589098, 4697.812087516598, 4934.703917764946, 5180.637355385087, 5200.531952124327, 5238.559396166085, 4326.329418940069, 4665.790039448165, 4687.8983438891155, 4719.206701453426, 5069.721165335942, 5236.39781425141, 5304.204369344629, 6002.0, 6169.784057557964, 6174.722137503733, 3646.5259516263777, 3978.9204215916097, 4193.077522945069, 4214.009707718745, 4217.325227963511, 5084.923726144185, 5810.5351571875, 6114.327423983269, 6115.300243352707, 6135.570510064253, 5216.66091704038, 5511.724413779215, 5570.941521110976, 5629.662220266797, 5852.795152169653, 5449.455054494519, 5534.603761332668, 5905.35041145465, 5941.881162376815, 5943.64338139713, 5239.4236633970995, 5319.813806516753, 5477.993492084064, 5757.884842303214, 5823.44677255677, 5649.350324712711, 5869.302339526072, 5904.5163353619855, 5921.970390148022, 5975.40245975399, 5635.182898479616, 5741.196232527533, 5839.773647533895, 5847.883042339215, 5866.445391971217], "value": 4031.990732441199, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.6876357365431, 719.1729511062281, 719.2376081353765, 755.5957562703954, 812.4231795425246, 14661.633459163526, 15561.455349062786, 15610.84389156108, 15626.374945002215, 15673.059616423008, 13955.092918960287, 14120.646983825409, 14229.857701422983, 14235.145891246533, 14241.430342786276, 642.2100815996328, 647.2524234509126, 798.4291231769283, 813.8860559521672, 836.0761358698642, 804.6741069866684, 840.1136800675316, 845.3701992015926, 847.766864112371, 848.4569875279795, 849.7875531117221, 866.4671227195292, 892.3664198419131, 899.9235065019482, 901.6979311930478, 846.9661213551451, 859.5659192108008, 893.1202765276198, 894.9955250223773, 897.0312062972007, 633.4615576759763, 657.6744511466807, 672.2034389248756, 678.253920687245, 679.9830004249884, 14273.57179284623, 14348.784768228485, 14352.497662581864, 14365.497207597684, 14402.711945761026, 11324.92413220751, 14304.28478576067, 14315.928420357935, 14483.493077742229, 14581.854181458155, 11780.764384712344, 11885.94057029718, 11909.583164589198, 11925.15160150178, 12067.336296503689, 3704.8888533344043, 3737.794421306827, 3754.3805272130066, 3788.109794198361, 3894.55212650546, 109329.90670093357, 109676.0, 111426.44286778514, 111667.0, 113368.0, 9251.398659087137, 10969.122470202365, 10976.835347469794, 11076.889231107585, 11273.576181120934, 22098.889505552368, 22357.55284894293, 22554.548909022054, 22592.887035564716, 22688.432789180402, 7889.8816517752275, 7953.960230198812, 8033.317168040782, 8083.272505474535, 8207.097219305871, 3726.6087060858763, 4220.683448741357, 4274.893127671833, 4282.143571285693, 4367.563243675607, 3168.811695614145, 3555.7866528008403, 3637.4907512948207, 3667.853285868589, 3677.7241706871555, 646.5904328563776, 650.3821369698805, 662.3442791636245, 676.6921050921819, 678.4809620640215, 595.3451203676009, 679.8266442057343, 686.6978529447027, 697.745322957117, 700.394159052416, 8858.274599519467, 9066.36535442526, 9403.153716165578, 9425.340226184102, 9441.0, 560.6860158311358, 573.6099452372343, 650.7299470719598, 666.8332916770808, 678.7115475922714, 3899.29812633723, 4314.870553883388, 4570.758280647313, 4574.862754117382, 4643.930341044887, 96131.0386896136, 137940.6205937948, 143925.0, 148631.25684371724, 151721.24139379233, 106521.40217896737, 121206.12160209735, 125835.11247331297, 125941.0, 126255.73744262439, 43165.35251971223, 53482.662933426975, 102888.97111028944, 103772.96227037784, 104606.0, 8071.87892181606, 8662.393632445668, 8694.608742606724, 8776.956115219638, 9091.999760052779, 51935.48064519306, 53452.93094138173, 57922.7103864478, 59434.0, 59991.0, 90764.0, 92826.0, 93047.53476232846, 93859.0, 94633.0, 11448.427578621202, 12710.745785084431, 12957.935210324265, 13222.272774997467, 13503.729925401634, 13779.311034448438, 13989.160650361011, 14928.477503287333, 14930.178840163895, 15224.391024358902, 13769.586912392644, 13872.791908121588, 14255.144691318554, 14372.0, 14458.493952711604, 13642.59072227795, 13747.862521374658, 13828.516001939885, 14191.0, 14573.052751571115, 13190.951019554386, 13454.125481843648, 13521.18872867631, 14036.508722194674, 14179.432822686977, 11122.833157502482, 11156.721081973015, 11182.161337899528, 11221.775564488502, 11769.705757356134, 7976.6410511525955, 10025.949870250603, 11513.597024104116, 11556.479958401724, 11772.293662380285], "value": 7641.045331016564, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 96.0, 176.0, 171.0, 132.0, 120.0, 118.0, 119.0, 118.0, 116.0], "value": 126.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 113.0, 117.0, 153.0, 116.0, 119.0, 122.0, 144.0, 180.0, 135.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 209.0, 214.0, 197.0, 194.0, 192.0, 194.0, 192.0, 195.0, 195.0], "value": 198.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 60.0, 84.0, 59.0, 61.0, 58.0, 59.0, 78.0, 59.0], "value": 65.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 86.0, 73.0, 70.0, 70.0, 86.0, 69.0, 70.0, 88.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 88.0, 137.0, 80.0, 134.0, 131.0, 127.0, 86.0, 76.0, 131.0], "value": 111.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 117.0, 125.0, 95.0, 95.0, 96.0, 95.0, 96.0], "value": 100.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 142.0, 89.0, 142.0, 92.0, 91.0, 94.0, 91.0, 91.0, 95.0], "value": 106.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 57.0, 56.0, 57.0, 56.0, 58.0, 56.0, 57.0, 58.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 103.0, 70.0, 79.0, 80.0, 74.0, 69.0, 69.0, 69.0, 70.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 89.0, 90.0, 90.0, 90.0, 86.0, 110.0, 106.0, 90.0], "value": 92.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 146.0, 139.0, 161.0, 157.0, 159.0, 157.0, 148.0, 203.0, 137.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 163.0, 226.0, 236.0, 177.0, 158.0, 230.0, 227.0, 173.0, 188.0], "value": 195.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 69.0, 69.0, 71.0, 68.0, 70.0, 69.0, 68.0, 68.0], "value": 69.4, "unit": "score"}]}]} 19:28:33 INFO - Return code: 0 19:28:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:28:33 ERROR - # TBPL SUCCESS # 19:28:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:28:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:28:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:28:33 INFO - 2016-10-11 19:28:33.926 screenresolution[1684:12284] starting screenresolution argv=screenresolution get 19:28:33 INFO - 2016-10-11 19:28:33.928 screenresolution[1684:12284] Display 0: 1600x1200x32@60 19:28:33 INFO - 2016-10-11 19:28:33.934 screenresolution[1685:12287] starting screenresolution argv=screenresolution list 19:28:33 INFO - Available Modes on Display 0 19:28:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:28:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:28:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:28:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:28:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:28:34 INFO - Intel Iris: 19:28:34 INFO - Chipset Model: Intel Iris 19:28:34 INFO - Type: GPU 19:28:34 INFO - Bus: Built-In 19:28:34 INFO - VRAM (Dynamic, Max): 1536 MB 19:28:34 INFO - Vendor: Intel (0x8086) 19:28:34 INFO - Device ID: 0x0a2e 19:28:34 INFO - Revision ID: 0x0009 19:28:34 INFO - Displays: 19:28:34 INFO - Display: 19:28:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:28:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:28:34 INFO - Display Serial Number: 1600x1200 60 19:28:34 INFO - Main Display: Yes 19:28:34 INFO - Mirror: Off 19:28:34 INFO - Online: Yes 19:28:34 INFO - Rotation: Supported 19:28:34 INFO - Return code: 0 19:28:34 INFO - Running post-action listener: _resource_record_post_action 19:28:34 INFO - [mozharness: 2016-10-12 02:28:34.170976Z] Finished run-tests step (success) 19:28:34 INFO - Running post-run listener: _resource_record_post_run 19:28:34 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 32875008; Write bytes: 710824960; Read time: 198; Write time: 2015 19:28:34 INFO - TinderboxPrint: CPU usage
54.7% 19:28:34 INFO - TinderboxPrint: I/O read bytes / time
32,875,008 / 198 19:28:34 INFO - TinderboxPrint: I/O write bytes / time
710,824,960 / 2,015 19:28:34 INFO - TinderboxPrint: CPU idle
2,193.9 (45.3%) 19:28:34 INFO - TinderboxPrint: CPU system
979.6 (20.2%) 19:28:34 INFO - TinderboxPrint: CPU user
1,672.2 (34.5%) 19:28:34 INFO - TinderboxPrint: Swap in / out
436,297,728 / 0 19:28:34 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399559168; Write bytes: 393905664; Read time: 29591; Write time: 828 19:28:34 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 30908928; Write bytes: 298608128; Read time: 180; Write time: 1131 19:28:34 INFO - Running post-run listener: _upload_blobber_files 19:28:34 INFO - Blob upload gear active. 19:28:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:28:34 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 19:28:34 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:28:34 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:28:35 INFO - (blobuploader) - INFO - Open directory for files ... 19:28:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:28:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:28:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:36 INFO - (blobuploader) - INFO - Done attempting. 19:28:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:28:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:28:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:37 INFO - (blobuploader) - INFO - Done attempting. 19:28:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:28:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:28:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:39 INFO - (blobuploader) - INFO - Done attempting. 19:28:39 INFO - (blobuploader) - INFO - Iteration through files over. 19:28:39 INFO - Return code: 0 19:28:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:28:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:28:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56cc383b2ce8b344afd140933c65e82661079944c0901e25974ee0241195dc392ca0d627e8b116eb052b70251af74c850639e1000b3cc7e637a393796a600ec1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/12ffc9db1fd552a97342a618827b92b6f0765c7e0f6851073ecc971ff3f1e7b5e5cafd6941b6d67ac8c2e4cf4b1ca9d138c0353848bfdeb901c969e08a4f36e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e042678b0ab2f73c2f330e1805b3c04d61559bcb48cf625d89fe9d38a4cf697b481565e1a2b20bb56dfe4f9a6e627b5c14574d7762a2803e842126ec42d2a5de"} 19:28:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:28:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:28:39 INFO - Contents: 19:28:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56cc383b2ce8b344afd140933c65e82661079944c0901e25974ee0241195dc392ca0d627e8b116eb052b70251af74c850639e1000b3cc7e637a393796a600ec1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/12ffc9db1fd552a97342a618827b92b6f0765c7e0f6851073ecc971ff3f1e7b5e5cafd6941b6d67ac8c2e4cf4b1ca9d138c0353848bfdeb901c969e08a4f36e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e042678b0ab2f73c2f330e1805b3c04d61559bcb48cf625d89fe9d38a4cf697b481565e1a2b20bb56dfe4f9a6e627b5c14574d7762a2803e842126ec42d2a5de"} 19:28:39 INFO - Running post-run listener: copy_logs_to_upload_dir 19:28:39 INFO - Copying logs to upload dir... 19:28:39 INFO - mkdir: /builds/slave/test/build/upload/logs 19:28:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.263984 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-11 19:28:39.479833) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-11 19:28:39.483376) ========= 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.MOpldjxdGp/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.zI3aJ9SPML/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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/Autoland/sha512/56cc383b2ce8b344afd140933c65e82661079944c0901e25974ee0241195dc392ca0d627e8b116eb052b70251af74c850639e1000b3cc7e637a393796a600ec1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/12ffc9db1fd552a97342a618827b92b6f0765c7e0f6851073ecc971ff3f1e7b5e5cafd6941b6d67ac8c2e4cf4b1ca9d138c0353848bfdeb901c969e08a4f36e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e042678b0ab2f73c2f330e1805b3c04d61559bcb48cf625d89fe9d38a4cf697b481565e1a2b20bb56dfe4f9a6e627b5c14574d7762a2803e842126ec42d2a5de"} build_url:https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010459 build_url: 'https://queue.taskcluster.net/v1/task/OqVcwSl2T26KJZAlfxWxOw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/56cc383b2ce8b344afd140933c65e82661079944c0901e25974ee0241195dc392ca0d627e8b116eb052b70251af74c850639e1000b3cc7e637a393796a600ec1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/12ffc9db1fd552a97342a618827b92b6f0765c7e0f6851073ecc971ff3f1e7b5e5cafd6941b6d67ac8c2e4cf4b1ca9d138c0353848bfdeb901c969e08a4f36e5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e042678b0ab2f73c2f330e1805b3c04d61559bcb48cf625d89fe9d38a4cf697b481565e1a2b20bb56dfe4f9a6e627b5c14574d7762a2803e842126ec42d2a5de"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-11 19:28:39.518375) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 19:28:39.518909) ========= 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.MOpldjxdGp/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.zI3aJ9SPML/Listeners TMPDIR=/var/folders/v6/pkqdmfh94j76xzqgv7yrqmr000000w/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.005006 ========= master_lag: 0.20 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 19:28:39.728236) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-11 19:28:39.728549) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-11 19:28:39.728960) ========= ========= Total master_lag: 0.42 =========