builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0226 starttime: 1476219540.28 results: success (0) buildid: 20161011120146 builduid: 4ec90307e7e14ef9b8a118adc082b556 revision: 380b5366b20f4ad1cb21ee8252486017e0f66ad2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-11 13:59:00.281927) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-11 13:59:00.282459) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-11 13:59:00.282781) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-11 13:59:00.458692) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 13:59:00.458994) ========= 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.e7uBDT4OTh/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.JF4YASOdcn/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 13:59:00-- 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% 10.4M=0.001s 2016-10-11 13:59:00 (10.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143094 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 13:59:00.638765) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 13:59:00.639086) ========= 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.e7uBDT4OTh/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.JF4YASOdcn/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.030440 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 13:59:00.690639) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 13:59:00.691027) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 380b5366b20f4ad1cb21ee8252486017e0f66ad2 --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 380b5366b20f4ad1cb21ee8252486017e0f66ad2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.e7uBDT4OTh/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.JF4YASOdcn/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 13:59:00,771 truncating revision to first 12 chars 2016-10-11 13:59:00,772 Setting DEBUG logging. 2016-10-11 13:59:00,772 attempt 1/10 2016-10-11 13:59:00,772 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/380b5366b20f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-11 13:59:01,434 unpacking tar archive at: autoland-380b5366b20f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.947557 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 13:59:01.660547) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-11 13:59:01.660862) ========= script_repo_revision: 380b5366b20f4ad1cb21ee8252486017e0f66ad2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-11 13:59:01.661323) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-11 13:59:01.661646) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-11 13:59:01.680646) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-11 13:59:01.681230) ========= /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.e7uBDT4OTh/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.JF4YASOdcn/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 13:59:01 INFO - MultiFileLogger online at 20161011 13:59:01 in /builds/slave/test 13:59:01 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 13:59:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:59:01 INFO - {'append_to_log': False, 13:59:01 INFO - 'base_work_dir': '/builds/slave/test', 13:59:01 INFO - 'blob_upload_branch': 'Autoland', 13:59:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:59:01 INFO - 'branch': 'Autoland', 13:59:01 INFO - 'buildbot_json_path': 'buildprops.json', 13:59:01 INFO - 'config_files': ('talos/mac_config.py',), 13:59:01 INFO - 'default_actions': ('clobber', 13:59:01 INFO - 'read-buildbot-config', 13:59:01 INFO - 'download-and-extract', 13:59:01 INFO - 'populate-webroot', 13:59:01 INFO - 'create-virtualenv', 13:59:01 INFO - 'install', 13:59:01 INFO - 'run-tests'), 13:59:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:59:01 INFO - 'download_minidump_stackwalk': True, 13:59:01 INFO - 'download_symbols': 'ondemand', 13:59:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:59:01 INFO - 'tooltool.py': '/tools/tooltool.py', 13:59:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:59:01 INFO - '/tools/misc-python/virtualenv.py')}, 13:59:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:59:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:59:01 INFO - 'installer_path': 'installer.exe', 13:59:01 INFO - 'log_level': 'info', 13:59:01 INFO - 'log_name': 'talos', 13:59:01 INFO - 'log_to_console': True, 13:59:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:59:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:59:01 INFO - 'opt_config_files': (), 13:59:01 INFO - 'pip_index': False, 13:59:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:59:01 INFO - 'cmd': ('bash', 13:59:01 INFO - '-c', 13:59:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:59:01 INFO - 'enabled': True, 13:59:01 INFO - 'halt_on_failure': False, 13:59:01 INFO - 'name': 'check_screen_resolution'},), 13:59:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:59:01 INFO - 'cmd': ('bash', 13:59:01 INFO - '-c', 13:59:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:59:01 INFO - 'enabled': True, 13:59:01 INFO - 'halt_on_failure': False, 13:59:01 INFO - 'name': 'check_screen_resolution'},), 13:59:01 INFO - 'run_cmd_checks_enabled': True, 13:59:01 INFO - 'sps_profile': False, 13:59:01 INFO - 'sps_profile_interval': 0, 13:59:01 INFO - 'suite': 'dromaeojs-e10s', 13:59:01 INFO - 'system_bits': '32', 13:59:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:59:01 INFO - 'title': 't-yosemite-r7-0226', 13:59:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:59:01 INFO - 'use_talos_json': True, 13:59:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:59:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:59:01 INFO - 'work_dir': 'build'} 13:59:01 INFO - [mozharness: 2016-10-11 20:59:01.880495Z] Running clobber step. 13:59:01 INFO - Running pre-action listener: _resource_record_pre_action 13:59:01 INFO - Running main action method: clobber 13:59:01 INFO - rmtree: /builds/slave/test/build 13:59:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:59:04 INFO - Running post-action listener: _resource_record_post_action 13:59:04 INFO - [mozharness: 2016-10-11 20:59:04.161496Z] Finished clobber step (success) 13:59:04 INFO - [mozharness: 2016-10-11 20:59:04.161760Z] Running read-buildbot-config step. 13:59:04 INFO - Running pre-action listener: _resource_record_pre_action 13:59:04 INFO - Running main action method: read_buildbot_config 13:59:04 INFO - Using buildbot properties: 13:59:04 INFO - { 13:59:04 INFO - "project": "", 13:59:04 INFO - "product": "firefox", 13:59:04 INFO - "script_repo_revision": "production", 13:59:04 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 13:59:04 INFO - "repository": "", 13:59:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 13:59:04 INFO - "buildid": "20161011120146", 13:59:04 INFO - "pgo_build": "False", 13:59:04 INFO - "basedir": "/builds/slave/test", 13:59:04 INFO - "buildnumber": 44, 13:59:04 INFO - "slavename": "t-yosemite-r7-0226", 13:59:04 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:59:04 INFO - "platform": "yosemite_r7", 13:59:04 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 13:59:04 INFO - "branch": "autoland", 13:59:04 INFO - "repo_path": "integration/autoland", 13:59:04 INFO - "revision": "380b5366b20f4ad1cb21ee8252486017e0f66ad2", 13:59:04 INFO - "stage_platform": "macosx64", 13:59:04 INFO - "builduid": "4ec90307e7e14ef9b8a118adc082b556", 13:59:04 INFO - "slavebuilddir": "test" 13:59:04 INFO - } 13:59:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 13:59:04 INFO - Running post-action listener: _resource_record_post_action 13:59:04 INFO - [mozharness: 2016-10-11 20:59:04.167460Z] Finished read-buildbot-config step (success) 13:59:04 INFO - [mozharness: 2016-10-11 20:59:04.167658Z] Running download-and-extract step. 13:59:04 INFO - Running pre-action listener: _resource_record_pre_action 13:59:04 INFO - Running main action method: download_and_extract 13:59:04 INFO - mkdir: /builds/slave/test/build/tests 13:59:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:04 INFO - trying https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 13:59:04 INFO - Downloading https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/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 13:59:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/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 13:59:05 INFO - Downloaded 1199 bytes. 13:59:05 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 13:59:05 INFO - Using the following test package requirements: 13:59:05 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 13:59:05 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:59:05 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 13:59:05 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:59:05 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 13:59:05 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:59:05 INFO - u'jsshell-mac.zip'], 13:59:05 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:59:05 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 13:59:05 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 13:59:05 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:59:05 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 13:59:05 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:59:05 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 13:59:05 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:59:05 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 13:59:05 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:59:05 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 13:59:05 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:59:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 13:59:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:59:05 INFO - Fetch https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 13:59:05 INFO - Http code: 200 13:59:05 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 13:59:05 INFO - x-amz-cf-id: zPA5nKscxSKLNef-XHXGEVt5-s2PlvRqipDHW_Eie8vsxUVKWGbamA== 13:59:05 INFO - x-amz-version-id: hCXyJNa.APY9ITxQjQawARiMXymd7QVn 13:59:06 INFO - Expected file size: 16508485 13:59:06 INFO - Obtained file size: 16508485 13:59:12 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 13:59:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 13:59:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:59:12 INFO - Fetch https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 13:59:13 INFO - Http code: 200 13:59:13 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 13:59:13 INFO - x-amz-cf-id: oF43-k6eJFaSNuRD_nXj8YsajRy-JCxyJ1piBWDuXc_htiVph3NMCw== 13:59:13 INFO - x-amz-version-id: hCXyJNa.APY9ITxQjQawARiMXymd7QVn 13:59:13 INFO - Expected file size: 16508485 13:59:13 INFO - Obtained file size: 16508485 13:59:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 13:59:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:59:20 INFO - Fetch https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 13:59:20 INFO - Http code: 200 13:59:20 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 13:59:20 INFO - x-amz-cf-id: Fc9HMovnO-al6utrRzwyvzL8aL6ge4-CuHdEJtOe99Hjn-UNfJsPmg== 13:59:20 INFO - x-amz-version-id: PcOJI4eLVXRKPIT4ix1wAQPpq3n9BuQ1 13:59:21 INFO - Expected file size: 14409006 13:59:21 INFO - Obtained file size: 14409006 13:59: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')]} 13:59:21 INFO - trying https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:59:21 INFO - Downloading https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 13:59:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/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 13:59:25 INFO - Downloaded 111678630 bytes. 13:59:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:59:25 INFO - mkdir: /builds/slave/test/properties 13:59:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:59:25 INFO - Writing to file /builds/slave/test/properties/build_url 13:59:25 INFO - Contents: 13:59:25 INFO - build_url:https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:59:25 INFO - Running post-action listener: _resource_record_post_action 13:59:25 INFO - Running post-action listener: set_extra_try_arguments 13:59:25 INFO - [mozharness: 2016-10-11 20:59:25.897714Z] Finished download-and-extract step (success) 13:59:25 INFO - [mozharness: 2016-10-11 20:59:25.897841Z] Running populate-webroot step. 13:59:25 INFO - Running pre-action listener: _resource_record_pre_action 13:59:25 INFO - Running main action method: populate_webroot 13:59:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:59:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:59:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:59:25 INFO - '--tppagecycles', 13:59:25 INFO - '7'], 13:59:25 INFO - 'tests': ['tsvgm']}}, 13:59:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:59:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:59:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:59:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:59:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:59:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:59:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:25 INFO - 'talos_options': ['--disable-e10s'], 13:59:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:59:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:59:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:25 INFO - 'talos_options': ['--disable-e10s'], 13:59:25 INFO - 'tests': ['damp', 'tps']}, 13:59:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:25 INFO - 'tests': ['damp', 'tps']}, 13:59:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:59:25 INFO - 'tests': ['dromaeo_dom']}, 13:59:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:59:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:59:25 INFO - 'tests': ['basic_compositor_video']}, 13:59:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:59:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:59:25 INFO - 'tests': ['a11yr', 13:59:25 INFO - 'ts_paint', 13:59:25 INFO - 'tpaint', 13:59:25 INFO - 'sessionrestore', 13:59:25 INFO - 'sessionrestore_no_auto_restore', 13:59:25 INFO - 'tabpaint']}, 13:59:25 INFO - 'other-e10s': {'tests': ['a11yr', 13:59:25 INFO - 'ts_paint', 13:59:25 INFO - 'tpaint', 13:59:25 INFO - 'sessionrestore', 13:59:25 INFO - 'sessionrestore_no_auto_restore', 13:59:25 INFO - 'tabpaint']}, 13:59:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:59:25 INFO - 'ts_paint', 13:59:25 INFO - 'tpaint', 13:59:25 INFO - 'sessionrestore', 13:59:25 INFO - 'sessionrestore_no_auto_restore', 13:59:25 INFO - 'tabpaint']}, 13:59:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:59:25 INFO - 'ts_paint', 13:59:25 INFO - 'tpaint', 13:59:25 INFO - 'sessionrestore', 13:59:25 INFO - 'sessionrestore_no_auto_restore', 13:59:25 INFO - 'tabpaint']}, 13:59:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:59:25 INFO - 'tests': ['a11yr', 13:59:25 INFO - 'ts_paint', 13:59:25 INFO - 'tpaint', 13:59:25 INFO - 'sessionrestore', 13:59:25 INFO - 'sessionrestore_no_auto_restore', 13:59:25 INFO - 'tabpaint']}, 13:59:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:59:25 INFO - 'tests': ['a11yr', 13:59:25 INFO - 'ts_paint', 13:59:25 INFO - 'tpaint', 13:59:25 INFO - 'sessionrestore', 13:59:25 INFO - 'sessionrestore_no_auto_restore', 13:59:25 INFO - 'tabpaint']}, 13:59:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:59:25 INFO - 'tests': ['tsvgx', 13:59:25 INFO - 'tsvgr_opacity', 13:59:25 INFO - 'tart', 13:59:25 INFO - 'tscrollx', 13:59:25 INFO - 'cart']}, 13:59:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:59:25 INFO - 'tsvgr_opacity', 13:59:25 INFO - 'tart', 13:59:25 INFO - 'tscrollx', 13:59:25 INFO - 'cart']}, 13:59:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:25 INFO - 'talos_options': ['--disable-e10s'], 13:59:25 INFO - 'tests': ['tp5o']}, 13:59:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:25 INFO - 'tests': ['tp5o']}, 13:59:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:25 INFO - 'talos_options': ['--disable-e10s', 13:59:25 INFO - '--xperf_path', 13:59:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:59:25 INFO - 'tests': ['tp5n']}, 13:59:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:25 INFO - 'talos_options': ['--xperf_path', 13:59:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:59:25 INFO - 'tests': ['tp5n']}}, 13:59:25 INFO - 'talos.zip': {'path': '', 13:59:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:59:25 INFO - Running post-action listener: _resource_record_post_action 13:59:25 INFO - [mozharness: 2016-10-11 20:59:25.916273Z] Finished populate-webroot step (success) 13:59:25 INFO - [mozharness: 2016-10-11 20:59:25.916387Z] Running create-virtualenv step. 13:59:25 INFO - Running pre-action listener: _resource_record_pre_action 13:59:25 INFO - Running main action method: create_virtualenv 13:59:25 INFO - Creating virtualenv /builds/slave/test/build/venv 13:59:25 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 13:59:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:59:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:59:26 INFO - Using real prefix '/tools/python27' 13:59:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:59:26 INFO - Installing distribute.............................................................................................................................................................................................done. 13:59:29 INFO - Installing pip.................done. 13:59:29 INFO - Return code: 0 13:59:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:59:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:59:29 INFO - Reading from file tmpfile_stdout 13:59:29 INFO - Output received: 13:59:29 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) 13:59:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:59:29 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')]} 13:59:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:29 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')]} 13:59:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:29 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 0x1096b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10962b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf32578c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c9d30>, '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 0x1092efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094c1b20>, '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.e7uBDT4OTh/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.JF4YASOdcn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 13:59:29 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 13:59:29 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 13:59:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e7uBDT4OTh/Render', 13:59:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:29 INFO - 'HOME': '/Users/cltbld', 13:59:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:29 INFO - 'LOGNAME': 'cltbld', 13:59:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:29 INFO - 'MOZ_NO_REMOTE': '1', 13:59:29 INFO - 'NO_EM_RESTART': '1', 13:59:29 INFO - 'PAGER': '/bin/cat', 13:59:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:29 INFO - 'PWD': '/builds/slave/test', 13:59:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:29 INFO - 'SHELL': '/bin/bash', 13:59:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JF4YASOdcn/Listeners', 13:59:29 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 13:59:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:29 INFO - 'USER': 'cltbld', 13:59:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:29 INFO - 'XPC_FLAGS': '0x0', 13:59:29 INFO - 'XPC_SERVICE_NAME': '0', 13:59:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:30 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 13:59:30 INFO - Cleaning up... 13:59:30 INFO - Return code: 0 13:59:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:30 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 0x1096b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10962b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf32578c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c9d30>, '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 0x1092efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094c1b20>, '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.e7uBDT4OTh/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.JF4YASOdcn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 13:59:30 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 13:59:30 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 13:59:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e7uBDT4OTh/Render', 13:59:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:30 INFO - 'HOME': '/Users/cltbld', 13:59:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:30 INFO - 'LOGNAME': 'cltbld', 13:59:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:30 INFO - 'MOZ_NO_REMOTE': '1', 13:59:30 INFO - 'NO_EM_RESTART': '1', 13:59:30 INFO - 'PAGER': '/bin/cat', 13:59:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:30 INFO - 'PWD': '/builds/slave/test', 13:59:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:30 INFO - 'SHELL': '/bin/bash', 13:59:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JF4YASOdcn/Listeners', 13:59:30 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 13:59:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:30 INFO - 'USER': 'cltbld', 13:59:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:30 INFO - 'XPC_FLAGS': '0x0', 13:59:30 INFO - 'XPC_SERVICE_NAME': '0', 13:59:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:30 INFO - Downloading/unpacking psutil>=3.1.1 13:59:30 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 13:59:30 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 13:59:30 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 13:59:30 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 13:59:30 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 13:59:30 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 13:59:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:59:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:59:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:59:34 INFO - Installing collected packages: psutil 13:59:34 INFO - Running setup.py install for psutil 13:59:34 INFO - building 'psutil._psutil_osx' extension 13:59:34 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 13:59:35 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 13:59:35 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 13:59:35 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 13:59:35 INFO - building 'psutil._psutil_posix' extension 13:59:35 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 13:59:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:59:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:59:35 INFO - ^ 13:59:35 INFO - 1 warning generated. 13:59:35 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 13:59:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:59:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:59:35 INFO - Successfully installed psutil 13:59:35 INFO - Cleaning up... 13:59:35 INFO - Return code: 0 13:59:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:59:35 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')]} 13:59:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:35 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')]} 13:59:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:35 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 0x1096b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10962b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf32578c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c9d30>, '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 0x1092efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094c1b20>, '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.e7uBDT4OTh/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.JF4YASOdcn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 13:59:35 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 13:59:35 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 13:59:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e7uBDT4OTh/Render', 13:59:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:35 INFO - 'HOME': '/Users/cltbld', 13:59:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:35 INFO - 'LOGNAME': 'cltbld', 13:59:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:35 INFO - 'MOZ_NO_REMOTE': '1', 13:59:35 INFO - 'NO_EM_RESTART': '1', 13:59:35 INFO - 'PAGER': '/bin/cat', 13:59:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:35 INFO - 'PWD': '/builds/slave/test', 13:59:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:35 INFO - 'SHELL': '/bin/bash', 13:59:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JF4YASOdcn/Listeners', 13:59:35 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 13:59:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:35 INFO - 'USER': 'cltbld', 13:59:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:35 INFO - 'XPC_FLAGS': '0x0', 13:59:35 INFO - 'XPC_SERVICE_NAME': '0', 13:59:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:59:35 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 13:59:35 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 13:59:35 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 13:59:35 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 13:59:35 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 13:59:35 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 13:59:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:59:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:59:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:59:38 INFO - Installing collected packages: mozsystemmonitor 13:59:38 INFO - Running setup.py install for mozsystemmonitor 13:59:38 INFO - Successfully installed mozsystemmonitor 13:59:38 INFO - Cleaning up... 13:59:38 INFO - Return code: 0 13:59:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:38 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 0x1096b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10962b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf32578c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c9d30>, '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 0x1092efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094c1b20>, '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.e7uBDT4OTh/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.JF4YASOdcn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 13:59:38 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 13:59:38 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 13:59:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e7uBDT4OTh/Render', 13:59:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:38 INFO - 'HOME': '/Users/cltbld', 13:59:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:38 INFO - 'LOGNAME': 'cltbld', 13:59:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:38 INFO - 'MOZ_NO_REMOTE': '1', 13:59:38 INFO - 'NO_EM_RESTART': '1', 13:59:38 INFO - 'PAGER': '/bin/cat', 13:59:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:38 INFO - 'PWD': '/builds/slave/test', 13:59:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:38 INFO - 'SHELL': '/bin/bash', 13:59:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JF4YASOdcn/Listeners', 13:59:38 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 13:59:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:38 INFO - 'USER': 'cltbld', 13:59:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:38 INFO - 'XPC_FLAGS': '0x0', 13:59:38 INFO - 'XPC_SERVICE_NAME': '0', 13:59:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:39 INFO - Downloading/unpacking jsonschema==2.5.1 13:59:39 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 13:59:39 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 13:59:39 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 13:59:39 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 13:59:39 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 13:59:39 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 13:59:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:59:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:59:41 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 13:59:41 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 13:59:41 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 13:59:41 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 13:59:41 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 13:59:41 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 13:59:41 INFO - Downloading functools32-3.2.3-2.tar.gz 13:59:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:59:42 INFO - warning: no files found matching '*.txt' 13:59:42 INFO - no previously-included directories found matching 'build' 13:59:42 INFO - no previously-included directories found matching 'dist' 13:59:42 INFO - no previously-included directories found matching '.git*' 13:59:42 INFO - Installing collected packages: jsonschema, functools32 13:59:42 INFO - Running setup.py install for functools32 13:59:42 INFO - warning: no files found matching '*.txt' 13:59:42 INFO - no previously-included directories found matching 'build' 13:59:42 INFO - no previously-included directories found matching 'dist' 13:59:42 INFO - no previously-included directories found matching '.git*' 13:59:42 INFO - Successfully installed jsonschema functools32 13:59:42 INFO - Cleaning up... 13:59:42 INFO - Return code: 0 13:59:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:59:42 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')]} 13:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:42 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')]} 13:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:42 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 0x1096b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10962b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf32578c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c9d30>, '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 0x1092efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094c1b20>, '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.e7uBDT4OTh/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.JF4YASOdcn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 13:59:42 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 13:59:42 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 13:59:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e7uBDT4OTh/Render', 13:59:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:42 INFO - 'HOME': '/Users/cltbld', 13:59:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:42 INFO - 'LOGNAME': 'cltbld', 13:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:42 INFO - 'MOZ_NO_REMOTE': '1', 13:59:42 INFO - 'NO_EM_RESTART': '1', 13:59:42 INFO - 'PAGER': '/bin/cat', 13:59:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:42 INFO - 'PWD': '/builds/slave/test', 13:59:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:42 INFO - 'SHELL': '/bin/bash', 13:59:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JF4YASOdcn/Listeners', 13:59:42 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 13:59:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:42 INFO - 'USER': 'cltbld', 13:59:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:42 INFO - 'XPC_FLAGS': '0x0', 13:59:42 INFO - 'XPC_SERVICE_NAME': '0', 13:59:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:59:42 INFO - Cleaning up... 13:59:42 INFO - Return code: 0 13:59:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:59:42 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')]} 13:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:42 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')]} 13:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:42 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 0x1096b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10962b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf32578c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c9d30>, '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 0x1092efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094c1b20>, '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.e7uBDT4OTh/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.JF4YASOdcn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 13:59:42 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 13:59:42 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 13:59:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e7uBDT4OTh/Render', 13:59:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:42 INFO - 'HOME': '/Users/cltbld', 13:59:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:42 INFO - 'LOGNAME': 'cltbld', 13:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:42 INFO - 'MOZ_NO_REMOTE': '1', 13:59:42 INFO - 'NO_EM_RESTART': '1', 13:59:42 INFO - 'PAGER': '/bin/cat', 13:59:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:42 INFO - 'PWD': '/builds/slave/test', 13:59:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:42 INFO - 'SHELL': '/bin/bash', 13:59:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JF4YASOdcn/Listeners', 13:59:42 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 13:59:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:42 INFO - 'USER': 'cltbld', 13:59:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:42 INFO - 'XPC_FLAGS': '0x0', 13:59:42 INFO - 'XPC_SERVICE_NAME': '0', 13:59:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:42 INFO - Downloading/unpacking blobuploader==1.2.4 13:59:42 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 13:59:42 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 13:59:42 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 13:59:42 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 13:59:42 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 13:59:42 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 13:59:45 INFO - Downloading blobuploader-1.2.4.tar.gz 13:59:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:59:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:59:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:59:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:59:46 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 13:59:46 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 13:59:46 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 13:59:46 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 13:59:46 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 13:59:46 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 13:59:46 INFO - Downloading docopt-0.6.1.tar.gz 13:59:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:59:46 INFO - Installing collected packages: blobuploader, requests, docopt 13:59:46 INFO - Running setup.py install for blobuploader 13:59:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:59:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:59:46 INFO - Running setup.py install for requests 13:59:47 INFO - Running setup.py install for docopt 13:59:47 INFO - Successfully installed blobuploader requests docopt 13:59:47 INFO - Cleaning up... 13:59:47 INFO - Return code: 0 13:59:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:47 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 0x1096b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10962b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf32578c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c9d30>, '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 0x1092efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094c1b20>, '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.e7uBDT4OTh/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.JF4YASOdcn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 13:59:47 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 13:59:47 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 13:59:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e7uBDT4OTh/Render', 13:59:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:47 INFO - 'HOME': '/Users/cltbld', 13:59:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:47 INFO - 'LOGNAME': 'cltbld', 13:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:47 INFO - 'MOZ_NO_REMOTE': '1', 13:59:47 INFO - 'NO_EM_RESTART': '1', 13:59:47 INFO - 'PAGER': '/bin/cat', 13:59:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:47 INFO - 'PWD': '/builds/slave/test', 13:59:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:47 INFO - 'SHELL': '/bin/bash', 13:59:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JF4YASOdcn/Listeners', 13:59:47 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 13:59:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:47 INFO - 'USER': 'cltbld', 13:59:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:47 INFO - 'XPC_FLAGS': '0x0', 13:59:47 INFO - 'XPC_SERVICE_NAME': '0', 13:59:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:59:47 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-C_jHOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:59:47 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-raCyZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:59:48 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-e4HKFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:59:48 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-PRNtbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:59:48 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-sBOvkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:59:48 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-uFlUKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:59:48 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-5_j5Dt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:59:48 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-IfXqgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:59:48 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-zpDoUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:59:48 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-YVknbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:59:48 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-WShnPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:59:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:59:48 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-4KVsqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:59:49 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-e8a4Ul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:59:49 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-PdmZB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:49 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-PQiXYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:59:49 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-LtaDJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:59:49 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-xiqDYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:59:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:59:49 INFO - Running setup.py install for manifestparser 13:59:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:59:49 INFO - Running setup.py install for mozcrash 13:59:49 INFO - Running setup.py install for mozdebug 13:59:50 INFO - Running setup.py install for mozdevice 13:59:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:59:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:59:50 INFO - Running setup.py install for mozfile 13:59:50 INFO - Running setup.py install for mozhttpd 13:59:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:59:50 INFO - Running setup.py install for mozinfo 13:59:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:59:50 INFO - Running setup.py install for mozInstall 13:59:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:59:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:59:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:59:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:59:50 INFO - Running setup.py install for mozleak 13:59:50 INFO - Running setup.py install for mozlog 13:59:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:59:51 INFO - Running setup.py install for moznetwork 13:59:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:59:51 INFO - Running setup.py install for mozprocess 13:59:51 INFO - Running setup.py install for mozprofile 13:59:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:59:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:59:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:59:51 INFO - Running setup.py install for mozrunner 13:59:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:59:51 INFO - Running setup.py install for mozscreenshot 13:59:51 INFO - Running setup.py install for moztest 13:59:52 INFO - Running setup.py install for mozversion 13:59:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:59:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:59:52 INFO - Cleaning up... 13:59:52 INFO - Return code: 0 13:59:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:59:52 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')]} 13:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:52 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')]} 13:59:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:52 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 0x1096b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10962b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf32578c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c9d30>, '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 0x1092efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094c1b20>, '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.e7uBDT4OTh/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.JF4YASOdcn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 13:59:52 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 13:59:52 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 13:59:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e7uBDT4OTh/Render', 13:59:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:52 INFO - 'HOME': '/Users/cltbld', 13:59:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:52 INFO - 'LOGNAME': 'cltbld', 13:59:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:52 INFO - 'MOZ_NO_REMOTE': '1', 13:59:52 INFO - 'NO_EM_RESTART': '1', 13:59:52 INFO - 'PAGER': '/bin/cat', 13:59:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:52 INFO - 'PWD': '/builds/slave/test', 13:59:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:52 INFO - 'SHELL': '/bin/bash', 13:59:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JF4YASOdcn/Listeners', 13:59:52 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 13:59:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:52 INFO - 'USER': 'cltbld', 13:59:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:52 INFO - 'XPC_FLAGS': '0x0', 13:59:52 INFO - 'XPC_SERVICE_NAME': '0', 13:59:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:59:52 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-CRP_7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:59:52 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)) 13:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:59:52 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-XQABnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:59:52 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)) 13:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:59:52 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-tsfouH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:59:52 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)) 13:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:59:52 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-cHical-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:59:52 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)) 13:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:59:52 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-5zkxmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:59:52 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)) 13:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:59:53 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-SE4y6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:59:53 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)) 13:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:59:53 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-RXeS72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:59:53 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)) 13:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:59:53 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-zCHswn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:59:53 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)) 13:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:59:53 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-VPVWh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:59:53 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)) 13:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:59:53 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-0eXRcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:59:53 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)) 13:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:59:53 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-pEJw0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:59:53 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)) 13:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:59:53 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ji0NQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:59:53 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)) 13:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:59:53 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-5kWm4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:59:53 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)) 13:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:59:53 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ksxyY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:59:53 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)) 13:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:53 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-aipu2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:54 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)) 13:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:59:54 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-F6vAJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:59:54 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)) 13:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:59:54 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-8VmTfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:59:54 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)) 13:59:54 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)) 13:59:54 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)) 13:59:54 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)) 13:59:54 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)) 13:59:54 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)) 13:59:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:59:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:56 INFO - Downloading blessings-1.6.tar.gz 13:59:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:59:56 INFO - Installing collected packages: blessings 13:59:56 INFO - Running setup.py install for blessings 13:59:57 INFO - Successfully installed blessings 13:59:57 INFO - Cleaning up... 13:59:57 INFO - Return code: 0 13:59:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:59:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:59:57 INFO - Reading from file tmpfile_stdout 13:59:57 INFO - Current package versions: 13:59:57 INFO - blessings == 1.6 13:59:57 INFO - blobuploader == 1.2.4 13:59:57 INFO - docopt == 0.6.1 13:59:57 INFO - functools32 == 3.2.3-2 13:59:57 INFO - jsonschema == 2.5.1 13:59:57 INFO - manifestparser == 1.1 13:59:57 INFO - mozInstall == 1.12 13:59:57 INFO - mozcrash == 0.17 13:59:57 INFO - mozdebug == 0.1 13:59:57 INFO - mozdevice == 0.48 13:59:57 INFO - mozfile == 1.2 13:59:57 INFO - mozhttpd == 0.7 13:59:57 INFO - mozinfo == 0.9 13:59:57 INFO - mozleak == 0.1 13:59:57 INFO - mozlog == 3.4 13:59:57 INFO - moznetwork == 0.27 13:59:57 INFO - mozprocess == 0.23 13:59:57 INFO - mozprofile == 0.28 13:59:57 INFO - mozrunner == 6.12 13:59:57 INFO - mozscreenshot == 0.1 13:59:57 INFO - mozsystemmonitor == 0.3 13:59:57 INFO - moztest == 0.7 13:59:57 INFO - mozversion == 1.4 13:59:57 INFO - psutil == 3.1.1 13:59:57 INFO - requests == 1.2.3 13:59:57 INFO - wsgiref == 0.1.2 13:59:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:59: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')]} 13:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59: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')]} 13:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:57 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 0x1096b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10962b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf32578c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c9d30>, '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 0x1092efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094c1b20>, '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.e7uBDT4OTh/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.JF4YASOdcn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 13:59:57 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 13:59:57 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 13:59:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e7uBDT4OTh/Render', 13:59:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:57 INFO - 'HOME': '/Users/cltbld', 13:59:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:57 INFO - 'LOGNAME': 'cltbld', 13:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:57 INFO - 'MOZ_NO_REMOTE': '1', 13:59:57 INFO - 'NO_EM_RESTART': '1', 13:59:57 INFO - 'PAGER': '/bin/cat', 13:59:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:57 INFO - 'PWD': '/builds/slave/test', 13:59:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:57 INFO - 'SHELL': '/bin/bash', 13:59:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JF4YASOdcn/Listeners', 13:59:57 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 13:59:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:57 INFO - 'USER': 'cltbld', 13:59:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:57 INFO - 'XPC_FLAGS': '0x0', 13:59:57 INFO - 'XPC_SERVICE_NAME': '0', 13:59:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:57 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)) 13:59:57 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)) 13:59:57 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)) 13:59:57 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)) 13:59:57 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)) 13:59:57 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)) 13:59:57 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)) 13:59:57 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)) 13:59:57 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)) 13:59:57 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)) 13:59:57 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)) 13:59:57 INFO - Cleaning up... 13:59:57 INFO - Return code: 0 13:59:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:59: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')]} 13:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59: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')]} 13:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:57 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 0x1096b5490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10962b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf32578c30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094c9d30>, '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 0x1092efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094c1b20>, '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.e7uBDT4OTh/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.JF4YASOdcn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 13:59:57 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 13:59:57 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 13:59:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e7uBDT4OTh/Render', 13:59:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:57 INFO - 'HOME': '/Users/cltbld', 13:59:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:57 INFO - 'LOGNAME': 'cltbld', 13:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:57 INFO - 'MOZ_NO_REMOTE': '1', 13:59:57 INFO - 'NO_EM_RESTART': '1', 13:59:57 INFO - 'PAGER': '/bin/cat', 13:59:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:57 INFO - 'PWD': '/builds/slave/test', 13:59:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:57 INFO - 'SHELL': '/bin/bash', 13:59:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JF4YASOdcn/Listeners', 13:59:57 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 13:59:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:57 INFO - 'USER': 'cltbld', 13:59:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:57 INFO - 'XPC_FLAGS': '0x0', 13:59:57 INFO - 'XPC_SERVICE_NAME': '0', 13:59:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:59:57 INFO - Cleaning up... 13:59:57 INFO - Return code: 0 13:59:57 INFO - Running post-action listener: _resource_record_post_action 13:59:57 INFO - Running post-action listener: _start_resource_monitoring 13:59:57 INFO - Starting resource monitoring. 13:59:57 INFO - [mozharness: 2016-10-11 20:59:57.966706Z] Finished create-virtualenv step (success) 13:59:57 INFO - [mozharness: 2016-10-11 20:59:57.967758Z] Running install step. 13:59:57 INFO - Running pre-action listener: _resource_record_pre_action 13:59:57 INFO - Running main action method: install 13:59:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:59:58 INFO - Reading from file tmpfile_stdout 13:59:58 INFO - Detecting whether we're running mozinstall >=1.0... 13:59:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:59:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:59:58 INFO - Reading from file tmpfile_stdout 13:59:58 INFO - Output received: 13:59:58 INFO - Usage: mozinstall [options] installer 13:59:58 INFO - Options: 13:59:58 INFO - -h, --help show this help message and exit 13:59:58 INFO - -d DEST, --destination=DEST 13:59:58 INFO - Directory to install application into. [default: 13:59:58 INFO - "/builds/slave/test"] 13:59:58 INFO - --app=APP Application being installed. [default: firefox] 13:59:58 INFO - mkdir: /builds/slave/test/build/application 13:59:58 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'] 13:59:58 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 14:00:33 INFO - Reading from file tmpfile_stdout 14:00:33 INFO - Output received: 14:00:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:00:33 INFO - Running post-action listener: _resource_record_post_action 14:00:33 INFO - [mozharness: 2016-10-11 21:00:33.290093Z] Finished install step (success) 14:00:33 INFO - [mozharness: 2016-10-11 21:00:33.290225Z] Running run-tests step. 14:00:33 INFO - Running pre-action listener: _resource_record_pre_action 14:00:33 INFO - Running main action method: run_tests 14:00:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:00:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:00:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:00:33 INFO - 2016-10-11 14:00:33.304 screenresolution[1647:8471] starting screenresolution argv=screenresolution get 14:00:33 INFO - 2016-10-11 14:00:33.307 screenresolution[1647:8471] Display 0: 1600x1200x32@60 14:00:33 INFO - 2016-10-11 14:00:33.313 screenresolution[1648:8474] starting screenresolution argv=screenresolution list 14:00:33 INFO - Available Modes on Display 0 14:00:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:00:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:00:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:00:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:00:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:00:33 INFO - Intel Iris: 14:00:33 INFO - Chipset Model: Intel Iris 14:00:33 INFO - Type: GPU 14:00:33 INFO - Bus: Built-In 14:00:33 INFO - VRAM (Dynamic, Max): 1536 MB 14:00:33 INFO - Vendor: Intel (0x8086) 14:00:33 INFO - Device ID: 0x0a2e 14:00:33 INFO - Revision ID: 0x0009 14:00:33 INFO - Displays: 14:00:33 INFO - Display: 14:00:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:00:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:00:33 INFO - Display Serial Number: 1600x1200 60 14:00:33 INFO - Main Display: Yes 14:00:33 INFO - Mirror: Off 14:00:33 INFO - Online: Yes 14:00:33 INFO - Rotation: Supported 14:00:33 INFO - Return code: 0 14:00:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:00:33 INFO - Python 2.7.3 14:00:33 INFO - Return code: 0 14:00:33 INFO - grabbing minidump binary from tooltool 14:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:00:33 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 0x1094c9d30>, '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 0x1092efdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094c1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:00:33 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 14:00:33 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 14:00:33 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 14:00:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:00:33 INFO - Return code: 0 14:00:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:00:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:00:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:00:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:00:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:00:33 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/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 14:00:33 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/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0226 --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 14:00:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.e7uBDT4OTh/Render', 14:00:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:33 INFO - 'HOME': '/Users/cltbld', 14:00:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:33 INFO - 'LOGNAME': 'cltbld', 14:00:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:00:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:33 INFO - 'MOZ_NO_REMOTE': '1', 14:00:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:00:33 INFO - 'NO_EM_RESTART': '1', 14:00:33 INFO - 'PAGER': '/bin/cat', 14:00:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:33 INFO - 'PWD': '/builds/slave/test', 14:00:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:00:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:33 INFO - 'SHELL': '/bin/bash', 14:00:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JF4YASOdcn/Listeners', 14:00:33 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 14:00:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:33 INFO - 'USER': 'cltbld', 14:00:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:33 INFO - 'XPC_FLAGS': '0x0', 14:00:33 INFO - 'XPC_SERVICE_NAME': '0', 14:00:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:33 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/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 14:00:33 INFO - mozversion application_buildid: 20161011120146 14:00:33 INFO - mozversion application_changeset: 380b5366b20f4ad1cb21ee8252486017e0f66ad2 14:00:33 INFO - mozversion application_display_name: Nightly 14:00:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:00:33 INFO - mozversion application_name: Firefox 14:00:33 INFO - mozversion application_remotingname: firefox 14:00:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 14:00:33 INFO - mozversion application_vendor: Mozilla 14:00:33 INFO - mozversion application_version: 52.0a1 14:00:33 INFO - mozversion platform_buildid: 20161011120146 14:00:33 INFO - mozversion platform_changeset: 380b5366b20f4ad1cb21ee8252486017e0f66ad2 14:00:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 14:00:33 INFO - mozversion platform_version: 52.0a1 14:00:33 INFO - using testdate: 1476219633 14:00:33 INFO - actual date: 1476219633 14:00:33 INFO - starting webserver on 'localhost:49273' 14:00:33 INFO - SUITE-START | Running 2 tests 14:00:33 INFO - TEST-START | dromaeo_css 14:00:33 INFO - operating with platform_type : mac_ 14:00:33 INFO - Initialising browser for dromaeo_css test... 14:00:33 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpb93Hoh/profile http://localhost:49273/getInfo.html) 14:00:36 INFO - PROCESS | 1658 | 2016-10-11 14:00:36.481 plugin-container[1660:8646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 14:00:36 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:00:36 INFO - PROCESS | 1658 | 2016-10-11 14:00:36.484 plugin-container[1660:8646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9403, name = 'com.apple.CFPasteboardClient' 14:00:36 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:00:36 INFO - PROCESS | 1658 | 2016-10-11 14:00:36.484 plugin-container[1660:8646] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:00:36 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 14:00:36 INFO - PROCESS | 1658 | colorDepth:24 14:00:36 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 14:00:36 INFO - PROCESS | 1658 | __metrics 14:00:36 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 14:00:37 INFO - TEST-INFO | 1658: exit 0 14:00:37 INFO - Browser initialized. 14:00:37 INFO - Running cycle 1/1 for dromaeo_css test... 14:00:37 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpb93Hoh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:00:39 INFO - PROCESS | 1662 | 2016-10-11 14:00:39.848 plugin-container[1663:8768] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 14:00:39 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:00:39 INFO - PROCESS | 1662 | 2016-10-11 14:00:39.849 plugin-container[1663:8768] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 14:00:39 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:00:39 INFO - PROCESS | 1662 | 2016-10-11 14:00:39.849 plugin-container[1663:8768] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:00:49 INFO - PROCESS | 1662 | RSS: Main: 239230976 14:00:49 INFO - PROCESS | 1662 | 14:01:39 INFO - PROCESS | 1662 | 1476219699176 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] 14:03:52 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 14:03:53 INFO - PROCESS | 1662 | RSS: Main: 253009920 14:03:53 INFO - PROCESS | 1662 | 14:06:55 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 14:06:56 INFO - PROCESS | 1662 | RSS: Main: 267919360 14:06:56 INFO - PROCESS | 1662 | 14:09:58 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 14:09:58 INFO - PROCESS | 1662 | RSS: Main: 271499264 14:09:58 INFO - PROCESS | 1662 | 14:13:00 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 14:13:01 INFO - PROCESS | 1662 | RSS: Main: 269950976 14:13:01 INFO - PROCESS | 1662 | 14:16:04 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 14:16:04 INFO - PROCESS | 1662 | RSS: Main: 275398656 14:16:04 INFO - PROCESS | 1662 | 14:19:07 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 14:19:07 INFO - PROCESS | 1662 | RSS: Main: 279101440 14:19:07 INFO - PROCESS | 1662 | 14:19:07 INFO - PROCESS | 1662 | __start_tp_report 14:19:07 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 14:19:07 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 14:19:07 INFO - PROCESS | 1662 | |i|pagename|runs| 14:19:07 INFO - PROCESS | 1662 | |0;dojo.html;3176.2218256527153;3368.366342323974;3440.088376580207;3456.7407444441674;3473.8784142555014;5597.524210442107;5703;5794.797182098628;5829.562782791291;5840.415958404157;5633.408492108331;5780.624259423134;5800.071988481844;5800.15897694834;5807.651540907548;5202.401723801754;5261.552768014704;5289.391719952217;5303.814366497173;5307.893842123155;5262.684238945656;5272.868178295535;5324;5349.893002139955;5383.084875571162;5136.280920671072;5326.094563924142;5336.573074154097;5369.7583608737505;5437.157240627689;5157.8452646420665;5198.610104242197;5229.058769421503;5241.79032838686;5285.814996475105;5132.486751324875;5183.559397451221;5187.870303242449;5244.318238628992;5263.842084737464;5589.186828890678;5804.390538993387;5869.442402971711;5872.031114866042;5886.499647529922;6152.815415537541;6179.969100154515;6260.906086408662;6272.6236425814595;6305.180326557518;6228.626282423069;6254.843628909268;6278.591891527036;6282.78010269643;6284.811455656338;5650.3502097258515;5719.57103217252;5917.852053698692;5936.168936348915;5939.406059394026;6229.940910045303;6230.283517395467;6276.278228003838;6345.904811427832;6352.109790045518;6215.564910456315;6223.284322302993;6235.625862448177;6236.563440559117;6266.874662506724;6079.088136779518;6300.086487459293;6306.936930630635;6314.210723659543;6336.714847831859;6020.789272375446;6184.81445556634;6195.938040619536;6208.379162083846;6215.658138802319;6216.44052035319;6252.437280644764;6262.5616206866;6272.655003974825;6293.779717709858;4969.204927211629;4981.825636102719;4984;4988.4263309719845;5010.799568017239;4294.9785251073545;4382.452193475816;4387.648988080946;4395.956040439554;4503.977480112579;5896;5966.49284810796;5970;5970.641761494324;6033.456988871023;6256.655883926428;6283.654399008098;6284.780032698834;6291.905621415683;6292.622442653455;4913.606911447076;5705.942940570624;5913.349531551526;5943.3462319144855;5956.106584012433;5429.321334833146;5471.151971444432;5477.260569823103;5489.368722596872;5526.917096243559;6334.176557047738;6381.010943303795;6401.935980640134;6415;6427.9715245559555;6121.666376154749;6170.969145154246;6215.223097112861;6282.1519094921605;6305.211848518935;5948.73230704628;6267.185265915402;6362.363763623601;6397.712102955285;6415.422611964946;4825.203841366135;5804.6807425592;6250.937490625218;6263.060540918898;6264.843378915381;5260.421353651172;5620.803271885465;6162.537809664203;6196.504279657706;6212.782552610818;5969.074793407029;6161.599496032743;6181.598196117238;6223.937760622336;6236.594621349598;6078.9880617942545;6108.511319094373;6142.416470435302;6149.569530132848;6169.691515424301;5601.60788744784;5826.242588463472;6025.367336429787;6054.3642917493025;6121.84695382619;6170.845728856635;6202.968985155046;6213.00591905293;6215.347388524321;6239.438450539473;6065.8180254592435;6096.390360963868;6102.145699601971;6104.725287362168;6118.571699981137 14:19:07 INFO - PROCESS | 1662 | |1;ext.html;452.6469353903956;484.6123101518786;527.3223907279147;541.4828838459272;556.7299859568109;39043.414348784754;40863.97840054;40885;40897.79551102248;41105;42099.368509472304;42296.78851605738;42749.35875961855;42792.57207427925;42918.785406073;9515.762105947355;9678;9688.612455501807;9719.570107473131;9733.707988760349;9581.760455988586;9590.616375344978;9643.180329671952;9648.324617276794;9679.661211857587;9660.661876834312;9682.564284607175;9717.174040206592;9727.173190278834;9748.220142388627;9601.90398096017;9605.951970240174;9636.180924621416;9662.371945823534;9680.419174849496;9504.667336643184;9603.4718090505;9605.18355939746;9628.18160456362;9651.517424128766;40533.58132465379;40795;40865;40906.59093409058;41042.589574104175;3146.3036138365946;3423.6747508986855;3585.587657419404;3882;4003.890177302335;29564.26089347762;30181.849090754626;30855.537166942515;31195;31763.52354714681;3261.3314270574474;3276.770626056177;3287.112479630486;3336.3160552086765;3405.863765449379;477480.6125969348;484299;489284;490796;491761;59313;59725;59773;59848.70075649594;60130.69934650299;60832.695836521416;62780;63262;63312;63328.68335658292;15752.527424177293;15931.283092260875;15999.360025599079;16019.439319623758;16082.517524474284;12350.320732359627;12405.565805196775;12407.689807754698;12422.006239500819;12427.067969902293;16705.749413758804;16786.32854685799;16839.32642694303;16843.663126737396;16981.575460613585;5850.707464626752;6772.2211945625895;6807.931920680829;6830.624315662587;6904.860697984876;853.6158728572167;957.3203025851584;982.7493989032894;1052.4316868890867;1135.1713249328036;26189.95240190409;28174.01390951307;28859;29746.107616771533;29903.551446728317;929.716436486878;1004.5288590068368;1017.2452519404117;1049.186880167876;1100.944952752359;15310.846891530942;15379.769303460456;15506;15552.608223057543;15778.842211577967;565331;566134;572847;576832;581371.0931445316;56913.71543142258;57148.71425642928;57195.71402142962;57224.71387643118;57409.27772166842;1320.795276732108;1357.8642135786342;1361.5098564516766;1368.7057282684073;1379.9862001379859;23641;25438;29462.549428355822;29688;30023.849880750455;48654.75672621614;48976;49090.50909490859;49180.75409622929;49240.50759492359;521897;522390;523913;526442;526660;26483;40211.59788402078;40277;40677;40692;46750.53249467462;46988.82527936829;48107.518924810305;48110.03779924312;48188.31340903052;38677;38750.02499549995;39081;40015.1996960065;40286.597134028285;38395.42406863955;38797;38819.6118038816;39890.60109398985;40049;37750.43374349332;38031;38497.23005539818;38716.6128338713;39725;30703.539446908762;34309.48535772015;34501.827490862386;35040;35117;23173.884130579238;23254.88372558194;23413.882930585238;23635.64546531838;23644.52710945737 14:19:07 INFO - PROCESS | 1662 | |2;jquery.html;367.7756568493219;371.2074720471792;373.9551253849539;377.9414190800427;394.27256711367517;18936.67355629157;19585.608287834253;20014.899925500387;20188.69716954247;20215.898920505413;19887.30394436195;19978;20094.69857952132;20389.592208155827;20445.97770111496;4988.351514303135;5767.961766881959;5790.478856902867;5977.940220597804;6074.119252708355;5971.402859714038;6064.423879731418;6118.204633397674;6122.265328160627;6126.479249263818;5770.04794208955;5901;5944.375840536746;5953.315368732608;5954.374790646984;5735.311762588474;5929.347771745104;5935.317438494563;5959.165716799652;5990.73041713124;5134.358205224347;5148.897022059576;5166.664166829181;5166.9224961625605;5186.507281808223;24060.79696015192;24958.251252462273;24985.62521562178;25210.49579008428;25423.74576254251;2198.9890050549807;2293.334932869466;2331.9883400583058;2343;2381.7261014983324;15251.466198682992;15457.072575645498;15502.61243468911;15913.363465461367;15951.92024039884;1821.4899828048167;1887.160213704902;1894.2233684189416;1898.3450709505198;1901.8003109673423;89888.55055724745;109528.40423351257;109892.44522488739;110427.89572104256;110540.78918421587;10366.637167699095;10574.89425105739;10785;10786;11020.614278500214;13306.667333316744;13334.933325333312;13349.998750093782;13387.995900307516;13418.396172172275;7620.923790762133;8008.6785680363255;8230.876536851953;8490.108538603357;8611.95694021526;8362.331013519026;8478.321734261244;8518.870282429392;8633.568321583896;8667.263282621048;6482.416582507597;6552.967235163794;6584.637844918576;6586;6701.0953521274005;1793.8744287899722;1810.5292623917876;1834.5321942904475;1839.4456684101913;1841.1070630744077;934.9252059835197;936.9531523423801;944.7118628818139;977.3354119199013;985.9211263098935;17835.910820445813;21896.90515474252;21969.466030334203;22095.44761380946;22159.889200553895;835.9926288821955;857.8884744983237;916.5921165081475;934.2946075712794;992.8808542974889;10973.257779103355;11535.365554894393;11565.884341156649;11609.709757255967;11646.359450230319;566859;567112;570157;577752;580194;108214.45892770644;112669.30996035066;113839;115087;116673;967.2648786921849;968.4528241543646;1003.1773945364731;1004.1815920025156;1004.9346792458467;19013.42959711211;19155.521111972314;19214.423567293004;19248;19269.325573604856;51180.74409627928;51243.48756512387;51321;51702.741486292325;52407;64599.38501537499;68898;69301;69453.65273173804;70297;6097;6980.755673551402;7427.205649000481;7844.980152580128;7976.122626511195;10156.49217539135;11075.947784960415;12902.67743306425;13086.345682715635;13121.737565248832;5152.078839570164;5281.57747380201;9176.587053582738;10236.87394386602;11041.779164416826;8971.551422428722;9158.725238242863;9448.95275523618;10920.563177472812;11309.321440713584;9895.713557237801;9954.701358959242;11258.493367798632;11398.637401966605;11599.884001160219;7282.271772822675;7403.259674032553;7445.10658720957;7596.354309883722;7610.543367397974;9673.951630241803;10177.49112544355;10235.720534933133;11330.94334528322;11510.884891150981 14:19:07 INFO - PROCESS | 1662 | |3;mootools.html;1374.6700791809965;1512.1909778268628;1593.4502596604168;1635.2396135796853;1736.9826301736978;6933.549319294249;7812.960935195316;8124.837503249932;8915.777105572364;8964.46213227206;8275.29659978901;8657.220850123487;8689.695860644879;8754.124587541242;8760.95619521903;2338.649202619604;2355.37582540626;2356.175338631482;2358.78770910618;2365.9171928982487;1959.8138176873174;2329.406001469623;2353.976460235393;2358.50471401006;2361.338825128958;2284.1091974130104;2331.825113116515;2344.2732752846587;2346.272655476808;2349.5418393413293;2301.7007788987494;2310.838241323108;2323.6049871521795;2326.6161083421216;2338.1232037985756;1944.3000519812856;2306.2043594959714;2311.4914718761847;2315.6063469210108;2327.197116994634;8739.912600873973;8787.297016238686;8797.252233560155;8799.78000549985;8820.33847461443;5126.154184559543;5226.1638137898135;5278.709670968095;5288.365396152486;5304.92042619361;3447.120984149026;3533.2580158166816;3642.7267954903486;3644.2711457708565;3663.3955397359405;1046.5287672578966;1086.8913108689069;1095.808233559124;1112.004755743613;1115.5928086248541;11491.597794077166;11643.534258629645;11712.297262164297;11733.765324693459;11763.35301558414;4497.752623605733;4539.909201816011;4759.50025247344;4773.09311230865;4786.8085276588545;8677.522736249566;8869.73390798277;9382.343235973416;9689.660861869941;10002.349847259904;4670.7389004969145;4711.434627844681;4933.580645645088;5286.444923283077;5307.9734601326745;3848.615138486129;3979.20415916812;4283.378910058024;4489.236829738952;4830.396200474941;3851.114243723959;3870.2453021661067;3977.960220397759;3984.541777695544;4007.1384652299894;1264.7217612125319;1266.2465832829453;1268.397511182181;1277.0485987939044;1279.897608191361;737.8155461134717;742.476554029408;810.1938571121772;892.7991850960448;895.2435192262528;5043.613006423189;5428.185772134132;5600.803971860965;5813;5871.4715675589405;926.6802952981211;941.1999800169938;951.4243882451156;953.3517208298424;961.7451375385389;3325.218573635194;3729.68297694699;3782.3759079751535;3876.2635099331037;3894.539547669624;10377.636782712569;10669.89330106689;10808.675739727822;10816.351018938889;10872.293300935415;10655.946720266349;10738.19463540222;10774.622888198875;10784.622538211283;10810.351378917132;8191.221833925768;8319.376046796515;8325.666973321;8350.039745429232;8357.58212089393;4515.841945531891;4807.254875494304;5362.195670649434;5558.638688485235;5673.035583950656;9961.950190249003;9985.1512621428;10061.446620435947;10082.747931301776;10199.694009179737;10003.499825008867;10157.035081667229;10276.691699249035;10326.638567650096;10369.377837329785;6537.182852143482;6791.04925310447;6867.450603951772;7070.36366726997;7205.7838264852135;8314.833703326021;8533.786655333666;8546.743597692079;8550.230479256898;8580.970283566012;7385.187629360658;7480;7483.70065197386;7534.849303014019;7589.810254743676;7246.60143692102;7313.232110628307;7383.409327253699;7462.403007759258;7486;6491.642959637265;6634.336566343327;7046.506744527834;7054.012438258545;7054.929450705427;4774.140654682052;4837.419509658864;4842.95157048425;4849.345338379274;4939.728314942713;4772.427308722976;4858.82994095205;4861.975690121527;4914.967856750122;4943.431505376784 14:19:07 INFO - PROCESS | 1662 | |4;prototype.html;296.1544789625619;297.4734719546403;305.09539216223897;308.97882498342966;309.7614836575838;5328.573714102872;5528.838943821792;5538.4738449847255;5608.9439105608935;5633.211350410946;5336.825898302367;5503.9728149517805;5555.9165313007625;5566.276384070075;5581.916271255935;3906.687465002793;4197.580241975808;4261.78691065447;4267.935980960288;4268.716236813306;4130.843363858125;4219.945013746564;4237.830486780541;4259.957400425987;4265.637420819234;3673.787650075482;3989.900252493682;4004.479417675698;4133.751974881502;4233.618974292313;4033.515978082649;4190.575204430478;4195.797206466599;4216.262154123032;4243.533211346749;2850;3255.137388592025;3672.779633222015;3680.429533422324;3680.7791532508136;3801.5275499229274;4837.250226214907;4993.375828021497;5209.635325527214;5283.762230699628;4205.474315710536;4815.881012354588;4829.203181475053;4830;4858.708477491362;5299.602529810241;5693.060644993571;5758.27957844898;5841.3282472515775;5846.181534585162;3446.2590543033116;3690.022144131807;3898.8050597470015;3935.58676338986;4010.83956641734;4867.975660121712;6120.510359171256;6139.693015349215;6164.181566437866;6173.888700033992;3649.357788994963;3869.6710779583486;4044.6157615026527;4108.958910410917;4113.362428823537;3459.567554055743;3490.5636795400574;4092.897677558085;4142.751434913915;4145.2952997990415;4531.592156705912;4826.444958829709;4830.2513110467935;4854.296127061556;4854.451413008199;5854.677992710356;5863.9738045841805;5887.381824908323;5936.020556608198;5945.702714864239;1285.3059347952;1324.1393094488467;1397.196611948134;1414.3776738235144;1428.264443811438;790.5533373643873;790.7548659915329;859.8108416148438;862.6032025268321;871.7994861181837;4282.023993324609;4520.344550040226;4684.461286951976;4713.717176969393;4732.361131247238;4305.160493703697;4410.272305069691;4734.384530011145;4871.856383191384;4886.975565122223;3948.407738839197;3973.7814420207164;4257.531671516131;4378.452693413324;4663.670853806717;2225;2529.8229123961146;2689.7175796541474;2704.472627837592;2712.4846279206886;4491.730496170175;5263.657862239019;5274.472552744694;5320.574354051667;5328.92006619901;4518.344839998182;5090.625954800364;5244.554212891947;5257.842264731988;5297.788088476418;4533.161365147458;4970.552650261494;5077.8730531737;5190.792368305226;5255.868603284948;4940.851774446773;5053.67151135175;5088.727818045489;5123.846284611468;5145;4352.521222665568;4369.64039418723;4535.4103966484145;4626.676132670816;5084.745762711776;4668.202741944308;4846.95153048465;4907.386576677915;5209.713465759268;5279.52484276417;3495.9475607865393;3527.795128044972;3544.9822750886083;3550.4496802995577;3562.399448402629;4337.934930976101;4602.665227084108;5061.595072394273;5123.231515272739;5161.690298582097;4966.776495057803;5170.457102004236;5894.440028197486;5894.587378883609;5942.257217847769;4452.198604251266;4728.503507131702;5646.435356464321;5858.736356863866;5942.821715348547;4933.939203071357;5350.785968561369;5718.085106382959;5779.826605201851;5811.360750317377;5546.750396232099;5862.912056319244;5868.295804503487;5881.17663527098;5888.234529511136;4902.455726446158;5069.366329208849;5728.598998070095;5742.224799651994;5779.393163717749 14:19:07 INFO - PROCESS | 1662 | |5;yui.html;661.6327937994413;710.2719712294897;748.981494871322;775.0544335910191;783.1111688233855;13136.671583210413;16084.919575402137;16086.5978350541;16104.275307611142;16141.515754527354;14393.352299146538;14493.420263189486;14642.780358294634;14668.413263469449;14692.559223223294;656.4617014048482;681.2982627893277;805.4442434720054;816.3632366753941;841.305922613843;678.6097993653644;743.7025189924019;843.852325842975;844.3540691371076;845.9280961118282;702.6767686864023;891.6344298837478;894.7181637784109;898.9011208767063;901.4275934781429;859.6432480520576;900.1178844732199;901.4456109492647;902.2826852652157;903.8779764731743;662.4203821656051;666.8466252761843;667.6194569095618;669.517947078103;672.492268337407;14923.925380373137;15057.698846023019;15298.617534561614;15412.306446209948;15447.845521544865;14412;14430.350634221486;14454.78317825223;14460.421583136662;14609.415623375053;11698.41507924609;11763.529458821637;11787.834898640733;11823.822642660367;12108.15242932995;3755.324041672498;3758.2859256741403;3791.8862434127022;3811.3711237645757;3943.093088589612;88891.11108888892;109351;110551;113113.43443282813;113152.43423782829;10816.837747433638;10843.78312433747;10952.288101273392;10958.616448424425;11269.267497612629;21975;22015.889920550293;22024.889875550518;22029.559408812056;22041;7528.247175282428;7777.766666999999;7811.4922530035365;7888.013998250219;8213.34293256538;4192.916141677149;4204.62158405745;4275.273203555403;4289.656827453796;4344.304911214191;3283.589551306087;3372.730181585467;3383.8138902360606;3385.272166484218;3392.084137282969;591.2018774654268;591.6982339007051;604.338249616669;610.5420934299276;614.3978900677362;572.68788510262;584.447696926409;600.8618017855918;645.4287955159624;645.6513482719373;8129.959350203211;8633.740987770378;9013.77465563353;9220.953895230616;9349.345545811864;647.7538535356629;648.6918713611092;652.1782553982017;664.5547483186277;670.9865802683919;3855;4256.8297268109545;4593.26507758757;4641.74470404131;4713.151632706145;121976.23464926517;153150;161335;163175;170113;97419;108665.91334086716;123109;136765.31617341848;140966;105836;109374.99999999841;110815;115015.42492287653;115733;9595.520223988913;9695;9907.950460247652;9947.154491867983;10094.747631309276;46654;54136.729316353165;62747.6862615684;64282;64559.67720161369;93740.53129734307;94258.05741942493;95256.52371738097;99909.50045250019;103128.48435757773;10829.67510974672;11433.942830285796;11444.427778611203;11462.254953428;13299.800502992657;14595.92702036483;14673.559793206221;14828;14961.401543938557;15510.457134000257;13677.452901883813;13969.441222350992;13982.58052258434;14201.147931124166;14514.34685439179;13285.468581257028;13641.386137623633;13711.862881371058;13752.587422377344;14405.991580589258;13090.214587124803;13380.531681391105;13624;14032.158070515801;14205.076670016417;11068.723281918017;11251.38117403518;11307.943460282646;11314.943425282821;11894.762104758029;9722.659706910224;10467.424291664032;10657.840132398176;11299.604513841976;11513.712157195801 14:19:07 INFO - PROCESS | 1662 | __end_tp_report 14:19:07 INFO - PROCESS | 1662 | __start_cc_report 14:19:07 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,377 14:19:07 INFO - PROCESS | 1662 | __end_cc_report 14:19:07 INFO - PROCESS | 1662 | __startTimestamp1476220747874__endTimestamp 14:19:07 INFO - PROCESS | 1662 | 14:19:07 INFO - PROCESS | 1662 | ------- Summary: start ------- 14:19:07 INFO - PROCESS | 1662 | Number of tests: 6 14:19:07 INFO - PROCESS | 1662 | 14:19:07 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5774.61 Median:6020.79 stddev:647.23 (10.7%) stddev-sans-first:616.29 14:19:07 INFO - PROCESS | 1662 | Values: 3176.2 3368.4 3440.1 3456.7 3473.9 5597.5 5703.0 5794.8 5829.6 5840.4 5633.4 5780.6 5800.1 5800.2 5807.7 5202.4 5261.6 5289.4 5303.8 5307.9 5262.7 5272.9 5324.0 5349.9 5383.1 5136.3 5326.1 5336.6 5369.8 5437.2 5157.8 5198.6 5229.1 5241.8 5285.8 5132.5 5183.6 5187.9 5244.3 5263.8 5589.2 5804.4 5869.4 5872.0 5886.5 6152.8 6180.0 6260.9 6272.6 6305.2 6228.6 6254.8 6278.6 6282.8 6284.8 5650.4 5719.6 5917.9 5936.2 5939.4 6229.9 6230.3 6276.3 6345.9 6352.1 6215.6 6223.3 6235.6 6236.6 6266.9 6079.1 6300.1 6306.9 6314.2 6336.7 6020.8 6184.8 6195.9 6208.4 6215.7 6216.4 6252.4 6262.6 6272.7 6293.8 4969.2 4981.8 4984.0 4988.4 5010.8 4295.0 4382.5 4387.6 4396.0 4504.0 5896.0 5966.5 5970.0 5970.6 6033.5 6256.7 6283.7 6284.8 6291.9 6292.6 4913.6 5705.9 5913.3 5943.3 5956.1 5429.3 5471.2 5477.3 5489.4 5526.9 6334.2 6381.0 6401.9 6415.0 6428.0 6121.7 6171.0 6215.2 6282.2 6305.2 5948.7 6267.2 6362.4 6397.7 6415.4 4825.2 5804.7 6250.9 6263.1 6264.8 5260.4 5620.8 6162.5 6196.5 6212.8 5969.1 6161.6 6181.6 6223.9 6236.6 6079.0 6108.5 6142.4 6149.6 6169.7 5601.6 5826.2 6025.4 6054.4 6121.8 6170.8 6203.0 6213.0 6215.3 6239.4 6065.8 6096.4 6102.1 6104.7 6118.6 14:19:07 INFO - PROCESS | 1662 | 14:19:07 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:66921.71 Median:40875.00 stddev:140892.22 (344.7%) stddev-sans-first:141199.08 14:19:07 INFO - PROCESS | 1662 | Values: 452.6 484.6 527.3 541.5 556.7 39043.4 40864.0 40885.0 40897.8 41105.0 42099.4 42296.8 42749.4 42792.6 42918.8 9515.8 9678.0 9688.6 9719.6 9733.7 9581.8 9590.6 9643.2 9648.3 9679.7 9660.7 9682.6 9717.2 9727.2 9748.2 9601.9 9606.0 9636.2 9662.4 9680.4 9504.7 9603.5 9605.2 9628.2 9651.5 40533.6 40795.0 40865.0 40906.6 41042.6 3146.3 3423.7 3585.6 3882.0 4003.9 29564.3 30181.8 30855.5 31195.0 31763.5 3261.3 3276.8 3287.1 3336.3 3405.9 477480.6 484299.0 489284.0 490796.0 491761.0 59313.0 59725.0 59773.0 59848.7 60130.7 60832.7 62780.0 63262.0 63312.0 63328.7 15752.5 15931.3 15999.4 16019.4 16082.5 12350.3 12405.6 12407.7 12422.0 12427.1 16705.7 16786.3 16839.3 16843.7 16981.6 5850.7 6772.2 6807.9 6830.6 6904.9 853.6 957.3 982.7 1052.4 1135.2 26190.0 28174.0 28859.0 29746.1 29903.6 929.7 1004.5 1017.2 1049.2 1100.9 15310.8 15379.8 15506.0 15552.6 15778.8 565331.0 566134.0 572847.0 576832.0 581371.1 56913.7 57148.7 57195.7 57224.7 57409.3 1320.8 1357.9 1361.5 1368.7 1380.0 23641.0 25438.0 29462.5 29688.0 30023.8 48654.8 48976.0 49090.5 49180.8 49240.5 521897.0 522390.0 523913.0 526442.0 526660.0 26483.0 40211.6 40277.0 40677.0 40692.0 46750.5 46988.8 48107.5 48110.0 48188.3 38677.0 38750.0 39081.0 40015.2 40286.6 38395.4 38797.0 38819.6 39890.6 40049.0 37750.4 38031.0 38497.2 38716.6 39725.0 30703.5 34309.5 34501.8 35040.0 35117.0 23173.9 23254.9 23413.9 23635.6 23644.5 14:19:07 INFO - PROCESS | 1662 | 14:19:07 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:33219.91 Median:375.95 stddev:95046.11 (25281.7%) stddev-sans-first:95280.73 14:19:07 INFO - PROCESS | 1662 | Values: 367.8 371.2 374.0 377.9 394.3 18936.7 19585.6 20014.9 20188.7 20215.9 19887.3 19978.0 20094.7 20389.6 20446.0 4988.4 5768.0 5790.5 5977.9 6074.1 5971.4 6064.4 6118.2 6122.3 6126.5 5770.0 5901.0 5944.4 5953.3 5954.4 5735.3 5929.3 5935.3 5959.2 5990.7 5134.4 5148.9 5166.7 5166.9 5186.5 24060.8 24958.3 24985.6 25210.5 25423.7 2199.0 2293.3 2332.0 2343.0 2381.7 15251.5 15457.1 15502.6 15913.4 15951.9 1821.5 1887.2 1894.2 1898.3 1901.8 89888.6 109528.4 109892.4 110427.9 110540.8 10366.6 10574.9 10785.0 10786.0 11020.6 13306.7 13334.9 13350.0 13388.0 13418.4 7620.9 8008.7 8230.9 8490.1 8612.0 8362.3 8478.3 8518.9 8633.6 8667.3 6482.4 6553.0 6584.6 6586.0 6701.1 1793.9 1810.5 1834.5 1839.4 1841.1 934.9 937.0 944.7 977.3 985.9 17835.9 21896.9 21969.5 22095.4 22159.9 836.0 857.9 916.6 934.3 992.9 10973.3 11535.4 11565.9 11609.7 11646.4 566859.0 567112.0 570157.0 577752.0 580194.0 108214.5 112669.3 113839.0 115087.0 116673.0 967.3 968.5 1003.2 1004.2 1004.9 19013.4 19155.5 19214.4 19248.0 19269.3 51180.7 51243.5 51321.0 51702.7 52407.0 64599.4 68898.0 69301.0 69453.7 70297.0 6097.0 6980.8 7427.2 7845.0 7976.1 10156.5 11075.9 12902.7 13086.3 13121.7 5152.1 5281.6 9176.6 10236.9 11041.8 8971.6 9158.7 9449.0 10920.6 11309.3 9895.7 9954.7 11258.5 11398.6 11599.9 7282.3 7403.3 7445.1 7596.4 7610.5 9674.0 10177.5 10235.7 11330.9 11510.9 14:19:07 INFO - PROCESS | 1662 | 14:19:07 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5606.78 Median:4773.62 stddev:3201.05 (67.1%) stddev-sans-first:3194.23 14:19:07 INFO - PROCESS | 1662 | Values: 1374.7 1512.2 1593.5 1635.2 1737.0 6933.5 7813.0 8124.8 8915.8 8964.5 8275.3 8657.2 8689.7 8754.1 8761.0 2338.6 2355.4 2356.2 2358.8 2365.9 1959.8 2329.4 2354.0 2358.5 2361.3 2284.1 2331.8 2344.3 2346.3 2349.5 2301.7 2310.8 2323.6 2326.6 2338.1 1944.3 2306.2 2311.5 2315.6 2327.2 8739.9 8787.3 8797.3 8799.8 8820.3 5126.2 5226.2 5278.7 5288.4 5304.9 3447.1 3533.3 3642.7 3644.3 3663.4 1046.5 1086.9 1095.8 1112.0 1115.6 11491.6 11643.5 11712.3 11733.8 11763.4 4497.8 4539.9 4759.5 4773.1 4786.8 8677.5 8869.7 9382.3 9689.7 10002.3 4670.7 4711.4 4933.6 5286.4 5308.0 3848.6 3979.2 4283.4 4489.2 4830.4 3851.1 3870.2 3978.0 3984.5 4007.1 1264.7 1266.2 1268.4 1277.0 1279.9 737.8 742.5 810.2 892.8 895.2 5043.6 5428.2 5600.8 5813.0 5871.5 926.7 941.2 951.4 953.4 961.7 3325.2 3729.7 3782.4 3876.3 3894.5 10377.6 10669.9 10808.7 10816.4 10872.3 10655.9 10738.2 10774.6 10784.6 10810.4 8191.2 8319.4 8325.7 8350.0 8357.6 4515.8 4807.3 5362.2 5558.6 5673.0 9962.0 9985.2 10061.4 10082.7 10199.7 10003.5 10157.0 10276.7 10326.6 10369.4 6537.2 6791.0 6867.5 7070.4 7205.8 8314.8 8533.8 8546.7 8550.2 8581.0 7385.2 7480.0 7483.7 7534.8 7589.8 7246.6 7313.2 7383.4 7462.4 7486.0 6491.6 6634.3 7046.5 7054.0 7054.9 4774.1 4837.4 4843.0 4849.3 4939.7 4772.4 4858.8 4862.0 4915.0 4943.4 14:19:07 INFO - PROCESS | 1662 | 14:19:07 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4415.30 Median:4829.60 stddev:1352.82 (28.0%) stddev-sans-first:1320.81 14:19:07 INFO - PROCESS | 1662 | Values: 296.2 297.5 305.1 309.0 309.8 5328.6 5528.8 5538.5 5608.9 5633.2 5336.8 5504.0 5555.9 5566.3 5581.9 3906.7 4197.6 4261.8 4267.9 4268.7 4130.8 4219.9 4237.8 4260.0 4265.6 3673.8 3989.9 4004.5 4133.8 4233.6 4033.5 4190.6 4195.8 4216.3 4243.5 2850.0 3255.1 3672.8 3680.4 3680.8 3801.5 4837.3 4993.4 5209.6 5283.8 4205.5 4815.9 4829.2 4830.0 4858.7 5299.6 5693.1 5758.3 5841.3 5846.2 3446.3 3690.0 3898.8 3935.6 4010.8 4868.0 6120.5 6139.7 6164.2 6173.9 3649.4 3869.7 4044.6 4109.0 4113.4 3459.6 3490.6 4092.9 4142.8 4145.3 4531.6 4826.4 4830.3 4854.3 4854.5 5854.7 5864.0 5887.4 5936.0 5945.7 1285.3 1324.1 1397.2 1414.4 1428.3 790.6 790.8 859.8 862.6 871.8 4282.0 4520.3 4684.5 4713.7 4732.4 4305.2 4410.3 4734.4 4871.9 4887.0 3948.4 3973.8 4257.5 4378.5 4663.7 2225.0 2529.8 2689.7 2704.5 2712.5 4491.7 5263.7 5274.5 5320.6 5328.9 4518.3 5090.6 5244.6 5257.8 5297.8 4533.2 4970.6 5077.9 5190.8 5255.9 4940.9 5053.7 5088.7 5123.8 5145.0 4352.5 4369.6 4535.4 4626.7 5084.7 4668.2 4847.0 4907.4 5209.7 5279.5 3495.9 3527.8 3545.0 3550.4 3562.4 4337.9 4602.7 5061.6 5123.2 5161.7 4966.8 5170.5 5894.4 5894.6 5942.3 4452.2 4728.5 5646.4 5858.7 5942.8 4933.9 5350.8 5718.1 5779.8 5811.4 5546.8 5862.9 5868.3 5881.2 5888.2 4902.5 5069.4 5728.6 5742.2 5779.4 14:19:07 INFO - PROCESS | 1662 | 14:19:07 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:24812.13 Median:3378.27 stddev:39847.98 (1179.5%) stddev-sans-first:39918.51 14:19:07 INFO - PROCESS | 1662 | Values: 661.6 710.3 749.0 775.1 783.1 13136.7 16084.9 16086.6 16104.3 16141.5 14393.4 14493.4 14642.8 14668.4 14692.6 656.5 681.3 805.4 816.4 841.3 678.6 743.7 843.9 844.4 845.9 702.7 891.6 894.7 898.9 901.4 859.6 900.1 901.4 902.3 903.9 662.4 666.8 667.6 669.5 672.5 14923.9 15057.7 15298.6 15412.3 15447.8 14412.0 14430.4 14454.8 14460.4 14609.4 11698.4 11763.5 11787.8 11823.8 12108.2 3755.3 3758.3 3791.9 3811.4 3943.1 88891.1 109351.0 110551.0 113113.4 113152.4 10816.8 10843.8 10952.3 10958.6 11269.3 21975.0 22015.9 22024.9 22029.6 22041.0 7528.2 7777.8 7811.5 7888.0 8213.3 4192.9 4204.6 4275.3 4289.7 4344.3 3283.6 3372.7 3383.8 3385.3 3392.1 591.2 591.7 604.3 610.5 614.4 572.7 584.4 600.9 645.4 645.7 8130.0 8633.7 9013.8 9221.0 9349.3 647.8 648.7 652.2 664.6 671.0 3855.0 4256.8 4593.3 4641.7 4713.2 121976.2 153150.0 161335.0 163175.0 170113.0 97419.0 108665.9 123109.0 136765.3 140966.0 105836.0 109375.0 110815.0 115015.4 115733.0 9595.5 9695.0 9908.0 9947.2 10094.7 46654.0 54136.7 62747.7 64282.0 64559.7 93740.5 94258.1 95256.5 99909.5 103128.5 10829.7 11433.9 11444.4 11462.3 13299.8 14595.9 14673.6 14828.0 14961.4 15510.5 13677.5 13969.4 13982.6 14201.1 14514.3 13285.5 13641.4 13711.9 13752.6 14406.0 13090.2 13380.5 13624.0 14032.2 14205.1 11068.7 11251.4 11307.9 11314.9 11894.8 9722.7 10467.4 10657.8 11299.6 11513.7 14:19:07 INFO - PROCESS | 1662 | -------- Summary: end -------- 14:19:07 INFO - PROCESS | 1662 | 14:19:08 INFO - TEST-INFO | 1662: exit 0 14:19:08 INFO - TEST-OK | dromaeo_css | took 1114609ms 14:19:08 INFO - TEST-START | kraken 14:19:08 INFO - operating with platform_type : mac_ 14:19:08 INFO - Initialising browser for kraken test... 14:19:08 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpKEfSzm/profile http://localhost:49273/getInfo.html) 14:19:10 INFO - PROCESS | 1670 | 2016-10-11 14:19:10.960 plugin-container[1671:11001] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8837, name = 'com.apple.tsm.portname' 14:19:10 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:10 INFO - PROCESS | 1670 | 2016-10-11 14:19:10.964 plugin-container[1671:11001] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 14:19:10 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:10 INFO - PROCESS | 1670 | 2016-10-11 14:19:10.964 plugin-container[1671:11001] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:19:11 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 14:19:11 INFO - PROCESS | 1670 | colorDepth:24 14:19:11 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 14:19:11 INFO - PROCESS | 1670 | __metrics 14:19:11 ERROR - PROCESS | 1670 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 14:19:11 INFO - TEST-INFO | 1670: exit 0 14:19:11 INFO - Browser initialized. 14:19:11 INFO - Running cycle 1/1 for kraken test... 14:19:11 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpKEfSzm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:19:14 INFO - PROCESS | 1672 | 2016-10-11 14:19:14.009 plugin-container[1673:11116] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 14:19:14 INFO - PROCESS | 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:14 INFO - PROCESS | 1672 | 2016-10-11 14:19:14.012 plugin-container[1673:11116] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 14:19:14 INFO - PROCESS | 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:14 INFO - PROCESS | 1672 | 2016-10-11 14:19:14.012 plugin-container[1673:11116] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:19:23 INFO - PROCESS | 1672 | RSS: Main: 238460928 14:19:23 INFO - PROCESS | 1672 | 14:19:26 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 14:19:27 INFO - PROCESS | 1672 | RSS: Main: 252370944 14:19:27 INFO - PROCESS | 1672 | 14:19:30 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 14:19:30 INFO - PROCESS | 1672 | RSS: Main: 259846144 14:19:30 INFO - PROCESS | 1672 | 14:19:34 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 14:19:34 INFO - PROCESS | 1672 | RSS: Main: 260407296 14:19:34 INFO - PROCESS | 1672 | 14:19:36 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 14:19:37 INFO - PROCESS | 1672 | RSS: Main: 262627328 14:19:37 INFO - PROCESS | 1672 | 14:19:39 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:19:40 INFO - PROCESS | 1672 | RSS: Main: 263266304 14:19:40 INFO - PROCESS | 1672 | 14:19:42 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 14:19:43 INFO - PROCESS | 1672 | RSS: Main: 263991296 14:19:43 INFO - PROCESS | 1672 | 14:19:46 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 14:19:47 INFO - PROCESS | 1672 | RSS: Main: 264138752 14:19:47 INFO - PROCESS | 1672 | 14:19:50 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 14:19:50 INFO - PROCESS | 1672 | RSS: Main: 264556544 14:19:50 INFO - PROCESS | 1672 | 14:19:52 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:19:53 INFO - PROCESS | 1672 | RSS: Main: 264101888 14:19:53 INFO - PROCESS | 1672 | 14:19:55 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:19:55 INFO - PROCESS | 1672 | RSS: Main: 264146944 14:19:55 INFO - PROCESS | 1672 | 14:19:57 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:19:58 INFO - PROCESS | 1672 | RSS: Main: 264179712 14:19:58 INFO - PROCESS | 1672 | 14:20:01 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:20:02 INFO - PROCESS | 1672 | RSS: Main: 264536064 14:20:02 INFO - PROCESS | 1672 | 14:20:05 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:20:06 INFO - PROCESS | 1672 | RSS: Main: 264536064 14:20:06 INFO - PROCESS | 1672 | 14:20:07 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 14:20:08 INFO - PROCESS | 1672 | RSS: Main: 266039296 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | __start_tp_report 14:20:08 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 14:20:08 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 14:20:08 INFO - PROCESS | 1672 | |i|pagename|runs| 14:20:08 INFO - PROCESS | 1672 | |0;ai-astar;119;120;117;116;119;116;116;117;116;107 14:20:08 INFO - PROCESS | 1672 | |1;audio-beat-detection;147;125;143;153;140;145;146;117;140;136 14:20:08 INFO - PROCESS | 1672 | |2;audio-dft;192;198;198;206;266;270;219;197;196;194 14:20:08 INFO - PROCESS | 1672 | |3;audio-fft;84;60;51;82;54;79;83;84;75;85 14:20:08 INFO - PROCESS | 1672 | |4;audio-oscillator;76;102;117;98;57;85;124;100;101;95 14:20:08 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;93;90;93;90;92;88;93;95;93;90 14:20:08 INFO - PROCESS | 1672 | |6;imaging-darkroom;141;96;98;93;92;127;150;139;100;97 14:20:08 INFO - PROCESS | 1672 | |7;imaging-desaturate;89;112;122;91;92;90;112;134;147;119 14:20:08 INFO - PROCESS | 1672 | |8;json-parse-financial;53;67;79;80;84;70;59;58;57;57 14:20:08 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;71;71;71;83;79;71;71;71;73;73 14:20:08 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;95;107;108;87;87;86;89;100;106;87 14:20:08 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;145;136;131;145;155;154;163;160;210;146 14:20:08 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;178;180;176;173;174;162;221;238;184;173 14:20:08 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;75;68;73;82;90;89;87;71;70;70 14:20:08 INFO - PROCESS | 1672 | __end_tp_report 14:20:08 INFO - PROCESS | 1672 | __start_cc_report 14:20:08 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,963 14:20:08 INFO - PROCESS | 1672 | __end_cc_report 14:20:08 INFO - PROCESS | 1672 | __startTimestamp1476220808150__endTimestamp 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | ------- Summary: start ------- 14:20:08 INFO - PROCESS | 1672 | Number of tests: 14 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:116.30 Median:117.00 stddev:3.59 (3.1%) stddev-sans-first:3.67 14:20:08 INFO - PROCESS | 1672 | Values: 119.0 120.0 117.0 116.0 119.0 116.0 116.0 117.0 116.0 107.0 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:144.00 stddev:10.81 (7.5%) stddev-sans-first:11.09 14:20:08 INFO - PROCESS | 1672 | Values: 147.0 125.0 143.0 153.0 140.0 145.0 146.0 117.0 140.0 136.0 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:213.60 Median:202.00 stddev:29.70 (14.7%) stddev-sans-first:30.45 14:20:08 INFO - PROCESS | 1672 | Values: 192.0 198.0 198.0 206.0 266.0 270.0 219.0 197.0 196.0 194.0 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:73.70 Median:82.50 stddev:13.40 (16.2%) stddev-sans-first:13.68 14:20:08 INFO - PROCESS | 1672 | Values: 84.0 60.0 51.0 82.0 54.0 79.0 83.0 84.0 75.0 85.0 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:66.50 stddev:19.28 (29.0%) stddev-sans-first:19.12 14:20:08 INFO - PROCESS | 1672 | Values: 76.0 102.0 117.0 98.0 57.0 85.0 124.0 100.0 101.0 95.0 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:93.00 stddev:2.11 (2.3%) stddev-sans-first:2.19 14:20:08 INFO - PROCESS | 1672 | Values: 93.0 90.0 93.0 90.0 92.0 88.0 93.0 95.0 93.0 90.0 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:113.30 Median:92.50 stddev:23.10 (25.0%) stddev-sans-first:22.22 14:20:08 INFO - PROCESS | 1672 | Values: 141.0 96.0 98.0 93.0 92.0 127.0 150.0 139.0 100.0 97.0 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:110.80 Median:118.00 stddev:20.27 (17.2%) stddev-sans-first:19.90 14:20:08 INFO - PROCESS | 1672 | Values: 89.0 112.0 122.0 91.0 92.0 90.0 112.0 134.0 147.0 119.0 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:66.40 Median:68.50 stddev:11.30 (16.5%) stddev-sans-first:10.89 14:20:08 INFO - PROCESS | 1672 | Values: 53.0 67.0 79.0 80.0 84.0 70.0 59.0 58.0 57.0 57.0 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:73.40 Median:72.00 stddev:4.20 (5.8%) stddev-sans-first:4.36 14:20:08 INFO - PROCESS | 1672 | Values: 71.0 71.0 71.0 83.0 79.0 71.0 71.0 71.0 73.0 73.0 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:95.20 Median:87.00 stddev:9.24 (10.6%) stddev-sans-first:9.80 14:20:08 INFO - PROCESS | 1672 | Values: 95.0 107.0 108.0 87.0 87.0 86.0 89.0 100.0 106.0 87.0 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:154.50 Median:154.50 stddev:21.94 (14.2%) stddev-sans-first:23.00 14:20:08 INFO - PROCESS | 1672 | Values: 145.0 136.0 131.0 145.0 155.0 154.0 163.0 160.0 210.0 146.0 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.90 Median:179.00 stddev:24.02 (13.4%) stddev-sans-first:25.30 14:20:08 INFO - PROCESS | 1672 | Values: 178.0 180.0 176.0 173.0 174.0 162.0 221.0 238.0 184.0 173.0 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.50 Median:78.50 stddev:8.63 (11.0%) stddev-sans-first:9.11 14:20:08 INFO - PROCESS | 1672 | Values: 75.0 68.0 73.0 82.0 90.0 89.0 87.0 71.0 70.0 70.0 14:20:08 INFO - PROCESS | 1672 | -------- Summary: end -------- 14:20:08 INFO - PROCESS | 1672 | 14:20:08 INFO - TEST-INFO | 1672: exit 0 14:20:09 INFO - TEST-OK | kraken | took 60506ms 14:20:09 INFO - SUITE-END | took 1175s 14:20:09 INFO - Completed test suite (00:19:35) 14:20:09 INFO - javascript benchmark 14:20:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7275.781999798053, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3176.2218256527153, 3368.366342323974, 3440.088376580207, 3456.7407444441674, 3473.8784142555014, 5597.524210442107, 5703.0, 5794.797182098628, 5829.562782791291, 5840.415958404157, 5633.408492108331, 5780.624259423134, 5800.071988481844, 5800.15897694834, 5807.651540907548, 5202.401723801754, 5261.552768014704, 5289.391719952217, 5303.814366497173, 5307.893842123155, 5262.684238945656, 5272.868178295535, 5324.0, 5349.893002139955, 5383.084875571162, 5136.280920671072, 5326.094563924142, 5336.573074154097, 5369.7583608737505, 5437.157240627689, 5157.8452646420665, 5198.610104242197, 5229.058769421503, 5241.79032838686, 5285.814996475105, 5132.486751324875, 5183.559397451221, 5187.870303242449, 5244.318238628992, 5263.842084737464, 5589.186828890678, 5804.390538993387, 5869.442402971711, 5872.031114866042, 5886.499647529922, 6152.815415537541, 6179.969100154515, 6260.906086408662, 6272.6236425814595, 6305.180326557518, 6228.626282423069, 6254.843628909268, 6278.591891527036, 6282.78010269643, 6284.811455656338, 5650.3502097258515, 5719.57103217252, 5917.852053698692, 5936.168936348915, 5939.406059394026, 6229.940910045303, 6230.283517395467, 6276.278228003838, 6345.904811427832, 6352.109790045518, 6215.564910456315, 6223.284322302993, 6235.625862448177, 6236.563440559117, 6266.874662506724, 6079.088136779518, 6300.086487459293, 6306.936930630635, 6314.210723659543, 6336.714847831859, 6020.789272375446, 6184.81445556634, 6195.938040619536, 6208.379162083846, 6215.658138802319, 6216.44052035319, 6252.437280644764, 6262.5616206866, 6272.655003974825, 6293.779717709858, 4969.204927211629, 4981.825636102719, 4984.0, 4988.4263309719845, 5010.799568017239, 4294.9785251073545, 4382.452193475816, 4387.648988080946, 4395.956040439554, 4503.977480112579, 5896.0, 5966.49284810796, 5970.0, 5970.641761494324, 6033.456988871023, 6256.655883926428, 6283.654399008098, 6284.780032698834, 6291.905621415683, 6292.622442653455, 4913.606911447076, 5705.942940570624, 5913.349531551526, 5943.3462319144855, 5956.106584012433, 5429.321334833146, 5471.151971444432, 5477.260569823103, 5489.368722596872, 5526.917096243559, 6334.176557047738, 6381.010943303795, 6401.935980640134, 6415.0, 6427.9715245559555, 6121.666376154749, 6170.969145154246, 6215.223097112861, 6282.1519094921605, 6305.211848518935, 5948.73230704628, 6267.185265915402, 6362.363763623601, 6397.712102955285, 6415.422611964946, 4825.203841366135, 5804.6807425592, 6250.937490625218, 6263.060540918898, 6264.843378915381, 5260.421353651172, 5620.803271885465, 6162.537809664203, 6196.504279657706, 6212.782552610818, 5969.074793407029, 6161.599496032743, 6181.598196117238, 6223.937760622336, 6236.594621349598, 6078.9880617942545, 6108.511319094373, 6142.416470435302, 6149.569530132848, 6169.691515424301, 5601.60788744784, 5826.242588463472, 6025.367336429787, 6054.3642917493025, 6121.84695382619, 6170.845728856635, 6202.968985155046, 6213.00591905293, 6215.347388524321, 6239.438450539473, 6065.8180254592435, 6096.390360963868, 6102.145699601971, 6104.725287362168, 6118.571699981137], "value": 5733.797051206058, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.6469353903956, 484.6123101518786, 527.3223907279147, 541.4828838459272, 556.7299859568109, 39043.414348784754, 40863.97840054, 40885.0, 40897.79551102248, 41105.0, 42099.368509472304, 42296.78851605738, 42749.35875961855, 42792.57207427925, 42918.785406073, 9515.762105947355, 9678.0, 9688.612455501807, 9719.570107473131, 9733.707988760349, 9581.760455988586, 9590.616375344978, 9643.180329671952, 9648.324617276794, 9679.661211857587, 9660.661876834312, 9682.564284607175, 9717.174040206592, 9727.173190278834, 9748.220142388627, 9601.90398096017, 9605.951970240174, 9636.180924621416, 9662.371945823534, 9680.419174849496, 9504.667336643184, 9603.4718090505, 9605.18355939746, 9628.18160456362, 9651.517424128766, 40533.58132465379, 40795.0, 40865.0, 40906.59093409058, 41042.589574104175, 3146.3036138365946, 3423.6747508986855, 3585.587657419404, 3882.0, 4003.890177302335, 29564.26089347762, 30181.849090754626, 30855.537166942515, 31195.0, 31763.52354714681, 3261.3314270574474, 3276.770626056177, 3287.112479630486, 3336.3160552086765, 3405.863765449379, 477480.6125969348, 484299.0, 489284.0, 490796.0, 491761.0, 59313.0, 59725.0, 59773.0, 59848.70075649594, 60130.69934650299, 60832.695836521416, 62780.0, 63262.0, 63312.0, 63328.68335658292, 15752.527424177293, 15931.283092260875, 15999.360025599079, 16019.439319623758, 16082.517524474284, 12350.320732359627, 12405.565805196775, 12407.689807754698, 12422.006239500819, 12427.067969902293, 16705.749413758804, 16786.32854685799, 16839.32642694303, 16843.663126737396, 16981.575460613585, 5850.707464626752, 6772.2211945625895, 6807.931920680829, 6830.624315662587, 6904.860697984876, 853.6158728572167, 957.3203025851584, 982.7493989032894, 1052.4316868890867, 1135.1713249328036, 26189.95240190409, 28174.01390951307, 28859.0, 29746.107616771533, 29903.551446728317, 929.716436486878, 1004.5288590068368, 1017.2452519404117, 1049.186880167876, 1100.944952752359, 15310.846891530942, 15379.769303460456, 15506.0, 15552.608223057543, 15778.842211577967, 565331.0, 566134.0, 572847.0, 576832.0, 581371.0931445316, 56913.71543142258, 57148.71425642928, 57195.71402142962, 57224.71387643118, 57409.27772166842, 1320.795276732108, 1357.8642135786342, 1361.5098564516766, 1368.7057282684073, 1379.9862001379859, 23641.0, 25438.0, 29462.549428355822, 29688.0, 30023.849880750455, 48654.75672621614, 48976.0, 49090.50909490859, 49180.75409622929, 49240.50759492359, 521897.0, 522390.0, 523913.0, 526442.0, 526660.0, 26483.0, 40211.59788402078, 40277.0, 40677.0, 40692.0, 46750.53249467462, 46988.82527936829, 48107.518924810305, 48110.03779924312, 48188.31340903052, 38677.0, 38750.02499549995, 39081.0, 40015.1996960065, 40286.597134028285, 38395.42406863955, 38797.0, 38819.6118038816, 39890.60109398985, 40049.0, 37750.43374349332, 38031.0, 38497.23005539818, 38716.6128338713, 39725.0, 30703.539446908762, 34309.48535772015, 34501.827490862386, 35040.0, 35117.0, 23173.884130579238, 23254.88372558194, 23413.882930585238, 23635.64546531838, 23644.52710945737], "value": 19868.30361574519, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.7756568493219, 371.2074720471792, 373.9551253849539, 377.9414190800427, 394.27256711367517, 18936.67355629157, 19585.608287834253, 20014.899925500387, 20188.69716954247, 20215.898920505413, 19887.30394436195, 19978.0, 20094.69857952132, 20389.592208155827, 20445.97770111496, 4988.351514303135, 5767.961766881959, 5790.478856902867, 5977.940220597804, 6074.119252708355, 5971.402859714038, 6064.423879731418, 6118.204633397674, 6122.265328160627, 6126.479249263818, 5770.04794208955, 5901.0, 5944.375840536746, 5953.315368732608, 5954.374790646984, 5735.311762588474, 5929.347771745104, 5935.317438494563, 5959.165716799652, 5990.73041713124, 5134.358205224347, 5148.897022059576, 5166.664166829181, 5166.9224961625605, 5186.507281808223, 24060.79696015192, 24958.251252462273, 24985.62521562178, 25210.49579008428, 25423.74576254251, 2198.9890050549807, 2293.334932869466, 2331.9883400583058, 2343.0, 2381.7261014983324, 15251.466198682992, 15457.072575645498, 15502.61243468911, 15913.363465461367, 15951.92024039884, 1821.4899828048167, 1887.160213704902, 1894.2233684189416, 1898.3450709505198, 1901.8003109673423, 89888.55055724745, 109528.40423351257, 109892.44522488739, 110427.89572104256, 110540.78918421587, 10366.637167699095, 10574.89425105739, 10785.0, 10786.0, 11020.614278500214, 13306.667333316744, 13334.933325333312, 13349.998750093782, 13387.995900307516, 13418.396172172275, 7620.923790762133, 8008.6785680363255, 8230.876536851953, 8490.108538603357, 8611.95694021526, 8362.331013519026, 8478.321734261244, 8518.870282429392, 8633.568321583896, 8667.263282621048, 6482.416582507597, 6552.967235163794, 6584.637844918576, 6586.0, 6701.0953521274005, 1793.8744287899722, 1810.5292623917876, 1834.5321942904475, 1839.4456684101913, 1841.1070630744077, 934.9252059835197, 936.9531523423801, 944.7118628818139, 977.3354119199013, 985.9211263098935, 17835.910820445813, 21896.90515474252, 21969.466030334203, 22095.44761380946, 22159.889200553895, 835.9926288821955, 857.8884744983237, 916.5921165081475, 934.2946075712794, 992.8808542974889, 10973.257779103355, 11535.365554894393, 11565.884341156649, 11609.709757255967, 11646.359450230319, 566859.0, 567112.0, 570157.0, 577752.0, 580194.0, 108214.45892770644, 112669.30996035066, 113839.0, 115087.0, 116673.0, 967.2648786921849, 968.4528241543646, 1003.1773945364731, 1004.1815920025156, 1004.9346792458467, 19013.42959711211, 19155.521111972314, 19214.423567293004, 19248.0, 19269.325573604856, 51180.74409627928, 51243.48756512387, 51321.0, 51702.741486292325, 52407.0, 64599.38501537499, 68898.0, 69301.0, 69453.65273173804, 70297.0, 6097.0, 6980.755673551402, 7427.205649000481, 7844.980152580128, 7976.122626511195, 10156.49217539135, 11075.947784960415, 12902.67743306425, 13086.345682715635, 13121.737565248832, 5152.078839570164, 5281.57747380201, 9176.587053582738, 10236.87394386602, 11041.779164416826, 8971.551422428722, 9158.725238242863, 9448.95275523618, 10920.563177472812, 11309.321440713584, 9895.713557237801, 9954.701358959242, 11258.493367798632, 11398.637401966605, 11599.884001160219, 7282.271772822675, 7403.259674032553, 7445.10658720957, 7596.354309883722, 7610.543367397974, 9673.951630241803, 10177.49112544355, 10235.720534933133, 11330.94334528322, 11510.884891150981], "value": 9415.254524461487, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1374.6700791809965, 1512.1909778268628, 1593.4502596604168, 1635.2396135796853, 1736.9826301736978, 6933.549319294249, 7812.960935195316, 8124.837503249932, 8915.777105572364, 8964.46213227206, 8275.29659978901, 8657.220850123487, 8689.695860644879, 8754.124587541242, 8760.95619521903, 2338.649202619604, 2355.37582540626, 2356.175338631482, 2358.78770910618, 2365.9171928982487, 1959.8138176873174, 2329.406001469623, 2353.976460235393, 2358.50471401006, 2361.338825128958, 2284.1091974130104, 2331.825113116515, 2344.2732752846587, 2346.272655476808, 2349.5418393413293, 2301.7007788987494, 2310.838241323108, 2323.6049871521795, 2326.6161083421216, 2338.1232037985756, 1944.3000519812856, 2306.2043594959714, 2311.4914718761847, 2315.6063469210108, 2327.197116994634, 8739.912600873973, 8787.297016238686, 8797.252233560155, 8799.78000549985, 8820.33847461443, 5126.154184559543, 5226.1638137898135, 5278.709670968095, 5288.365396152486, 5304.92042619361, 3447.120984149026, 3533.2580158166816, 3642.7267954903486, 3644.2711457708565, 3663.3955397359405, 1046.5287672578966, 1086.8913108689069, 1095.808233559124, 1112.004755743613, 1115.5928086248541, 11491.597794077166, 11643.534258629645, 11712.297262164297, 11733.765324693459, 11763.35301558414, 4497.752623605733, 4539.909201816011, 4759.50025247344, 4773.09311230865, 4786.8085276588545, 8677.522736249566, 8869.73390798277, 9382.343235973416, 9689.660861869941, 10002.349847259904, 4670.7389004969145, 4711.434627844681, 4933.580645645088, 5286.444923283077, 5307.9734601326745, 3848.615138486129, 3979.20415916812, 4283.378910058024, 4489.236829738952, 4830.396200474941, 3851.114243723959, 3870.2453021661067, 3977.960220397759, 3984.541777695544, 4007.1384652299894, 1264.7217612125319, 1266.2465832829453, 1268.397511182181, 1277.0485987939044, 1279.897608191361, 737.8155461134717, 742.476554029408, 810.1938571121772, 892.7991850960448, 895.2435192262528, 5043.613006423189, 5428.185772134132, 5600.803971860965, 5813.0, 5871.4715675589405, 926.6802952981211, 941.1999800169938, 951.4243882451156, 953.3517208298424, 961.7451375385389, 3325.218573635194, 3729.68297694699, 3782.3759079751535, 3876.2635099331037, 3894.539547669624, 10377.636782712569, 10669.89330106689, 10808.675739727822, 10816.351018938889, 10872.293300935415, 10655.946720266349, 10738.19463540222, 10774.622888198875, 10784.622538211283, 10810.351378917132, 8191.221833925768, 8319.376046796515, 8325.666973321, 8350.039745429232, 8357.58212089393, 4515.841945531891, 4807.254875494304, 5362.195670649434, 5558.638688485235, 5673.035583950656, 9961.950190249003, 9985.1512621428, 10061.446620435947, 10082.747931301776, 10199.694009179737, 10003.499825008867, 10157.035081667229, 10276.691699249035, 10326.638567650096, 10369.377837329785, 6537.182852143482, 6791.04925310447, 6867.450603951772, 7070.36366726997, 7205.7838264852135, 8314.833703326021, 8533.786655333666, 8546.743597692079, 8550.230479256898, 8580.970283566012, 7385.187629360658, 7480.0, 7483.70065197386, 7534.849303014019, 7589.810254743676, 7246.60143692102, 7313.232110628307, 7383.409327253699, 7462.403007759258, 7486.0, 6491.642959637265, 6634.336566343327, 7046.506744527834, 7054.012438258545, 7054.929450705427, 4774.140654682052, 4837.419509658864, 4842.95157048425, 4849.345338379274, 4939.728314942713, 4772.427308722976, 4858.82994095205, 4861.975690121527, 4914.967856750122, 4943.431505376784], "value": 4489.346964010741, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.1544789625619, 297.4734719546403, 305.09539216223897, 308.97882498342966, 309.7614836575838, 5328.573714102872, 5528.838943821792, 5538.4738449847255, 5608.9439105608935, 5633.211350410946, 5336.825898302367, 5503.9728149517805, 5555.9165313007625, 5566.276384070075, 5581.916271255935, 3906.687465002793, 4197.580241975808, 4261.78691065447, 4267.935980960288, 4268.716236813306, 4130.843363858125, 4219.945013746564, 4237.830486780541, 4259.957400425987, 4265.637420819234, 3673.787650075482, 3989.900252493682, 4004.479417675698, 4133.751974881502, 4233.618974292313, 4033.515978082649, 4190.575204430478, 4195.797206466599, 4216.262154123032, 4243.533211346749, 2850.0, 3255.137388592025, 3672.779633222015, 3680.429533422324, 3680.7791532508136, 3801.5275499229274, 4837.250226214907, 4993.375828021497, 5209.635325527214, 5283.762230699628, 4205.474315710536, 4815.881012354588, 4829.203181475053, 4830.0, 4858.708477491362, 5299.602529810241, 5693.060644993571, 5758.27957844898, 5841.3282472515775, 5846.181534585162, 3446.2590543033116, 3690.022144131807, 3898.8050597470015, 3935.58676338986, 4010.83956641734, 4867.975660121712, 6120.510359171256, 6139.693015349215, 6164.181566437866, 6173.888700033992, 3649.357788994963, 3869.6710779583486, 4044.6157615026527, 4108.958910410917, 4113.362428823537, 3459.567554055743, 3490.5636795400574, 4092.897677558085, 4142.751434913915, 4145.2952997990415, 4531.592156705912, 4826.444958829709, 4830.2513110467935, 4854.296127061556, 4854.451413008199, 5854.677992710356, 5863.9738045841805, 5887.381824908323, 5936.020556608198, 5945.702714864239, 1285.3059347952, 1324.1393094488467, 1397.196611948134, 1414.3776738235144, 1428.264443811438, 790.5533373643873, 790.7548659915329, 859.8108416148438, 862.6032025268321, 871.7994861181837, 4282.023993324609, 4520.344550040226, 4684.461286951976, 4713.717176969393, 4732.361131247238, 4305.160493703697, 4410.272305069691, 4734.384530011145, 4871.856383191384, 4886.975565122223, 3948.407738839197, 3973.7814420207164, 4257.531671516131, 4378.452693413324, 4663.670853806717, 2225.0, 2529.8229123961146, 2689.7175796541474, 2704.472627837592, 2712.4846279206886, 4491.730496170175, 5263.657862239019, 5274.472552744694, 5320.574354051667, 5328.92006619901, 4518.344839998182, 5090.625954800364, 5244.554212891947, 5257.842264731988, 5297.788088476418, 4533.161365147458, 4970.552650261494, 5077.8730531737, 5190.792368305226, 5255.868603284948, 4940.851774446773, 5053.67151135175, 5088.727818045489, 5123.846284611468, 5145.0, 4352.521222665568, 4369.64039418723, 4535.4103966484145, 4626.676132670816, 5084.745762711776, 4668.202741944308, 4846.95153048465, 4907.386576677915, 5209.713465759268, 5279.52484276417, 3495.9475607865393, 3527.795128044972, 3544.9822750886083, 3550.4496802995577, 3562.399448402629, 4337.934930976101, 4602.665227084108, 5061.595072394273, 5123.231515272739, 5161.690298582097, 4966.776495057803, 5170.457102004236, 5894.440028197486, 5894.587378883609, 5942.257217847769, 4452.198604251266, 4728.503507131702, 5646.435356464321, 5858.736356863866, 5942.821715348547, 4933.939203071357, 5350.785968561369, 5718.085106382959, 5779.826605201851, 5811.360750317377, 5546.750396232099, 5862.912056319244, 5868.295804503487, 5881.17663527098, 5888.234529511136, 4902.455726446158, 5069.366329208849, 5728.598998070095, 5742.224799651994, 5779.393163717749], "value": 3986.9951243112027, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.6327937994413, 710.2719712294897, 748.981494871322, 775.0544335910191, 783.1111688233855, 13136.671583210413, 16084.919575402137, 16086.5978350541, 16104.275307611142, 16141.515754527354, 14393.352299146538, 14493.420263189486, 14642.780358294634, 14668.413263469449, 14692.559223223294, 656.4617014048482, 681.2982627893277, 805.4442434720054, 816.3632366753941, 841.305922613843, 678.6097993653644, 743.7025189924019, 843.852325842975, 844.3540691371076, 845.9280961118282, 702.6767686864023, 891.6344298837478, 894.7181637784109, 898.9011208767063, 901.4275934781429, 859.6432480520576, 900.1178844732199, 901.4456109492647, 902.2826852652157, 903.8779764731743, 662.4203821656051, 666.8466252761843, 667.6194569095618, 669.517947078103, 672.492268337407, 14923.925380373137, 15057.698846023019, 15298.617534561614, 15412.306446209948, 15447.845521544865, 14412.0, 14430.350634221486, 14454.78317825223, 14460.421583136662, 14609.415623375053, 11698.41507924609, 11763.529458821637, 11787.834898640733, 11823.822642660367, 12108.15242932995, 3755.324041672498, 3758.2859256741403, 3791.8862434127022, 3811.3711237645757, 3943.093088589612, 88891.11108888892, 109351.0, 110551.0, 113113.43443282813, 113152.43423782829, 10816.837747433638, 10843.78312433747, 10952.288101273392, 10958.616448424425, 11269.267497612629, 21975.0, 22015.889920550293, 22024.889875550518, 22029.559408812056, 22041.0, 7528.247175282428, 7777.766666999999, 7811.4922530035365, 7888.013998250219, 8213.34293256538, 4192.916141677149, 4204.62158405745, 4275.273203555403, 4289.656827453796, 4344.304911214191, 3283.589551306087, 3372.730181585467, 3383.8138902360606, 3385.272166484218, 3392.084137282969, 591.2018774654268, 591.6982339007051, 604.338249616669, 610.5420934299276, 614.3978900677362, 572.68788510262, 584.447696926409, 600.8618017855918, 645.4287955159624, 645.6513482719373, 8129.959350203211, 8633.740987770378, 9013.77465563353, 9220.953895230616, 9349.345545811864, 647.7538535356629, 648.6918713611092, 652.1782553982017, 664.5547483186277, 670.9865802683919, 3855.0, 4256.8297268109545, 4593.26507758757, 4641.74470404131, 4713.151632706145, 121976.23464926517, 153150.0, 161335.0, 163175.0, 170113.0, 97419.0, 108665.91334086716, 123109.0, 136765.31617341848, 140966.0, 105836.0, 109374.99999999841, 110815.0, 115015.42492287653, 115733.0, 9595.520223988913, 9695.0, 9907.950460247652, 9947.154491867983, 10094.747631309276, 46654.0, 54136.729316353165, 62747.6862615684, 64282.0, 64559.67720161369, 93740.53129734307, 94258.05741942493, 95256.52371738097, 99909.50045250019, 103128.48435757773, 10829.67510974672, 11433.942830285796, 11444.427778611203, 11462.254953428, 13299.800502992657, 14595.92702036483, 14673.559793206221, 14828.0, 14961.401543938557, 15510.457134000257, 13677.452901883813, 13969.441222350992, 13982.58052258434, 14201.147931124166, 14514.34685439179, 13285.468581257028, 13641.386137623633, 13711.862881371058, 13752.587422377344, 14405.991580589258, 13090.214587124803, 13380.531681391105, 13624.0, 14032.158070515801, 14205.076670016417, 11068.723281918017, 11251.38117403518, 11307.943460282646, 11314.943425282821, 11894.762104758029, 9722.659706910224, 10467.424291664032, 10657.840132398176, 11299.604513841976, 11513.712157195801], "value": 7726.211143820976, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1607.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 120.0, 117.0, 116.0, 119.0, 116.0, 116.0, 117.0, 116.0, 107.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 125.0, 143.0, 153.0, 140.0, 145.0, 146.0, 117.0, 140.0, 136.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 198.0, 198.0, 206.0, 266.0, 270.0, 219.0, 197.0, 196.0, 194.0], "value": 213.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 51.0, 82.0, 54.0, 79.0, 83.0, 84.0, 75.0, 85.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 102.0, 117.0, 98.0, 57.0, 85.0, 124.0, 100.0, 101.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 93.0, 90.0, 92.0, 88.0, 93.0, 95.0, 93.0, 90.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 96.0, 98.0, 93.0, 92.0, 127.0, 150.0, 139.0, 100.0, 97.0], "value": 113.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 112.0, 122.0, 91.0, 92.0, 90.0, 112.0, 134.0, 147.0, 119.0], "value": 110.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 67.0, 79.0, 80.0, 84.0, 70.0, 59.0, 58.0, 57.0, 57.0], "value": 66.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 71.0, 83.0, 79.0, 71.0, 71.0, 71.0, 73.0, 73.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 107.0, 108.0, 87.0, 87.0, 86.0, 89.0, 100.0, 106.0, 87.0], "value": 95.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 136.0, 131.0, 145.0, 155.0, 154.0, 163.0, 160.0, 210.0, 146.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 180.0, 176.0, 173.0, 174.0, 162.0, 221.0, 238.0, 184.0, 173.0], "value": 185.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 68.0, 73.0, 82.0, 90.0, 89.0, 87.0, 71.0, 70.0, 70.0], "value": 77.5, "unit": "score"}]}]} 14:20:09 INFO - Return code: 0 14:20:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:20:09 ERROR - # TBPL SUCCESS # 14:20:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:20:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:20:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:20:09 INFO - 2016-10-11 14:20:09.476 screenresolution[1678:11510] starting screenresolution argv=screenresolution get 14:20:09 INFO - 2016-10-11 14:20:09.478 screenresolution[1678:11510] Display 0: 1600x1200x32@60 14:20:09 INFO - 2016-10-11 14:20:09.484 screenresolution[1679:11513] starting screenresolution argv=screenresolution list 14:20:09 INFO - Available Modes on Display 0 14:20:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:20:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:20:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:20:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:20:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:20:09 INFO - Intel Iris: 14:20:09 INFO - Chipset Model: Intel Iris 14:20:09 INFO - Type: GPU 14:20:09 INFO - Bus: Built-In 14:20:09 INFO - VRAM (Dynamic, Max): 1536 MB 14:20:09 INFO - Vendor: Intel (0x8086) 14:20:09 INFO - Device ID: 0x0a2e 14:20:09 INFO - Revision ID: 0x0009 14:20:09 INFO - Displays: 14:20:09 INFO - Display: 14:20:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:20:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:20:09 INFO - Display Serial Number: 1600x1200 60 14:20:09 INFO - Main Display: Yes 14:20:09 INFO - Mirror: Off 14:20:09 INFO - Online: Yes 14:20:09 INFO - Rotation: Supported 14:20:09 INFO - Return code: 0 14:20:09 INFO - Running post-action listener: _resource_record_post_action 14:20:09 INFO - [mozharness: 2016-10-11 21:20:09.721304Z] Finished run-tests step (success) 14:20:09 INFO - Running post-run listener: _resource_record_post_run 14:20:09 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 32326144; Write bytes: 713088000; Read time: 205; Write time: 2041 14:20:09 INFO - TinderboxPrint: CPU usage
54.7% 14:20:09 INFO - TinderboxPrint: I/O read bytes / time
32,326,144 / 205 14:20:09 INFO - TinderboxPrint: I/O write bytes / time
713,088,000 / 2,041 14:20:09 INFO - TinderboxPrint: CPU idle
2,196.5 (45.3%) 14:20:09 INFO - TinderboxPrint: CPU system
977.9 (20.2%) 14:20:09 INFO - TinderboxPrint: CPU user
1,671.3 (34.5%) 14:20:09 INFO - TinderboxPrint: Swap in / out
436,064,256 / 0 14:20:09 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 403714560; Write bytes: 398022656; Read time: 30778; Write time: 812 14:20:09 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 30261760; Write bytes: 302105600; Read time: 187; Write time: 1194 14:20:10 INFO - Running post-run listener: _upload_blobber_files 14:20:10 INFO - Blob upload gear active. 14:20:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:20:10 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 14:20:10 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'] 14:20:10 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 14:20:10 INFO - (blobuploader) - INFO - Open directory for files ... 14:20:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:20:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:20:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:20:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:20:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:20:12 INFO - (blobuploader) - INFO - Done attempting. 14:20:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:20:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:20:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:20:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:20:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:20:14 INFO - (blobuploader) - INFO - Done attempting. 14:20:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:20:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:20:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:20:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:20:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:20:16 INFO - (blobuploader) - INFO - Done attempting. 14:20:16 INFO - (blobuploader) - INFO - Iteration through files over. 14:20:16 INFO - Return code: 0 14:20:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:20:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:20:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6588c7f8c41329d199958fad540b84f61ef7feb05048435cb5636d20977c610b4d049e2cc26dee01b88cc2e2172f8ade5d3587bb54e7c2480a31e8f14fde84fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f8450b1ba962c4e2099c77c1086b471274b98522f6dc0919f5e3fe4115a5c89b7dbf5d680f29e2b0c68683e8d733f2abd52f78e08400bbcc7f6007b4c4a9db7f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6ff5a544923e66a719cde7f213f4a27f7b10e62174ad1d61274abf8589d1ccc06958830e7cb396fff628e6617465eeb29d5ed81c8f2360d993d694707ca258b9"} 14:20:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:20:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:20:16 INFO - Contents: 14:20:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6588c7f8c41329d199958fad540b84f61ef7feb05048435cb5636d20977c610b4d049e2cc26dee01b88cc2e2172f8ade5d3587bb54e7c2480a31e8f14fde84fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f8450b1ba962c4e2099c77c1086b471274b98522f6dc0919f5e3fe4115a5c89b7dbf5d680f29e2b0c68683e8d733f2abd52f78e08400bbcc7f6007b4c4a9db7f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6ff5a544923e66a719cde7f213f4a27f7b10e62174ad1d61274abf8589d1ccc06958830e7cb396fff628e6617465eeb29d5ed81c8f2360d993d694707ca258b9"} 14:20:16 INFO - Running post-run listener: copy_logs_to_upload_dir 14:20:16 INFO - Copying logs to upload dir... 14:20:16 INFO - mkdir: /builds/slave/test/build/upload/logs 14:20:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.675634 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-11 14:20:16.397563) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-11 14:20:16.400911) ========= 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.e7uBDT4OTh/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.JF4YASOdcn/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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/6588c7f8c41329d199958fad540b84f61ef7feb05048435cb5636d20977c610b4d049e2cc26dee01b88cc2e2172f8ade5d3587bb54e7c2480a31e8f14fde84fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f8450b1ba962c4e2099c77c1086b471274b98522f6dc0919f5e3fe4115a5c89b7dbf5d680f29e2b0c68683e8d733f2abd52f78e08400bbcc7f6007b4c4a9db7f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6ff5a544923e66a719cde7f213f4a27f7b10e62174ad1d61274abf8589d1ccc06958830e7cb396fff628e6617465eeb29d5ed81c8f2360d993d694707ca258b9"} build_url:https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011117 build_url: 'https://queue.taskcluster.net/v1/task/a50ChiCrQv21_98UgdWMpg/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/6588c7f8c41329d199958fad540b84f61ef7feb05048435cb5636d20977c610b4d049e2cc26dee01b88cc2e2172f8ade5d3587bb54e7c2480a31e8f14fde84fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f8450b1ba962c4e2099c77c1086b471274b98522f6dc0919f5e3fe4115a5c89b7dbf5d680f29e2b0c68683e8d733f2abd52f78e08400bbcc7f6007b4c4a9db7f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6ff5a544923e66a719cde7f213f4a27f7b10e62174ad1d61274abf8589d1ccc06958830e7cb396fff628e6617465eeb29d5ed81c8f2360d993d694707ca258b9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-11 14:20:16.433272) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 14:20:16.433653) ========= 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.e7uBDT4OTh/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.JF4YASOdcn/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.005126 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 14:20:16.482668) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-11 14:20:16.483069) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-11 14:20:16.483483) ========= ========= Total master_lag: 0.19 =========