builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0194 starttime: 1480078893.32 results: success (0) buildid: 20161125021618 builduid: bb3d2e8a301943f5b8ce26405d2f01dd revision: 75453f12b616711e72c5a090b2041ae32450345e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 05:01:33.317254) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 05:01:33.317743) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 05:01:33.318144) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 05:01:33.829802) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 05:01:33.830155) ========= 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.l8WHkFZWWe/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.wD9qpEP8nP/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-25 05:01:33-- 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% 8.68M=0.001s 2016-11-25 05:01:33 (8.68 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093723 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 05:01:33.947325) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 05:01:33.947655) ========= 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.l8WHkFZWWe/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.wD9qpEP8nP/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.022620 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 05:01:33.996575) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-11-25 05:01:33.996937) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 75453f12b616711e72c5a090b2041ae32450345e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 75453f12b616711e72c5a090b2041ae32450345e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.l8WHkFZWWe/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.wD9qpEP8nP/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-25 05:01:34,082 truncating revision to first 12 chars 2016-11-25 05:01:34,083 Setting DEBUG logging. 2016-11-25 05:01:34,083 attempt 1/10 2016-11-25 05:01:34,083 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/75453f12b616?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 05:01:38,421 unpacking tar archive at: graphics-75453f12b616/testing/mozharness/ program finished with exit code 0 elapsedTime=5.727204 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-11-25 05:01:39.746354) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 05:01:39.746687) ========= script_repo_revision: 75453f12b616711e72c5a090b2041ae32450345e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 05:01:39.747065) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 05:01:39.747330) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 05:01:39.764540) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-11-25 05:01:39.764831) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.l8WHkFZWWe/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.wD9qpEP8nP/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:01:39 INFO - MultiFileLogger online at 20161125 05:01:39 in /builds/slave/test 05:01:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 05:01:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:01:39 INFO - {'append_to_log': False, 05:01:39 INFO - 'base_work_dir': '/builds/slave/test', 05:01:39 INFO - 'blob_upload_branch': 'Graphics', 05:01:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:01:39 INFO - 'branch': 'Graphics', 05:01:39 INFO - 'buildbot_json_path': 'buildprops.json', 05:01:39 INFO - 'config_files': ('talos/mac_config.py',), 05:01:39 INFO - 'default_actions': ('clobber', 05:01:39 INFO - 'read-buildbot-config', 05:01:39 INFO - 'download-and-extract', 05:01:39 INFO - 'populate-webroot', 05:01:39 INFO - 'create-virtualenv', 05:01:39 INFO - 'install', 05:01:39 INFO - 'run-tests'), 05:01:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:01:39 INFO - 'download_minidump_stackwalk': True, 05:01:39 INFO - 'download_symbols': 'ondemand', 05:01:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:01:39 INFO - 'tooltool.py': '/tools/tooltool.py', 05:01:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:01:39 INFO - '/tools/misc-python/virtualenv.py')}, 05:01:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:01:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:01:39 INFO - 'installer_path': 'installer.exe', 05:01:39 INFO - 'log_level': 'info', 05:01:39 INFO - 'log_name': 'talos', 05:01:39 INFO - 'log_to_console': True, 05:01:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:01:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:01:39 INFO - 'opt_config_files': (), 05:01:39 INFO - 'pip_index': False, 05:01:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:01:39 INFO - 'cmd': ('bash', 05:01:39 INFO - '-c', 05:01:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:01:39 INFO - 'enabled': True, 05:01:39 INFO - 'halt_on_failure': False, 05:01:39 INFO - 'name': 'check_screen_resolution'},), 05:01:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:01:39 INFO - 'cmd': ('bash', 05:01:39 INFO - '-c', 05:01:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:01:39 INFO - 'enabled': True, 05:01:39 INFO - 'halt_on_failure': False, 05:01:39 INFO - 'name': 'check_screen_resolution'},), 05:01:39 INFO - 'run_cmd_checks_enabled': True, 05:01:39 INFO - 'sps_profile': False, 05:01:39 INFO - 'sps_profile_interval': 0, 05:01:39 INFO - 'suite': 'dromaeojs-e10s', 05:01:39 INFO - 'system_bits': '32', 05:01:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:01:39 INFO - 'title': 't-yosemite-r7-0194', 05:01:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:01:39 INFO - 'use_talos_json': True, 05:01:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:01:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:01:39 INFO - 'work_dir': 'build'} 05:01:39 INFO - [mozharness: 2016-11-25 13:01:39.978174Z] Running clobber step. 05:01:39 INFO - Running pre-action listener: _resource_record_pre_action 05:01:39 INFO - Running main action method: clobber 05:01:39 INFO - rmtree: /builds/slave/test/build 05:01:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:01:40 INFO - Running post-action listener: _resource_record_post_action 05:01:40 INFO - [mozharness: 2016-11-25 13:01:40.953894Z] Finished clobber step (success) 05:01:40 INFO - [mozharness: 2016-11-25 13:01:40.954046Z] Running read-buildbot-config step. 05:01:40 INFO - Running pre-action listener: _resource_record_pre_action 05:01:40 INFO - Running main action method: read_buildbot_config 05:01:40 INFO - Using buildbot properties: 05:01:40 INFO - { 05:01:40 INFO - "project": "", 05:01:40 INFO - "product": "firefox", 05:01:40 INFO - "script_repo_revision": "default", 05:01:40 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 05:01:40 INFO - "repository": "", 05:01:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 05:01:40 INFO - "buildid": "20161125021618", 05:01:40 INFO - "pgo_build": "False", 05:01:40 INFO - "basedir": "/builds/slave/test", 05:01:40 INFO - "buildnumber": 9, 05:01:40 INFO - "slavename": "t-yosemite-r7-0194", 05:01:40 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:01:40 INFO - "platform": "yosemite_r7", 05:01:40 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 05:01:40 INFO - "branch": "graphics", 05:01:40 INFO - "repo_path": "projects/graphics", 05:01:40 INFO - "revision": "75453f12b616711e72c5a090b2041ae32450345e", 05:01:40 INFO - "stage_platform": "macosx64", 05:01:40 INFO - "builduid": "bb3d2e8a301943f5b8ce26405d2f01dd", 05:01:40 INFO - "slavebuilddir": "test" 05:01:40 INFO - } 05:01:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 05:01:40 INFO - Running post-action listener: _resource_record_post_action 05:01:40 INFO - [mozharness: 2016-11-25 13:01:40.957386Z] Finished read-buildbot-config step (success) 05:01:40 INFO - [mozharness: 2016-11-25 13:01:40.957492Z] Running download-and-extract step. 05:01:40 INFO - Running pre-action listener: _resource_record_pre_action 05:01:40 INFO - Running main action method: download_and_extract 05:01:40 INFO - mkdir: /builds/slave/test/build/tests 05:01:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:40 INFO - trying https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 05:01:40 INFO - Downloading https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:01:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 05:01:42 INFO - Downloaded 1199 bytes. 05:01:42 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:01:42 INFO - Using the following test package requirements: 05:01:42 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:01:42 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:01:42 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 05:01:42 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:01:42 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 05:01:42 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:01:42 INFO - u'jsshell-mac.zip'], 05:01:42 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:01:42 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 05:01:42 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:01:42 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:01:42 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 05:01:42 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:01:42 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 05:01:42 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:01:42 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 05:01:42 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:01:42 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 05:01:42 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:01:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:01:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:01:42 INFO - Fetch https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:01:43 INFO - Http code: 200 05:01:43 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 05:01:43 INFO - x-amz-cf-id: 5KG1kqw0WztyXvgeoKPkggA31ZmodAHwNCYGtjFo3cYy2VaIMdHYlw== 05:01:43 INFO - x-amz-version-id: Y0cH2BgdfltiApwEfjGfZiybxdf1mkEP 05:01:44 INFO - Expected file size: 16267116 05:01:44 INFO - Obtained file size: 16267116 05:01:51 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:01:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:01:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:01:51 INFO - Fetch https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:01:52 INFO - Http code: 200 05:01:52 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 05:01:52 INFO - x-amz-cf-id: _mITOYcna6bP_96Y_NNCzAhcPEPGt6LOgWxEnCdwMLTESHFxHhifQw== 05:01:52 INFO - x-amz-version-id: Y0cH2BgdfltiApwEfjGfZiybxdf1mkEP 05:01:52 INFO - Expected file size: 16267116 05:01:52 INFO - Obtained file size: 16267116 05:01:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 05:01:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:01:58 INFO - Fetch https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 05:02:00 INFO - Http code: 200 05:02:00 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 05:02:00 INFO - x-amz-cf-id: f_-g_NKlYkFNiPMlSXm4XeK9_wGvIXe5Rug3bvu1jF05nmS8JrSMSg== 05:02:00 INFO - x-amz-version-id: QKv25A8vsiXEMPkig97LaO3.TpcCu0e9 05:02:00 INFO - Expected file size: 14409506 05:02:00 INFO - Obtained file size: 14409506 05:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:01 INFO - trying https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:02:01 INFO - Downloading https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 05:02:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 05:02:03 INFO - Downloaded 107550864 bytes. 05:02:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:02:03 INFO - mkdir: /builds/slave/test/properties 05:02:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:02:03 INFO - Writing to file /builds/slave/test/properties/build_url 05:02:03 INFO - Contents: 05:02:03 INFO - build_url:https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:02:05 INFO - Running post-action listener: _resource_record_post_action 05:02:05 INFO - Running post-action listener: set_extra_try_arguments 05:02:05 INFO - [mozharness: 2016-11-25 13:02:05.396351Z] Finished download-and-extract step (success) 05:02:05 INFO - [mozharness: 2016-11-25 13:02:05.396451Z] Running populate-webroot step. 05:02:05 INFO - Running pre-action listener: _resource_record_pre_action 05:02:05 INFO - Running main action method: populate_webroot 05:02:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:02:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:02:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:02:05 INFO - '--tppagecycles', 05:02:05 INFO - '7'], 05:02:05 INFO - 'tests': ['tsvgm']}}, 05:02:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:02:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:02:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:02:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:02:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:02:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:02:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:02:05 INFO - 'talos_options': ['--disable-e10s'], 05:02:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:02:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:02:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:02:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:02:05 INFO - 'talos_options': ['--disable-e10s'], 05:02:05 INFO - 'tests': ['damp', 'tps']}, 05:02:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:02:05 INFO - 'tests': ['damp', 'tps']}, 05:02:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:02:05 INFO - 'tests': ['dromaeo_dom']}, 05:02:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:02:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:02:05 INFO - 'tests': ['basic_compositor_video']}, 05:02:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:02:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:02:05 INFO - 'tests': ['a11yr', 05:02:05 INFO - 'ts_paint', 05:02:05 INFO - 'tpaint', 05:02:05 INFO - 'sessionrestore', 05:02:05 INFO - 'sessionrestore_no_auto_restore', 05:02:05 INFO - 'tabpaint']}, 05:02:05 INFO - 'other-e10s': {'tests': ['a11yr', 05:02:05 INFO - 'ts_paint', 05:02:05 INFO - 'tpaint', 05:02:05 INFO - 'sessionrestore', 05:02:05 INFO - 'sessionrestore_no_auto_restore', 05:02:05 INFO - 'tabpaint']}, 05:02:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:02:05 INFO - 'ts_paint', 05:02:05 INFO - 'tpaint', 05:02:05 INFO - 'sessionrestore', 05:02:05 INFO - 'sessionrestore_no_auto_restore', 05:02:05 INFO - 'tabpaint']}, 05:02:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:02:05 INFO - 'ts_paint', 05:02:05 INFO - 'tpaint', 05:02:05 INFO - 'sessionrestore', 05:02:05 INFO - 'sessionrestore_no_auto_restore', 05:02:05 INFO - 'tabpaint']}, 05:02:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:02:05 INFO - 'tests': ['a11yr', 05:02:05 INFO - 'ts_paint', 05:02:05 INFO - 'tpaint', 05:02:05 INFO - 'sessionrestore', 05:02:05 INFO - 'sessionrestore_no_auto_restore', 05:02:05 INFO - 'tabpaint']}, 05:02:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:02:05 INFO - 'tests': ['a11yr', 05:02:05 INFO - 'ts_paint', 05:02:05 INFO - 'tpaint', 05:02:05 INFO - 'sessionrestore', 05:02:05 INFO - 'sessionrestore_no_auto_restore', 05:02:05 INFO - 'tabpaint']}, 05:02:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:02:05 INFO - 'tests': ['tsvgx', 05:02:05 INFO - 'tsvgr_opacity', 05:02:05 INFO - 'tart', 05:02:05 INFO - 'tscrollx', 05:02:05 INFO - 'cart']}, 05:02:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:02:05 INFO - 'tsvgr_opacity', 05:02:05 INFO - 'tart', 05:02:05 INFO - 'tscrollx', 05:02:05 INFO - 'cart']}, 05:02:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:02:05 INFO - 'talos_options': ['--disable-e10s'], 05:02:05 INFO - 'tests': ['tp5o']}, 05:02:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:02:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:02:05 INFO - 'talos_options': ['--disable-e10s', 05:02:05 INFO - '--xperf_path', 05:02:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:02:05 INFO - 'tests': ['tp5n']}, 05:02:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:02:05 INFO - 'talos_options': ['--xperf_path', 05:02:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:02:05 INFO - 'tests': ['tp5n']}}, 05:02:05 INFO - 'talos.zip': {'path': '', 05:02:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:02:05 INFO - Running post-action listener: _resource_record_post_action 05:02:05 INFO - [mozharness: 2016-11-25 13:02:05.414405Z] Finished populate-webroot step (success) 05:02:05 INFO - [mozharness: 2016-11-25 13:02:05.414519Z] Running create-virtualenv step. 05:02:05 INFO - Running pre-action listener: _resource_record_pre_action 05:02:05 INFO - Running main action method: create_virtualenv 05:02:05 INFO - Creating virtualenv /builds/slave/test/build/venv 05:02:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:02:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:02:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:02:05 INFO - Using real prefix '/tools/python27' 05:02:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:02:06 INFO - Installing distribute.............................................................................................................................................................................................done. 05:02:09 INFO - Installing pip.................done. 05:02:09 INFO - Return code: 0 05:02:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:02:09 INFO - Reading from file tmpfile_stdout 05:02:09 INFO - Output received: 05:02:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:02:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e70a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd412dbc990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520d30>, '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 0x10e387db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e518b20>, '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.l8WHkFZWWe/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.wD9qpEP8nP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:02:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8WHkFZWWe/Render', 05:02:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:09 INFO - 'HOME': '/Users/cltbld', 05:02:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:09 INFO - 'LOGNAME': 'cltbld', 05:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:09 INFO - 'MOZ_NO_REMOTE': '1', 05:02:09 INFO - 'NO_EM_RESTART': '1', 05:02:09 INFO - 'PAGER': '/bin/cat', 05:02:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:09 INFO - 'PWD': '/builds/slave/test', 05:02:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:09 INFO - 'SHELL': '/bin/bash', 05:02:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wD9qpEP8nP/Listeners', 05:02:09 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:02:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:09 INFO - 'USER': 'cltbld', 05:02:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:09 INFO - 'XPC_FLAGS': '0x0', 05:02:09 INFO - 'XPC_SERVICE_NAME': '0', 05:02:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:02:09 INFO - Cleaning up... 05:02:09 INFO - Return code: 0 05:02:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e70a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd412dbc990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520d30>, '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 0x10e387db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e518b20>, '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.l8WHkFZWWe/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.wD9qpEP8nP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:02:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8WHkFZWWe/Render', 05:02:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:09 INFO - 'HOME': '/Users/cltbld', 05:02:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:09 INFO - 'LOGNAME': 'cltbld', 05:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:09 INFO - 'MOZ_NO_REMOTE': '1', 05:02:09 INFO - 'NO_EM_RESTART': '1', 05:02:09 INFO - 'PAGER': '/bin/cat', 05:02:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:09 INFO - 'PWD': '/builds/slave/test', 05:02:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:09 INFO - 'SHELL': '/bin/bash', 05:02:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wD9qpEP8nP/Listeners', 05:02:09 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:02:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:09 INFO - 'USER': 'cltbld', 05:02:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:09 INFO - 'XPC_FLAGS': '0x0', 05:02:09 INFO - 'XPC_SERVICE_NAME': '0', 05:02:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:10 INFO - Downloading/unpacking psutil>=3.1.1 05:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:02:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:02:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:02:16 INFO - Installing collected packages: psutil 05:02:16 INFO - Running setup.py install for psutil 05:02:16 INFO - building 'psutil._psutil_osx' extension 05:02:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:02:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:02:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:02:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:02:17 INFO - building 'psutil._psutil_posix' extension 05:02:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:02:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:02:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:02:17 INFO - ^ 05:02:17 INFO - 1 warning generated. 05:02:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:02:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:02:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:02:17 INFO - Successfully installed psutil 05:02:17 INFO - Cleaning up... 05:02:17 INFO - Return code: 0 05:02:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e70a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd412dbc990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520d30>, '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 0x10e387db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e518b20>, '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.l8WHkFZWWe/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.wD9qpEP8nP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:02:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:02:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8WHkFZWWe/Render', 05:02:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:17 INFO - 'HOME': '/Users/cltbld', 05:02:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:17 INFO - 'LOGNAME': 'cltbld', 05:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:17 INFO - 'MOZ_NO_REMOTE': '1', 05:02:17 INFO - 'NO_EM_RESTART': '1', 05:02:17 INFO - 'PAGER': '/bin/cat', 05:02:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:17 INFO - 'PWD': '/builds/slave/test', 05:02:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:17 INFO - 'SHELL': '/bin/bash', 05:02:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wD9qpEP8nP/Listeners', 05:02:17 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:02:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:17 INFO - 'USER': 'cltbld', 05:02:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:17 INFO - 'XPC_FLAGS': '0x0', 05:02:17 INFO - 'XPC_SERVICE_NAME': '0', 05:02:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:02:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:02:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:02:22 INFO - Installing collected packages: mozsystemmonitor 05:02:22 INFO - Running setup.py install for mozsystemmonitor 05:02:22 INFO - Successfully installed mozsystemmonitor 05:02:22 INFO - Cleaning up... 05:02:22 INFO - Return code: 0 05:02:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:02:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e70a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd412dbc990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520d30>, '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 0x10e387db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e518b20>, '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.l8WHkFZWWe/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.wD9qpEP8nP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:02:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:02:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8WHkFZWWe/Render', 05:02:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:22 INFO - 'HOME': '/Users/cltbld', 05:02:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:22 INFO - 'LOGNAME': 'cltbld', 05:02:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:22 INFO - 'MOZ_NO_REMOTE': '1', 05:02:22 INFO - 'NO_EM_RESTART': '1', 05:02:22 INFO - 'PAGER': '/bin/cat', 05:02:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:22 INFO - 'PWD': '/builds/slave/test', 05:02:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:22 INFO - 'SHELL': '/bin/bash', 05:02:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wD9qpEP8nP/Listeners', 05:02:22 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:02:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:22 INFO - 'USER': 'cltbld', 05:02:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:22 INFO - 'XPC_FLAGS': '0x0', 05:02:22 INFO - 'XPC_SERVICE_NAME': '0', 05:02:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:23 INFO - Downloading/unpacking jsonschema==2.5.1 05:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:02:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:26 INFO - Downloading functools32-3.2.3-2.tar.gz 05:02:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:02:26 INFO - warning: no files found matching '*.txt' 05:02:26 INFO - no previously-included directories found matching 'build' 05:02:26 INFO - no previously-included directories found matching 'dist' 05:02:26 INFO - no previously-included directories found matching '.git*' 05:02:26 INFO - Installing collected packages: jsonschema, functools32 05:02:26 INFO - Running setup.py install for functools32 05:02:26 INFO - warning: no files found matching '*.txt' 05:02:26 INFO - no previously-included directories found matching 'build' 05:02:26 INFO - no previously-included directories found matching 'dist' 05:02:26 INFO - no previously-included directories found matching '.git*' 05:02:26 INFO - Successfully installed jsonschema functools32 05:02:26 INFO - Cleaning up... 05:02:26 INFO - Return code: 0 05:02:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:26 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 0x10e70a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd412dbc990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520d30>, '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 0x10e387db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e518b20>, '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.l8WHkFZWWe/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.wD9qpEP8nP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:02:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:02:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8WHkFZWWe/Render', 05:02:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:26 INFO - 'HOME': '/Users/cltbld', 05:02:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:26 INFO - 'LOGNAME': 'cltbld', 05:02:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:26 INFO - 'MOZ_NO_REMOTE': '1', 05:02:26 INFO - 'NO_EM_RESTART': '1', 05:02:26 INFO - 'PAGER': '/bin/cat', 05:02:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:26 INFO - 'PWD': '/builds/slave/test', 05:02:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:26 INFO - 'SHELL': '/bin/bash', 05:02:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wD9qpEP8nP/Listeners', 05:02:26 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:02:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:26 INFO - 'USER': 'cltbld', 05:02:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:26 INFO - 'XPC_FLAGS': '0x0', 05:02:26 INFO - 'XPC_SERVICE_NAME': '0', 05:02:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:02:26 INFO - Cleaning up... 05:02:26 INFO - Return code: 0 05:02:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:26 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 0x10e70a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd412dbc990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520d30>, '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 0x10e387db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e518b20>, '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.l8WHkFZWWe/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.wD9qpEP8nP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:02:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:02:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8WHkFZWWe/Render', 05:02:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:26 INFO - 'HOME': '/Users/cltbld', 05:02:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:26 INFO - 'LOGNAME': 'cltbld', 05:02:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:26 INFO - 'MOZ_NO_REMOTE': '1', 05:02:26 INFO - 'NO_EM_RESTART': '1', 05:02:26 INFO - 'PAGER': '/bin/cat', 05:02:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:26 INFO - 'PWD': '/builds/slave/test', 05:02:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:26 INFO - 'SHELL': '/bin/bash', 05:02:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wD9qpEP8nP/Listeners', 05:02:26 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:02:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:26 INFO - 'USER': 'cltbld', 05:02:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:26 INFO - 'XPC_FLAGS': '0x0', 05:02:26 INFO - 'XPC_SERVICE_NAME': '0', 05:02:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:26 INFO - Downloading/unpacking blobuploader==1.2.4 05:02:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:32 INFO - Downloading blobuploader-1.2.4.tar.gz 05:02:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:02:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:02:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:33 INFO - Downloading docopt-0.6.1.tar.gz 05:02:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:02:33 INFO - Installing collected packages: blobuploader, requests, docopt 05:02:33 INFO - Running setup.py install for blobuploader 05:02:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:02:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:02:33 INFO - Running setup.py install for requests 05:02:34 INFO - Running setup.py install for docopt 05:02:34 INFO - Successfully installed blobuploader requests docopt 05:02:34 INFO - Cleaning up... 05:02:34 INFO - Return code: 0 05:02:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02: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')]} 05:02:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:35 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 0x10e70a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd412dbc990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520d30>, '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 0x10e387db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e518b20>, '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.l8WHkFZWWe/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.wD9qpEP8nP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:02:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:02:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8WHkFZWWe/Render', 05:02:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:35 INFO - 'HOME': '/Users/cltbld', 05:02:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:35 INFO - 'LOGNAME': 'cltbld', 05:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:35 INFO - 'MOZ_NO_REMOTE': '1', 05:02:35 INFO - 'NO_EM_RESTART': '1', 05:02:35 INFO - 'PAGER': '/bin/cat', 05:02:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:35 INFO - 'PWD': '/builds/slave/test', 05:02:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:35 INFO - 'SHELL': '/bin/bash', 05:02:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wD9qpEP8nP/Listeners', 05:02:35 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:02:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:35 INFO - 'USER': 'cltbld', 05:02:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:35 INFO - 'XPC_FLAGS': '0x0', 05:02:35 INFO - 'XPC_SERVICE_NAME': '0', 05:02:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:02:35 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-nbOiw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:02:35 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-3O1TMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:02:35 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-zasNp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:02:36 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-JSCDk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:02:36 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-34rXlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:02:36 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Fnlilm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:02:36 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-c8FV5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:02:36 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-u1HC0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:02:36 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-pAqds2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:02:36 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-TjDoZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:02:36 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-tGkm6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:02:36 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Okh9HO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:02:37 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-69cbXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:02:37 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Zj1rbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:37 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-DTf0rO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:02:37 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-zR_kPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:02:37 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-kYJ7JL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:02:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:02:37 INFO - Running setup.py install for manifestparser 05:02:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:02:37 INFO - Running setup.py install for mozcrash 05:02:37 INFO - Running setup.py install for mozdebug 05:02:37 INFO - Running setup.py install for mozdevice 05:02:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:02:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:02:38 INFO - Running setup.py install for mozfile 05:02:38 INFO - Running setup.py install for mozhttpd 05:02:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:02:38 INFO - Running setup.py install for mozinfo 05:02:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:02:38 INFO - Running setup.py install for mozInstall 05:02:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:02:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:02:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:02:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:02:38 INFO - Running setup.py install for mozleak 05:02:38 INFO - Running setup.py install for mozlog 05:02:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:02:39 INFO - Running setup.py install for moznetwork 05:02:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:02:39 INFO - Running setup.py install for mozprocess 05:02:39 INFO - Running setup.py install for mozprofile 05:02:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:02:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:02:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:02:39 INFO - Running setup.py install for mozrunner 05:02:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:02:39 INFO - Running setup.py install for mozscreenshot 05:02:39 INFO - Running setup.py install for moztest 05:02:40 INFO - Running setup.py install for mozversion 05:02:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:02:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:02:40 INFO - Cleaning up... 05:02:40 INFO - Return code: 0 05:02:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:40 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 0x10e70a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd412dbc990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520d30>, '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 0x10e387db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e518b20>, '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.l8WHkFZWWe/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.wD9qpEP8nP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8WHkFZWWe/Render', 05:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:40 INFO - 'HOME': '/Users/cltbld', 05:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:40 INFO - 'LOGNAME': 'cltbld', 05:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:40 INFO - 'MOZ_NO_REMOTE': '1', 05:02:40 INFO - 'NO_EM_RESTART': '1', 05:02:40 INFO - 'PAGER': '/bin/cat', 05:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:40 INFO - 'PWD': '/builds/slave/test', 05:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:40 INFO - 'SHELL': '/bin/bash', 05:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wD9qpEP8nP/Listeners', 05:02:40 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:40 INFO - 'USER': 'cltbld', 05:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:40 INFO - 'XPC_FLAGS': '0x0', 05:02:40 INFO - 'XPC_SERVICE_NAME': '0', 05:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:02:40 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-VwI8CI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:02:40 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-jTj1Ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:02:41 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Da6qB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:02:41 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-nQt46A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:02:41 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-3zBBB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:02:41 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-ELXDyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:02:41 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-vUoHrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:02:41 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-VOE31q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:02:41 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-vJkGvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:02:41 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Bv3n9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:02:41 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-WJAAaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:02:41 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-k5DDxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:02:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:02:42 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-V9AtJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:02:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:02:42 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-GVnl9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:02:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:42 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-hWPY4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:02:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:02:42 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-4iInh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:02:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:02:42 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-9kx6kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:02:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:02:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:02:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:02:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:02:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:02:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:02:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:02: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 05:02: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 05:02: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 05:02: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 05:02: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 05:02: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 05:02:46 INFO - Downloading blessings-1.6.tar.gz 05:02:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:02:46 INFO - Installing collected packages: blessings 05:02:46 INFO - Running setup.py install for blessings 05:02:47 INFO - Successfully installed blessings 05:02:47 INFO - Cleaning up... 05:02:47 INFO - Return code: 0 05:02:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:02:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:02:47 INFO - Reading from file tmpfile_stdout 05:02:47 INFO - Current package versions: 05:02:47 INFO - blessings == 1.6 05:02:47 INFO - blobuploader == 1.2.4 05:02:47 INFO - docopt == 0.6.1 05:02:47 INFO - functools32 == 3.2.3-2 05:02:47 INFO - jsonschema == 2.5.1 05:02:47 INFO - manifestparser == 1.1 05:02:47 INFO - mozInstall == 1.12 05:02:47 INFO - mozcrash == 1.0 05:02:47 INFO - mozdebug == 0.1 05:02:47 INFO - mozdevice == 0.49 05:02:47 INFO - mozfile == 1.2 05:02:47 INFO - mozhttpd == 0.7 05:02:47 INFO - mozinfo == 0.9 05:02:47 INFO - mozleak == 0.1 05:02:47 INFO - mozlog == 3.4 05:02:47 INFO - moznetwork == 0.27 05:02:47 INFO - mozprocess == 0.23 05:02:47 INFO - mozprofile == 0.28 05:02:47 INFO - mozrunner == 6.13 05:02:47 INFO - mozscreenshot == 0.1 05:02:47 INFO - mozsystemmonitor == 0.3 05:02:47 INFO - moztest == 0.7 05:02:47 INFO - mozversion == 1.4 05:02:47 INFO - psutil == 3.1.1 05:02:47 INFO - requests == 1.2.3 05:02:47 INFO - wsgiref == 0.1.2 05:02:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:47 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 0x10e70a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd412dbc990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520d30>, '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 0x10e387db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e518b20>, '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.l8WHkFZWWe/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.wD9qpEP8nP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:02:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8WHkFZWWe/Render', 05:02:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:47 INFO - 'HOME': '/Users/cltbld', 05:02:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:47 INFO - 'LOGNAME': 'cltbld', 05:02:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:47 INFO - 'MOZ_NO_REMOTE': '1', 05:02:47 INFO - 'NO_EM_RESTART': '1', 05:02:47 INFO - 'PAGER': '/bin/cat', 05:02:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:47 INFO - 'PWD': '/builds/slave/test', 05:02:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:47 INFO - 'SHELL': '/bin/bash', 05:02:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wD9qpEP8nP/Listeners', 05:02:47 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:02:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:47 INFO - 'USER': 'cltbld', 05:02:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:47 INFO - 'XPC_FLAGS': '0x0', 05:02:47 INFO - 'XPC_SERVICE_NAME': '0', 05:02:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:02:47 INFO - Cleaning up... 05:02:47 INFO - Return code: 0 05:02:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:02:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e70a3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e680570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd412dbc990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e520d30>, '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 0x10e387db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e518b20>, '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.l8WHkFZWWe/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.wD9qpEP8nP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:02:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:02:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8WHkFZWWe/Render', 05:02:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:47 INFO - 'HOME': '/Users/cltbld', 05:02:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:47 INFO - 'LOGNAME': 'cltbld', 05:02:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:47 INFO - 'MOZ_NO_REMOTE': '1', 05:02:47 INFO - 'NO_EM_RESTART': '1', 05:02:47 INFO - 'PAGER': '/bin/cat', 05:02:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:47 INFO - 'PWD': '/builds/slave/test', 05:02:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:47 INFO - 'SHELL': '/bin/bash', 05:02:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wD9qpEP8nP/Listeners', 05:02:47 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:02:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:47 INFO - 'USER': 'cltbld', 05:02:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:47 INFO - 'XPC_FLAGS': '0x0', 05:02:47 INFO - 'XPC_SERVICE_NAME': '0', 05:02:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:02:47 INFO - Cleaning up... 05:02:47 INFO - Return code: 0 05:02:47 INFO - Running post-action listener: _resource_record_post_action 05:02:47 INFO - Running post-action listener: _start_resource_monitoring 05:02:47 INFO - Starting resource monitoring. 05:02:47 INFO - [mozharness: 2016-11-25 13:02:47.950199Z] Finished create-virtualenv step (success) 05:02:47 INFO - [mozharness: 2016-11-25 13:02:47.951195Z] Running install step. 05:02:47 INFO - Running pre-action listener: _resource_record_pre_action 05:02:47 INFO - Running main action method: install 05:02:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:02:48 INFO - Reading from file tmpfile_stdout 05:02:48 INFO - Detecting whether we're running mozinstall >=1.0... 05:02:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:02:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:02:48 INFO - Reading from file tmpfile_stdout 05:02:48 INFO - Output received: 05:02:48 INFO - Usage: mozinstall [options] installer 05:02:48 INFO - Options: 05:02:48 INFO - -h, --help show this help message and exit 05:02:48 INFO - -d DEST, --destination=DEST 05:02:48 INFO - Directory to install application into. [default: 05:02:48 INFO - "/builds/slave/test"] 05:02:48 INFO - --app=APP Application being installed. [default: firefox] 05:02:48 INFO - mkdir: /builds/slave/test/build/application 05:02:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:02:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:03:21 INFO - Reading from file tmpfile_stdout 05:03:21 INFO - Output received: 05:03:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:03:21 INFO - Running post-action listener: _resource_record_post_action 05:03:21 INFO - [mozharness: 2016-11-25 13:03:21.261895Z] Finished install step (success) 05:03:21 INFO - [mozharness: 2016-11-25 13:03:21.262040Z] Running run-tests step. 05:03:21 INFO - Running pre-action listener: _resource_record_pre_action 05:03:21 INFO - Running main action method: run_tests 05:03:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:03:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:03:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:03:21 INFO - 2016-11-25 05:03:21.276 screenresolution[6879:124087] starting screenresolution argv=screenresolution get 05:03:21 INFO - 2016-11-25 05:03:21.279 screenresolution[6879:124087] Display 0: 1600x1200x32@60 05:03:21 INFO - 2016-11-25 05:03:21.285 screenresolution[6880:124090] starting screenresolution argv=screenresolution list 05:03:21 INFO - Available Modes on Display 0 05:03:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:03:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:03:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:03:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:03:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:03:21 INFO - Intel Iris: 05:03:21 INFO - Chipset Model: Intel Iris 05:03:21 INFO - Type: GPU 05:03:21 INFO - Bus: Built-In 05:03:21 INFO - VRAM (Dynamic, Max): 1536 MB 05:03:21 INFO - Vendor: Intel (0x8086) 05:03:21 INFO - Device ID: 0x0a2e 05:03:21 INFO - Revision ID: 0x0009 05:03:21 INFO - Displays: 05:03:21 INFO - Display: 05:03:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:03:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:03:21 INFO - Display Serial Number: 1600x1200 60 05:03:21 INFO - Main Display: Yes 05:03:21 INFO - Mirror: Off 05:03:21 INFO - Online: Yes 05:03:21 INFO - Rotation: Supported 05:03:21 INFO - Return code: 0 05:03:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:03:21 INFO - Python 2.7.3 05:03:21 INFO - Return code: 0 05:03:21 INFO - grabbing minidump binary from tooltool 05:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:21 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 0x10e520d30>, '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 0x10e387db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e518b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:03:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:03:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:03:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:03:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:03:21 INFO - Return code: 0 05:03:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:03:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:03:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:03:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:03:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:03:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0194 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:03:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.l8WHkFZWWe/Render', 05:03:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:21 INFO - 'HOME': '/Users/cltbld', 05:03:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:21 INFO - 'LOGNAME': 'cltbld', 05:03:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:03:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:21 INFO - 'MOZ_NO_REMOTE': '1', 05:03:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:03:21 INFO - 'NO_EM_RESTART': '1', 05:03:21 INFO - 'PAGER': '/bin/cat', 05:03:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:21 INFO - 'PWD': '/builds/slave/test', 05:03:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:03:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:21 INFO - 'SHELL': '/bin/bash', 05:03:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wD9qpEP8nP/Listeners', 05:03:21 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 05:03:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:21 INFO - 'USER': 'cltbld', 05:03:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:21 INFO - 'XPC_FLAGS': '0x0', 05:03:21 INFO - 'XPC_SERVICE_NAME': '0', 05:03:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:03:21 INFO - mozversion application_buildid: 20161125021618 05:03:21 INFO - mozversion application_changeset: 75453f12b616711e72c5a090b2041ae32450345e 05:03:21 INFO - mozversion application_display_name: Nightly 05:03:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:03:21 INFO - mozversion application_name: Firefox 05:03:21 INFO - mozversion application_remotingname: firefox 05:03:21 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 05:03:21 INFO - mozversion application_vendor: Mozilla 05:03:21 INFO - mozversion application_version: 53.0a1 05:03:21 INFO - mozversion platform_buildid: 20161125021618 05:03:21 INFO - mozversion platform_changeset: 75453f12b616711e72c5a090b2041ae32450345e 05:03:21 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 05:03:21 INFO - mozversion platform_version: 53.0a1 05:03:21 INFO - using testdate: 1480079001 05:03:21 INFO - actual date: 1480079001 05:03:21 INFO - starting webserver on 'localhost:60621' 05:03:21 INFO - SUITE-START | Running 2 tests 05:03:21 INFO - TEST-START | dromaeo_css 05:03:21 INFO - operating with platform_type : mac_ 05:03:21 INFO - Initialising browser for dromaeo_css test... 05:03:21 INFO - TEST-INFO | started process 6890 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpqZ4LVl/profile http://localhost:60621/getInfo.html) 05:03:24 INFO - PROCESS | 6890 | 2016-11-25 05:03:24.869 plugin-container[6892:124250] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e37, name = 'com.apple.tsm.portname' 05:03:24 INFO - PROCESS | 6890 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:03:24 INFO - PROCESS | 6890 | 2016-11-25 05:03:24.872 plugin-container[6892:124250] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 05:03:24 INFO - PROCESS | 6890 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:03:24 INFO - PROCESS | 6890 | 2016-11-25 05:03:24.872 plugin-container[6892:124250] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:03:24 INFO - PROCESS | 6890 | __metrics Screen width/height:1600/1200 05:03:24 INFO - PROCESS | 6890 | colorDepth:24 05:03:24 INFO - PROCESS | 6890 | Browser inner width/height: 1024/689 05:03:24 INFO - PROCESS | 6890 | __metrics 05:03:25 ERROR - PROCESS | 6890 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 05:03:25 INFO - TEST-INFO | 6890: exit 0 05:03:25 INFO - Browser initialized. 05:03:25 INFO - Running cycle 1/1 for dromaeo_css test... 05:03:25 INFO - TEST-INFO | started process 6893 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpqZ4LVl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:03:28 INFO - PROCESS | 6893 | 2016-11-25 05:03:28.646 plugin-container[6894:124372] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 05:03:28 INFO - PROCESS | 6893 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:03:28 INFO - PROCESS | 6893 | 2016-11-25 05:03:28.647 plugin-container[6894:124372] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 05:03:28 INFO - PROCESS | 6893 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:03:28 INFO - PROCESS | 6893 | 2016-11-25 05:03:28.647 plugin-container[6894:124372] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:03:38 INFO - PROCESS | 6893 | RSS: Main: 241479680 05:03:38 INFO - PROCESS | 6893 | 05:04:27 INFO - PROCESS | 6893 | 1480079067938 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:06:41 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:60621/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60621/tests/dromaeo/cssquery-ext.html) 05:06:43 INFO - PROCESS | 6893 | RSS: Main: 248692736 05:06:43 INFO - PROCESS | 6893 | 05:09:45 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:60621/tests/dromaeo/cssquery-ext.html (next: http://localhost:60621/tests/dromaeo/cssquery-jquery.html) 05:09:46 INFO - PROCESS | 6893 | RSS: Main: 260235264 05:09:46 INFO - PROCESS | 6893 | 05:12:48 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:60621/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60621/tests/dromaeo/cssquery-mootools.html) 05:12:48 INFO - PROCESS | 6893 | RSS: Main: 265773056 05:12:48 INFO - PROCESS | 6893 | 05:15:50 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:60621/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60621/tests/dromaeo/cssquery-prototype.html) 05:15:51 INFO - PROCESS | 6893 | RSS: Main: 264572928 05:15:51 INFO - PROCESS | 6893 | 05:18:54 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:60621/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60621/tests/dromaeo/cssquery-yui.html) 05:18:55 INFO - PROCESS | 6893 | RSS: Main: 269905920 05:18:55 INFO - PROCESS | 6893 | 05:21:57 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:60621/tests/dromaeo/cssquery-yui.html (next: http://localhost:60621/tests/dromaeo/cssquery-dojo.html) 05:21:58 INFO - PROCESS | 6893 | RSS: Main: 272474112 05:21:58 INFO - PROCESS | 6893 | 05:21:58 INFO - PROCESS | 6893 | __start_tp_report 05:21:58 INFO - PROCESS | 6893 | _x_x_mozilla_page_load 05:21:58 INFO - PROCESS | 6893 | _x_x_mozilla_page_load_details 05:21:58 INFO - PROCESS | 6893 | |i|pagename|runs| 05:21:58 INFO - PROCESS | 6893 | |0;dojo.html;3313.0889005523477;3342.715869151122;3366.8653253869848;3377.679120483554;3397.490376443535;5466.644668096581;5612.270404847369;5638.746256418466;5649.8587535311635;5708.857278568038;5479.53423958964;5721.456461636149;5736.110902810061;5799.391063938289;5856.2386889704385;4982.850514484553;5126.333576635051;5144.536991670749;5250;5327.653702509325;5184.766685499161;5300.151975683892;5325.653832500894;5334.546563542103;5395.974764794696;5093.694378337312;5145.948540514584;5237.4500677428705;5271.683698978073;5290.7354632268425;5074.010567939254;5163.277141200235;5183.118869792144;5239.449857764918;5269.841904742864;5117.437081921023;5131.666441681279;5146.665466744649;5169.069567477853;5194.584433245369;5560.332760068777;5688.772449102031;5692.260006199209;5709.857253568694;5720.685362305072;6083.939160608382;6105.490073363223;6139.079138129272;6153.938460615426;6167.044108163242;6151.077338399231;6178.62928224308;6192.256929168483;6204.131421600934;6217.720202590849;5861.941380586182;5866.237389139427;5870.618409803349;5890.970545147246;5892.734826932756;6115.327313995457;6131.040261761377;6208.9689551551955;6271.059341098871;6284.183056202756;6028.216331876915;6125.724342404603;6139.631622102688;6148.1699970503405;6163.414475624898;6177.444030037318;6185.195924529782;6201.069839524017;6207.96896015517;6208.1308616793685;6096.481799047042;6115.205023346936;6124.724387402578;6135.32511423743;6137.956547386955;6174.166487524217;6184.5980011299125;6187.3503282155625;6211.9689401552705;6246.312905580383;4885.511448855086;4907.386576677915;4932.1122198004705;4948.90102197954;4968.701877887338;3388.9663652586196;3936.1340505088833;4293.635041021485;4336.566343365701;4386.780660966939;5716.28546431696;5896.852578685567;5931.881362372815;5969.224000879858;5989.131575921467;6189.597676151036;6208.906866397007;6212.223472065992;6228.096925945712;6228.875422491525;4693.319468677023;5758.913616295759;5760.711964401763;5881.852953676193;5902;5324.9733751331;5353.892922141613;5373.704446255493;5431.293931788842;5451.454854514517;5521.503064724114;6001.669908155093;6338.429541341301;6365.854146253719;6406.910825159827;5523.8066667668045;5980.910286345621;6191.535634827496;6206.131141640084;6233.065040244;6071.301800293023;6135.07955838608;6218.347073557211;6233.2208473940755;6281.96859015702;6160.691965401802;6182.258129024546;6184.226971628546;6190.7214175363115;6208.503319734501;6037.366076562073;6154.969225153845;6191.504679625709;6196.566240363131;6235.314115447388;5912.054071348563;6000.339962604023;6033.9698301508215;6086.1175129606845;6153.2616086068165;5976.402359764163;5994.730237139252;6034.094885766995;6104.0347610180015;6106.096658337741;5705.286839145107;5895.557833162444;5908.468237858441;5943.851403714942;6029.397060293935;6054.27348718139;6104;6135.785247516315;6145.033589251397;6173.84565385869;5759.481646651821;5778.913316300175;6016.187814644967;6025.337212906665;6081.331053584191 05:21:58 INFO - PROCESS | 6893 | |1;ext.html;455.3852299395814;458.662882781156;468.1619900378323;485.8348161625047;507.69030891156393;37672.24655506888;39596;39619.20761584766;39712.80861574154;39778.204435911335;41841.95395115116;42187.789061054726;42213.78893105538;42354.57645423545;42369.57630423695;7399.815004624874;7661.923380766205;9240.30697697672;9430.29272804539;9436.528173591327;8360.790980225482;11232.382218977953;11243.988041076302;11341.092712582973;11424.086073114131;9572.42565446072;11246.931541503543;11270.943645281763;11331.206815522917;11373.976342129206;9505.524723763854;11222.214444988855;11275.985161335519;11285.322880627189;11322.830157547643;10144.898551014468;10644.095251903598;11115.11043122671;11130.443477826157;11139.832902506469;34791.4781278281;36413.90758277229;39028;39393;39436.40845387321;3267.248532837437;3399.915002124942;3406.8416738308847;3488.7557870949045;3838.1172330363893;29824.850875745484;30452.238694032603;30530.694693053007;30614.38771224563;30802.69197308043;3115.7195852373397;3305.008497450755;3305.520699498559;3350.447176215946;3350.932981340384;491514;498419;499852;500594;503216.99999999633;57906.42093579094;58435.70782146062;58439;58490;58527.41472585305;61041.69479152664;61065.38934610686;61290.38709612847;61381.38618613757;61445;15413.152276624893;15641.13973731433;15647.765283520755;15694.450694225647;15729.292181851844;8861.291096712377;10820.513076911559;11788.23376480526;11969.341686207168;12092.27446353204;16339.754903676454;16447.013179209047;16477.01137931728;16477.176141192893;16480.752788708178;6300.369963003663;7159.534630249122;7165.749198778018;7172.533785303938;7259.165196002422;844.5692696724715;983.9950800246096;993.7217579077869;1040.6357774778767;1055.5672174408455;28443.431131377256;29333.12000639984;29551.556726649116;29699.96050138235;30077.248068798017;837.3426859914938;937.6249500199974;1025.6410256410375;1029.9443070852435;1093.2019625673304;12846;14454.421823127168;15036.699266014837;15332.310046047955;15550.377984880479;397500;454492;495055;527731;534117;56063;56541.151882721795;56611.71694141503;56664.15003774947;56707.43292567104;1362.5844117544243;1373.8214032175754;1374.71131062478;1388.9861101389058;1391.262630805695;28396.57405138966;29100.12699619015;29189.708102919547;29655;29982;49321.753391232814;49322;49532;49567.50432495773;49595;578237;583182;584680;585397;588870;38509;39154;39464.60535394724;39496.605033949294;40053;37188.6281137196;44454.55545444504;44811.87970300768;44843.32735008912;46100.30849537192;37874.242515148995;38004;38264.42603361008;38340.41415371857;38669.41995870008;37166.07084822793;37447;37985;38447.23105537929;38543;36664.63335366719;37059;37471.43792843055;37553.06117347088;37627.81186094161;27632.86183569069;30827;33390.33219335551;33391.33217335688;33901.83049084739;21830.57881864721;24960;25059.498810024063;25322.74677253204;25541 05:21:58 INFO - PROCESS | 6893 | |2;jquery.html;313.9232432755648;351.5289512053847;355.76163970140004;368.20467789574525;388.5007765021946;18597.72103418448;19247.422577322668;19663.213471461124;20239.79760202396;20514.48713782156;17520.386786462477;19611.607767844635;20077.297294594693;20341.593168136664;20350.491237719063;5693.117566777156;5694.430556944303;6001.339852616209;6002.069679199709;6026.156338112646;5708.286464191976;5858.502027327682;5914.1128830675525;5926;5938.079597662369;5009.398872135331;5572.634704497404;5820.796272130477;5871.266091738533;5883.294004719418;4843.878903027453;5718.142278658193;5774.595778295521;5801.709914504279;5849.415058494138;4996.275540046673;4996.675216110941;5046.520580544842;5080.77136528857;5081.212412076134;24172;24505.754942450705;24676.259712208663;24778.177128374817;25094.996200152156;2203;2216.213244298283;2229.320057382498;2444.8777561121874;2963.140689200151;13838.723225535434;14687.63280917975;15147.924260378737;15175.861810364268;15713.528594142193;1782.5008997480727;1807.5119717676155;1808.3489943620286;1880.9059547022594;1923.1538123225737;81467.9999999994;101613.98386016118;105118.42322365247;105914;106432.46783766031;10247.38515689061;10551.15590752738;10672.199585031154;10718.464076796128;10880.78238435227;13371.264580447974;13380.732385352238;13384;13413.865861341457;13451.461941522426;8031.317338026216;8258.504489730636;8347.624356903954;8488.363372747068;8493.10822363643;8189.795255118551;8198.94543810043;8356.373272004503;8512.063672995966;8516.190961858738;6364.395382438661;6534;6547;6550.70521826519;6632.701528431232;1514.5229252785336;1794.5872449336464;1845.5570663041042;1857.3684947118045;1867.9252829886652;874.606427107804;951.1439704266078;986.649739342523;1012.6101450941441;1066.8293073108266;16751.6649667006;20573.69139462909;21008.369748907557;21081.683774743393;21087.578248434944;944.1644144931785;945.0107523662729;959.788846453779;979.1383582447401;994.5922171909482;7640.312371886557;10611.991860773214;10804.027637512505;11030.400591914127;11208.04731597807;551889;585169;585450;597727.0795522135;601150;76458.61770691222;96670.51664741631;99175.50412247893;99418.50872236922;105192.99999999847;878.7759121423996;938.8544775559799;1000.0299709281985;1004.2417974429405;1004.6684594083935;18876.05619719036;19179.424617261506;19239.326623568668;19319.51701207481;19435.70846437333;44570.10859782851;50874.23688644599;51020.48979510306;52243.1714889977;53934.730326348115;57432;74844;75259;76138.85791713018;76581.2341876574;5861.827634473208;6964.547304425196;7358.1538123116525;7771.261730135628;8018.83962320762;10547.52536135891;12202.938985305016;12551.058670599925;12619.053570982396;12676.936615316865;8536.40245182856;10426.009529094725;10472.94763526208;10742.24804263694;10928.453577321261;10090.596376144871;10488.842667360148;10719;10843.349399036084;11116.61091861781;11021.669349919515;11057.889421106007;11163.776724465628;11236.606718764804;11290;7034.894476582753;7252.238514955854;7347.5224110432655;7394.112706475043;7493.88759168602;9805.460699661588;10434.634787782392;10574.682759517227;11205.495752690986;11249.775004499701 05:21:58 INFO - PROCESS | 6893 | |3;mootools.html;1307.0263901508722;1442.8268607767066;1569;1641.7865677461928;1743.093591332508;6548.410643042125;7505.211952744965;7937.245961633643;8422.663093476262;8486.957565212173;7166.319199676022;7862.6854925803045;8066.03207615087;8207.425480216372;8297;2272.0798076778888;2300.769923007703;2301.1715782318433;2305.1931823861596;2309.8749150781236;2298.804601608857;2327.4530485336018;2335.509542995973;2339.4034521197154;2347.647852822082;2275.6927814744963;2281.2927992322434;2306.411864974438;2308;2315.6063469210276;2270.71616047994;2285.8628482291115;2290.03818396274;2290.4502919299416;2310.838241323108;2284.371797755614;2312.814974802012;2314.745378008418;2315.895784689693;2325.453518423169;8171.71399001038;8249.876251856227;8317.085120636724;8322.417430779788;8326.333893288522;4991.625628077909;5109.029284435946;5138.254953031789;5144.871378215537;5158.303629010074;2531.886065127074;3225.483922572377;3442.9827850860584;3446.052335607703;3458.1354661334667;1007.9899201007969;1037.5590374091057;1055.5777688924416;1065.239703845427;1074.6346242277664;10671.413072281099;10679.572817087308;10737.51681174349;10748.301360411548;10773.622923197729;3846.788426636506;4400.471943366816;4575.633949284049;4672.696274742131;4678.7660616969015;7932.801679958047;8568.186022327869;8996.64013439455;9289.72130836076;9321.673741419017;5100.821471248489;5118.462561430996;5132.666376685504;5134.101532231844;5145.716985565755;3515.6308587598446;4168.937465938014;4539.591436770706;4665.650076244296;4711.104890070944;3698.3158115748674;3767.05823544114;3791.810409479515;3831.16104269952;3854.1713531590844;1229.557359350633;1248.450681700049;1249.0881656390886;1256.6355756830417;1256.9308688022247;733.3839574757229;777.5684495105157;828.2545708862073;829.6142293833396;858.1032820702382;5331.653442526223;5401.567874570025;5440.972795136078;5478.331154640669;5479.6164268500825;426.9295566231537;427.1904740516703;438.6753802186359;439.5867884188916;443.0872402850139;2808.2137001639576;2889.4943384907556;3572.9285414291567;3645.5260816093737;3920.5687374388795;9674.516274186262;9873.308868379288;10019.448930308758;10045.598176073021;10053.346532475365;9983.251256155818;9991.750206244757;10010.699679009642;10037.19702423819;10041.698749037541;7747.915291859144;7825.217478252129;7831.138574756772;7832.843343133105;7840.9215907841335;5280.471952804689;5296.788128474818;5460.945390546044;5494.725263736877;5518.862028449321;9069.954650226706;9105.317101217302;9239.584218710039;9257.166854983085;9271.397359171633;8883.111688831064;9162.129597688208;9255.76860578491;9386.249100071842;9397.624095036395;6562.704678289393;6785.9660701696175;6910.481713871379;7072.787816365517;7246.96376518114;7764.534127952341;7796.454248202572;7815.10126335456;7860.882086768817;7872.488288261245;6953.061336719478;7085.14978202619;7095.361417472453;7096.8935465967015;7192.136943566806;6943.305669433218;6964.756233531802;7012.333828286304;7070.893936590853;7092.113485814273;6586.110875031809;6613.537052406477;6696.363845434676;6721.294264102199;6733.360330768569;4772.49888761675;4839.249916262985;4845.079434907357;4879.853604391874;4928.359313289249;4744.644151688568;4768.809247630056;4829.6860704054125;4937.012597480447;4938.901221975612 05:21:58 INFO - PROCESS | 6893 | |4;prototype.html;294.7332663939133;299.3817766312565;300.14159503819087;307.6093361430982;311.9890803821865;4141.958580414195;4572.748498832563;5442.755076021579;5470.726463676815;5486.561075113991;4220.89447763806;4491.977540112295;4864.500337188105;5151.6908985460805;5436.510714035736;4133.6693064554765;4195.056112374707;4205.222033923718;4212.810423530933;4235.428217190686;3363.7477189210786;3467.878624248139;3684.2631473705233;3881.7088718346095;3975.5428125765475;3608.7113030957594;4130.63688982635;4135.607117323864;4141.254574176647;4142.337226043818;4134.565870583576;4144.150449157931;4150.709450338478;4165.396017577435;4170.998960249518;2854.3863069439963;3625.1843335249623;3632.346177688015;3636.5999740028574;3660.401115208179;4355.803988820479;4863.805447782084;4977.800887964477;5368.463153684639;5390.946090539083;4246.4691913510815;4631.8378856739855;4640.67515273931;4658.55743704351;4691.7184968901975;4709.340692303081;5724.079005880985;5731.882282954825;5736.311642602873;5821.941780582225;3376.4428869236544;3565.554305711786;3598.229081645267;3850.8652197173246;3855;5076.898462030776;6031.276246850362;6046.3745234894905;6079.209702738615;6092.878142437171;3301.9598826369615;3572.374834403969;3921.9803900980883;3930.5872883346838;3935.5080614923136;3619.800910949923;3984.1633257015774;3991.5210174779213;4007.519097708235;4021.5978402160135;3848.4034974578985;4208.379773787114;4231.428757117811;4583.656225783079;4584.404027476407;4812.807487700523;5772.3938986406665;5805.303363596311;5818.389069147764;5865.35481097079;963.6193703487223;1131.643532287327;1226.4113225651738;1281.9743605128033;1302.6873550347848;850.5116046917893;867.8915135608049;868.6221493650282;873.4759144513368;880.7313769300425;4975.502449755068;5137.504683401949;5201.349831271091;5239.904038384677;5251.894962100737;5088.542031217208;5326.547243484347;5384.730763461811;5619.100943848965;5653.575981801299;4114.773687447159;4619.491855895849;5056.763671250403;5195.636305458657;5228.882207816863;2980.687027862043;2987.985060074686;3005.128512731327;3021.743151832075;3034.696530346992;4472.064175384179;4823.734694591831;5674.099176775744;5704.800331988444;5853.64878107315;4755.9286610700865;4784;5066.316047333637;5641.323041235078;5747.678034052108;4788.6662570373865;5099.792671046475;5718.714064296852;5778.508826749773;5783.277090363705;4725.503822098768;5255.816046438356;5321.5476684482255;5467.835964921059;5535.5848311376;5479.589030822784;5484;5506.697131657797;5509.531689806411;5520.779168833202;4755.476897541199;4888.584470320063;5653.660780353192;5753.453421924911;5788.392218817132;3579.463080537836;3834.712396570324;3908.8241029154324;3921.6862650988423;3952.150287688161;5618.6628802272;5741.827745167651;5807.466600074916;5850.5027072699295;5938.604427959513;5400.405955344988;5749.447649134794;5764;5796.913046304225;5816.47651711348;5196.064708352533;5645.793883556777;5717.485426311652;5806.200077975841;5834.066549352253;4575.398610486436;5305.495977882095;5326.786928522815;5434.619576629598;5602.299712535933;5078.669886457368;5342.866428339323;5774.94225057744;5820.679862607428;5864.06175011996;4701.670883038291;5370.865728356823;5671.886562268649;5702.258706368311;5708.857278568069 05:21:58 INFO - PROCESS | 6893 | |5;yui.html;707.2821086597102;727.7452891487976;730.4886579394425;780.1067777394882;823.0617096509977;10459.529321180546;14610;14648.85351146491;14683.486077987272;14740.999999999987;12742.04434667399;13279.535216267432;13373.732525349487;13475.797863032063;13622.250776207306;833.162671515128;848.7326492154982;851.5018714052256;852.9317654587618;853.2363534636496;604.9032154855224;639.5833229396885;813.9633716482773;837.3259526081501;841.5161282262693;793.1513280789558;819.1358117186365;869.652139144341;879.7580665317025;882.6028287270722;857.1985193843744;872.6378552900538;872.9083446238149;873.7684513603837;874.4796845876725;648.8572514046903;656.8489247473108;662.595816551903;664.670987861007;665.6971078159426;13490.325483725774;13574.321283935764;13608.727825443435;13632.727345453135;13680.726385472335;10933.890661093366;12842.715728427083;13427.03736539598;13581.932090339486;13687.452501899912;11308.151888608387;11312;11342.60300889465;11408.372539510323;11498.033178553385;3634.800085995269;3650.142216579102;3674.9081272968388;3676.466912297702;3769.2461507698295;69050.61898762047;89901.65147522792;90699;93435.08550981028;93968;10406.219533535015;10461;10482.947585262025;10521.894781052244;10584.153267738562;20010.59978800445;20420;20430.69353959692;20555;20707;7494.6252687366505;7605.087389513184;7616.504927179715;7661.808454788675;7774.844503110019;4246.808893599796;4253.106847561985;4272.957270427318;4312.827486900552;4331.242032644338;3338.582677165354;3417.4532074867902;3417.8120203389053;3451;3451.6893479586456;629.5026928726255;636.0649844728242;643.732850866885;650.339904996429;655.3021032600266;566.8016194332033;609.7560975609791;626.8620903401336;634.3181080338654;648.1800522339245;8514.707493699605;8860.689875854443;8909.198172164537;9046.638134474679;9062.093790620886;467.9438467383936;538.9757460914268;616.6269407008784;620.6772478311253;658.940695337422;4113.794310284534;4202.747835129902;4304.892377690583;4312.259494207904;4490.416245888079;82636.76044859212;91962.54018729864;92404.53797731103;93145;107467.92532074735;43467;53255.46744532583;60494.697526512966;71207.64396177986;91266.54366728256;81106.78339824907;83201.58399207966;83527;85291.5735421319;85955;7450.292222238879;8187.345012398875;9724.562394692115;9726.36778609388;9767.706968790948;43093.784531077144;44943.32585011162;47271.818204543786;51303.973920520635;57441.71279143745;51023.74488127536;61029;68302;70788.93816592859;71913.64043179751;11969.341686207343;12296.631101066982;12388.81416778767;12553.309567973849;12762.42569084412;11927.403629818647;14002.43990240379;14194.858051419353;14303.642408939444;14560.49038283655;13109.739389500712;13198.406071726604;13227.86772132305;13458.394372253077;13932.094413863066;12817.935910320388;12852;13220.140690855063;13469.259190744211;13489;12671.80992285135;13018.739625207632;13332.3333833306;13360.866391335961;13494.662633434242;10273.914666003235;10525.9473702631;11053.723656908642;11242.494087765906;11359.943200284277;8471.279941205066;9040.321975851968;9480.620775168916;10324.380537167794;10922.18083643714 05:21:58 INFO - PROCESS | 6893 | __end_tp_report 05:21:58 INFO - PROCESS | 6893 | __start_cc_report 05:21:58 INFO - PROCESS | 6893 | _x_x_mozilla_cycle_collect,366 05:21:58 INFO - PROCESS | 6893 | __end_cc_report 05:21:58 INFO - PROCESS | 6893 | __startTimestamp1480080118065__endTimestamp 05:21:58 INFO - PROCESS | 6893 | 05:21:58 INFO - PROCESS | 6893 | ------- Summary: start ------- 05:21:58 INFO - PROCESS | 6893 | Number of tests: 6 05:21:58 INFO - PROCESS | 6893 | 05:21:58 INFO - PROCESS | 6893 | [#0] dojo.html Cycles:165 Average:5704.38 Median:5969.22 stddev:655.71 (11.0%) stddev-sans-first:630.32 05:21:58 INFO - PROCESS | 6893 | Values: 3313.1 3342.7 3366.9 3377.7 3397.5 5466.6 5612.3 5638.7 5649.9 5708.9 5479.5 5721.5 5736.1 5799.4 5856.2 4982.9 5126.3 5144.5 5250.0 5327.7 5184.8 5300.2 5325.7 5334.5 5396.0 5093.7 5145.9 5237.5 5271.7 5290.7 5074.0 5163.3 5183.1 5239.4 5269.8 5117.4 5131.7 5146.7 5169.1 5194.6 5560.3 5688.8 5692.3 5709.9 5720.7 6083.9 6105.5 6139.1 6153.9 6167.0 6151.1 6178.6 6192.3 6204.1 6217.7 5861.9 5866.2 5870.6 5891.0 5892.7 6115.3 6131.0 6209.0 6271.1 6284.2 6028.2 6125.7 6139.6 6148.2 6163.4 6177.4 6185.2 6201.1 6208.0 6208.1 6096.5 6115.2 6124.7 6135.3 6138.0 6174.2 6184.6 6187.4 6212.0 6246.3 4885.5 4907.4 4932.1 4948.9 4968.7 3389.0 3936.1 4293.6 4336.6 4386.8 5716.3 5896.9 5931.9 5969.2 5989.1 6189.6 6208.9 6212.2 6228.1 6228.9 4693.3 5758.9 5760.7 5881.9 5902.0 5325.0 5353.9 5373.7 5431.3 5451.5 5521.5 6001.7 6338.4 6365.9 6406.9 5523.8 5980.9 6191.5 6206.1 6233.1 6071.3 6135.1 6218.3 6233.2 6282.0 6160.7 6182.3 6184.2 6190.7 6208.5 6037.4 6155.0 6191.5 6196.6 6235.3 5912.1 6000.3 6034.0 6086.1 6153.3 5976.4 5994.7 6034.1 6104.0 6106.1 5705.3 5895.6 5908.5 5943.9 6029.4 6054.3 6104.0 6135.8 6145.0 6173.8 5759.5 5778.9 6016.2 6025.3 6081.3 05:21:58 INFO - PROCESS | 6893 | 05:21:58 INFO - PROCESS | 6893 | [#1] ext.html Cycles:180 Average:66074.25 Median:37773.24 stddev:139677.64 (369.8%) stddev-sans-first:139982.59 05:21:58 INFO - PROCESS | 6893 | Values: 455.4 458.7 468.2 485.8 507.7 37672.2 39596.0 39619.2 39712.8 39778.2 41842.0 42187.8 42213.8 42354.6 42369.6 7399.8 7661.9 9240.3 9430.3 9436.5 8360.8 11232.4 11244.0 11341.1 11424.1 9572.4 11246.9 11270.9 11331.2 11374.0 9505.5 11222.2 11276.0 11285.3 11322.8 10144.9 10644.1 11115.1 11130.4 11139.8 34791.5 36413.9 39028.0 39393.0 39436.4 3267.2 3399.9 3406.8 3488.8 3838.1 29824.9 30452.2 30530.7 30614.4 30802.7 3115.7 3305.0 3305.5 3350.4 3350.9 491514.0 498419.0 499852.0 500594.0 503217.0 57906.4 58435.7 58439.0 58490.0 58527.4 61041.7 61065.4 61290.4 61381.4 61445.0 15413.2 15641.1 15647.8 15694.5 15729.3 8861.3 10820.5 11788.2 11969.3 12092.3 16339.8 16447.0 16477.0 16477.2 16480.8 6300.4 7159.5 7165.7 7172.5 7259.2 844.6 984.0 993.7 1040.6 1055.6 28443.4 29333.1 29551.6 29700.0 30077.2 837.3 937.6 1025.6 1029.9 1093.2 12846.0 14454.4 15036.7 15332.3 15550.4 397500.0 454492.0 495055.0 527731.0 534117.0 56063.0 56541.2 56611.7 56664.2 56707.4 1362.6 1373.8 1374.7 1389.0 1391.3 28396.6 29100.1 29189.7 29655.0 29982.0 49321.8 49322.0 49532.0 49567.5 49595.0 578237.0 583182.0 584680.0 585397.0 588870.0 38509.0 39154.0 39464.6 39496.6 40053.0 37188.6 44454.6 44811.9 44843.3 46100.3 37874.2 38004.0 38264.4 38340.4 38669.4 37166.1 37447.0 37985.0 38447.2 38543.0 36664.6 37059.0 37471.4 37553.1 37627.8 27632.9 30827.0 33390.3 33391.3 33901.8 21830.6 24960.0 25059.5 25322.7 25541.0 05:21:58 INFO - PROCESS | 6893 | 05:21:58 INFO - PROCESS | 6893 | [#2] jquery.html Cycles:180 Average:32903.93 Median:14029.07 stddev:96541.32 (688.2%) stddev-sans-first:96781.13 05:21:58 INFO - PROCESS | 6893 | Values: 313.9 351.5 355.8 368.2 388.5 18597.7 19247.4 19663.2 20239.8 20514.5 17520.4 19611.6 20077.3 20341.6 20350.5 5693.1 5694.4 6001.3 6002.1 6026.2 5708.3 5858.5 5914.1 5926.0 5938.1 5009.4 5572.6 5820.8 5871.3 5883.3 4843.9 5718.1 5774.6 5801.7 5849.4 4996.3 4996.7 5046.5 5080.8 5081.2 24172.0 24505.8 24676.3 24778.2 25095.0 2203.0 2216.2 2229.3 2444.9 2963.1 13838.7 14687.6 15147.9 15175.9 15713.5 1782.5 1807.5 1808.3 1880.9 1923.2 81468.0 101614.0 105118.4 105914.0 106432.5 10247.4 10551.2 10672.2 10718.5 10880.8 13371.3 13380.7 13384.0 13413.9 13451.5 8031.3 8258.5 8347.6 8488.4 8493.1 8189.8 8198.9 8356.4 8512.1 8516.2 6364.4 6534.0 6547.0 6550.7 6632.7 1514.5 1794.6 1845.6 1857.4 1867.9 874.6 951.1 986.6 1012.6 1066.8 16751.7 20573.7 21008.4 21081.7 21087.6 944.2 945.0 959.8 979.1 994.6 7640.3 10612.0 10804.0 11030.4 11208.0 551889.0 585169.0 585450.0 597727.1 601150.0 76458.6 96670.5 99175.5 99418.5 105193.0 878.8 938.9 1000.0 1004.2 1004.7 18876.1 19179.4 19239.3 19319.5 19435.7 44570.1 50874.2 51020.5 52243.2 53934.7 57432.0 74844.0 75259.0 76138.9 76581.2 5861.8 6964.5 7358.2 7771.3 8018.8 10547.5 12202.9 12551.1 12619.1 12676.9 8536.4 10426.0 10472.9 10742.2 10928.5 10090.6 10488.8 10719.0 10843.3 11116.6 11021.7 11057.9 11163.8 11236.6 11290.0 7034.9 7252.2 7347.5 7394.1 7493.9 9805.5 10434.6 10574.7 11205.5 11249.8 05:21:58 INFO - PROCESS | 6893 | 05:21:58 INFO - PROCESS | 6893 | [#3] mootools.html Cycles:180 Average:5325.02 Median:4965.26 stddev:2955.73 (59.5%) stddev-sans-first:2948.60 05:21:58 INFO - PROCESS | 6893 | Values: 1307.0 1442.8 1569.0 1641.8 1743.1 6548.4 7505.2 7937.2 8422.7 8487.0 7166.3 7862.7 8066.0 8207.4 8297.0 2272.1 2300.8 2301.2 2305.2 2309.9 2298.8 2327.5 2335.5 2339.4 2347.6 2275.7 2281.3 2306.4 2308.0 2315.6 2270.7 2285.9 2290.0 2290.5 2310.8 2284.4 2312.8 2314.7 2315.9 2325.5 8171.7 8249.9 8317.1 8322.4 8326.3 4991.6 5109.0 5138.3 5144.9 5158.3 2531.9 3225.5 3443.0 3446.1 3458.1 1008.0 1037.6 1055.6 1065.2 1074.6 10671.4 10679.6 10737.5 10748.3 10773.6 3846.8 4400.5 4575.6 4672.7 4678.8 7932.8 8568.2 8996.6 9289.7 9321.7 5100.8 5118.5 5132.7 5134.1 5145.7 3515.6 4168.9 4539.6 4665.7 4711.1 3698.3 3767.1 3791.8 3831.2 3854.2 1229.6 1248.5 1249.1 1256.6 1256.9 733.4 777.6 828.3 829.6 858.1 5331.7 5401.6 5441.0 5478.3 5479.6 426.9 427.2 438.7 439.6 443.1 2808.2 2889.5 3572.9 3645.5 3920.6 9674.5 9873.3 10019.4 10045.6 10053.3 9983.3 9991.8 10010.7 10037.2 10041.7 7747.9 7825.2 7831.1 7832.8 7840.9 5280.5 5296.8 5460.9 5494.7 5518.9 9070.0 9105.3 9239.6 9257.2 9271.4 8883.1 9162.1 9255.8 9386.2 9397.6 6562.7 6786.0 6910.5 7072.8 7247.0 7764.5 7796.5 7815.1 7860.9 7872.5 6953.1 7085.1 7095.4 7096.9 7192.1 6943.3 6964.8 7012.3 7070.9 7092.1 6586.1 6613.5 6696.4 6721.3 6733.4 4772.5 4839.2 4845.1 4879.9 4928.4 4744.6 4768.8 4829.7 4937.0 4938.9 05:21:58 INFO - PROCESS | 6893 | 05:21:58 INFO - PROCESS | 6893 | [#4] prototype.html Cycles:180 Average:4490.53 Median:4976.65 stddev:1389.53 (27.9%) stddev-sans-first:1357.27 05:21:58 INFO - PROCESS | 6893 | Values: 294.7 299.4 300.1 307.6 312.0 4142.0 4572.7 5442.8 5470.7 5486.6 4220.9 4492.0 4864.5 5151.7 5436.5 4133.7 4195.1 4205.2 4212.8 4235.4 3363.7 3467.9 3684.3 3881.7 3975.5 3608.7 4130.6 4135.6 4141.3 4142.3 4134.6 4144.2 4150.7 4165.4 4171.0 2854.4 3625.2 3632.3 3636.6 3660.4 4355.8 4863.8 4977.8 5368.5 5390.9 4246.5 4631.8 4640.7 4658.6 4691.7 4709.3 5724.1 5731.9 5736.3 5821.9 3376.4 3565.6 3598.2 3850.9 3855.0 5076.9 6031.3 6046.4 6079.2 6092.9 3302.0 3572.4 3922.0 3930.6 3935.5 3619.8 3984.2 3991.5 4007.5 4021.6 3848.4 4208.4 4231.4 4583.7 4584.4 4812.8 5772.4 5805.3 5818.4 5865.4 963.6 1131.6 1226.4 1282.0 1302.7 850.5 867.9 868.6 873.5 880.7 4975.5 5137.5 5201.3 5239.9 5251.9 5088.5 5326.5 5384.7 5619.1 5653.6 4114.8 4619.5 5056.8 5195.6 5228.9 2980.7 2988.0 3005.1 3021.7 3034.7 4472.1 4823.7 5674.1 5704.8 5853.6 4755.9 4784.0 5066.3 5641.3 5747.7 4788.7 5099.8 5718.7 5778.5 5783.3 4725.5 5255.8 5321.5 5467.8 5535.6 5479.6 5484.0 5506.7 5509.5 5520.8 4755.5 4888.6 5653.7 5753.5 5788.4 3579.5 3834.7 3908.8 3921.7 3952.2 5618.7 5741.8 5807.5 5850.5 5938.6 5400.4 5749.4 5764.0 5796.9 5816.5 5196.1 5645.8 5717.5 5806.2 5834.1 4575.4 5305.5 5326.8 5434.6 5602.3 5078.7 5342.9 5774.9 5820.7 5864.1 4701.7 5370.9 5671.9 5702.3 5708.9 05:21:58 INFO - PROCESS | 6893 | 05:21:58 INFO - PROCESS | 6893 | [#5] yui.html Cycles:180 Average:18672.10 Median:22705.63 stddev:26300.96 (115.8%) stddev-sans-first:26340.15 05:21:58 INFO - PROCESS | 6893 | Values: 707.3 727.7 730.5 780.1 823.1 10459.5 14610.0 14648.9 14683.5 14741.0 12742.0 13279.5 13373.7 13475.8 13622.3 833.2 848.7 851.5 852.9 853.2 604.9 639.6 814.0 837.3 841.5 793.2 819.1 869.7 879.8 882.6 857.2 872.6 872.9 873.8 874.5 648.9 656.8 662.6 664.7 665.7 13490.3 13574.3 13608.7 13632.7 13680.7 10933.9 12842.7 13427.0 13581.9 13687.5 11308.2 11312.0 11342.6 11408.4 11498.0 3634.8 3650.1 3674.9 3676.5 3769.2 69050.6 89901.7 90699.0 93435.1 93968.0 10406.2 10461.0 10482.9 10521.9 10584.2 20010.6 20420.0 20430.7 20555.0 20707.0 7494.6 7605.1 7616.5 7661.8 7774.8 4246.8 4253.1 4273.0 4312.8 4331.2 3338.6 3417.5 3417.8 3451.0 3451.7 629.5 636.1 643.7 650.3 655.3 566.8 609.8 626.9 634.3 648.2 8514.7 8860.7 8909.2 9046.6 9062.1 467.9 539.0 616.6 620.7 658.9 4113.8 4202.7 4304.9 4312.3 4490.4 82636.8 91962.5 92404.5 93145.0 107467.9 43467.0 53255.5 60494.7 71207.6 91266.5 81106.8 83201.6 83527.0 85291.6 85955.0 7450.3 8187.3 9724.6 9726.4 9767.7 43093.8 44943.3 47271.8 51304.0 57441.7 51023.7 61029.0 68302.0 70788.9 71913.6 11969.3 12296.6 12388.8 12553.3 12762.4 11927.4 14002.4 14194.9 14303.6 14560.5 13109.7 13198.4 13227.9 13458.4 13932.1 12817.9 12852.0 13220.1 13469.3 13489.0 12671.8 13018.7 13332.3 13360.9 13494.7 10273.9 10525.9 11053.7 11242.5 11359.9 8471.3 9040.3 9480.6 10324.4 10922.2 05:21:58 INFO - PROCESS | 6893 | -------- Summary: end -------- 05:21:58 INFO - PROCESS | 6893 | 05:21:58 INFO - TEST-INFO | 6893: exit 0 05:21:58 INFO - TEST-OK | dromaeo_css | took 1116855ms 05:21:58 INFO - TEST-START | kraken 05:21:58 INFO - operating with platform_type : mac_ 05:21:58 INFO - Initialising browser for kraken test... 05:21:58 INFO - TEST-INFO | started process 6897 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpBrJK2b/profile http://localhost:60621/getInfo.html) 05:22:01 INFO - PROCESS | 6897 | 2016-11-25 05:22:01.709 plugin-container[6898:126438] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 05:22:01 INFO - PROCESS | 6897 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:22:01 INFO - PROCESS | 6897 | 2016-11-25 05:22:01.713 plugin-container[6898:126438] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 05:22:01 INFO - PROCESS | 6897 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:22:01 INFO - PROCESS | 6897 | 2016-11-25 05:22:01.713 plugin-container[6898:126438] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:22:01 INFO - PROCESS | 6897 | __metrics Screen width/height:1600/1200 05:22:01 INFO - PROCESS | 6897 | colorDepth:24 05:22:01 INFO - PROCESS | 6897 | Browser inner width/height: 1024/689 05:22:01 INFO - PROCESS | 6897 | __metrics 05:22:01 ERROR - PROCESS | 6897 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 05:22:02 INFO - TEST-INFO | 6897: exit 0 05:22:02 INFO - Browser initialized. 05:22:02 INFO - Running cycle 1/1 for kraken test... 05:22:02 INFO - TEST-INFO | started process 6899 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpBrJK2b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:22:05 INFO - PROCESS | 6899 | 2016-11-25 05:22:05.399 plugin-container[6900:126557] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4b, name = 'com.apple.tsm.portname' 05:22:05 INFO - PROCESS | 6899 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:22:05 INFO - PROCESS | 6899 | 2016-11-25 05:22:05.400 plugin-container[6900:126557] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 05:22:05 INFO - PROCESS | 6899 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:22:05 INFO - PROCESS | 6899 | 2016-11-25 05:22:05.400 plugin-container[6900:126557] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:22:14 INFO - PROCESS | 6899 | RSS: Main: 241455104 05:22:14 INFO - PROCESS | 6899 | 05:22:17 INFO - PROCESS | 6899 | Cycle 1(1): loaded http://localhost:60621/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60621/tests/kraken/driver.html?testName=audio-beat-detection) 05:22:18 INFO - PROCESS | 6899 | RSS: Main: 253403136 05:22:18 INFO - PROCESS | 6899 | 05:22:21 INFO - PROCESS | 6899 | Cycle 1(1): loaded http://localhost:60621/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60621/tests/kraken/driver.html?testName=audio-dft) 05:22:21 INFO - PROCESS | 6899 | RSS: Main: 261091328 05:22:21 INFO - PROCESS | 6899 | 05:22:25 INFO - PROCESS | 6899 | Cycle 1(1): loaded http://localhost:60621/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60621/tests/kraken/driver.html?testName=audio-fft) 05:22:26 INFO - PROCESS | 6899 | RSS: Main: 263815168 05:22:26 INFO - PROCESS | 6899 | 05:22:28 INFO - PROCESS | 6899 | Cycle 1(1): loaded http://localhost:60621/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60621/tests/kraken/driver.html?testName=audio-oscillator) 05:22:28 INFO - PROCESS | 6899 | RSS: Main: 264970240 05:22:28 INFO - PROCESS | 6899 | 05:22:30 INFO - PROCESS | 6899 | Cycle 1(1): loaded http://localhost:60621/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60621/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:22:31 INFO - PROCESS | 6899 | RSS: Main: 265265152 05:22:31 INFO - PROCESS | 6899 | 05:22:34 INFO - PROCESS | 6899 | Cycle 1(1): loaded http://localhost:60621/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60621/tests/kraken/driver.html?testName=imaging-darkroom) 05:22:34 INFO - PROCESS | 6899 | RSS: Main: 270921728 05:22:34 INFO - PROCESS | 6899 | 05:22:37 INFO - PROCESS | 6899 | Cycle 1(1): loaded http://localhost:60621/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60621/tests/kraken/driver.html?testName=imaging-desaturate) 05:22:38 INFO - PROCESS | 6899 | RSS: Main: 270757888 05:22:38 INFO - PROCESS | 6899 | 05:22:41 INFO - PROCESS | 6899 | Cycle 1(1): loaded http://localhost:60621/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60621/tests/kraken/driver.html?testName=json-parse-financial) 05:22:42 INFO - PROCESS | 6899 | RSS: Main: 271056896 05:22:42 INFO - PROCESS | 6899 | 05:22:43 INFO - PROCESS | 6899 | Cycle 1(1): loaded http://localhost:60621/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60621/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:22:44 INFO - PROCESS | 6899 | RSS: Main: 271204352 05:22:44 INFO - PROCESS | 6899 | 05:22:46 INFO - PROCESS | 6899 | Cycle 1(1): loaded http://localhost:60621/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60621/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:22:47 INFO - PROCESS | 6899 | RSS: Main: 271261696 05:22:47 INFO - PROCESS | 6899 | 05:22:49 INFO - PROCESS | 6899 | Cycle 1(1): loaded http://localhost:60621/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60621/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:22:50 INFO - PROCESS | 6899 | RSS: Main: 271278080 05:22:50 INFO - PROCESS | 6899 | 05:22:53 INFO - PROCESS | 6899 | Cycle 1(1): loaded http://localhost:60621/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60621/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:22:54 INFO - PROCESS | 6899 | RSS: Main: 271511552 05:22:54 INFO - PROCESS | 6899 | 05:22:57 INFO - PROCESS | 6899 | Cycle 1(1): loaded http://localhost:60621/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60621/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:22:57 INFO - PROCESS | 6899 | RSS: Main: 271360000 05:22:57 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | Cycle 1(1): loaded http://localhost:60621/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60621/tests/kraken/driver.html?testName=ai-astar) 05:22:59 INFO - PROCESS | 6899 | RSS: Main: 273154048 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | __start_tp_report 05:22:59 INFO - PROCESS | 6899 | _x_x_mozilla_page_load 05:22:59 INFO - PROCESS | 6899 | _x_x_mozilla_page_load_details 05:22:59 INFO - PROCESS | 6899 | |i|pagename|runs| 05:22:59 INFO - PROCESS | 6899 | |0;ai-astar;111;121;120;118;121;143;175;149;119;119 05:22:59 INFO - PROCESS | 6899 | |1;audio-beat-detection;147;114;116;152;119;118;116;120;135;116 05:22:59 INFO - PROCESS | 6899 | |2;audio-dft;199;201;213;245;282;204;281;211;197;204 05:22:59 INFO - PROCESS | 6899 | |3;audio-fft;89;59;64;100;85;51;77;52;76;76 05:22:59 INFO - PROCESS | 6899 | |4;audio-oscillator;86;71;90;107;104;87;80;72;71;83 05:22:59 INFO - PROCESS | 6899 | |5;imaging-gaussian-blur;90;87;89;87;90;101;90;89;90;88 05:22:59 INFO - PROCESS | 6899 | |6;imaging-darkroom;132;99;99;99;100;98;99;99;99;101 05:22:59 INFO - PROCESS | 6899 | |7;imaging-desaturate;91;97;95;105;126;93;136;143;93;91 05:22:59 INFO - PROCESS | 6899 | |8;json-parse-financial;58;65;70;91;92;74;49;73;86;90 05:22:59 INFO - PROCESS | 6899 | |9;json-stringify-tinderbox;71;72;76;104;128;88;72;75;73;75 05:22:59 INFO - PROCESS | 6899 | |10;stanford-crypto-aes;100;89;123;114;111;77;103;77;101;113 05:22:59 INFO - PROCESS | 6899 | |11;stanford-crypto-ccm;160;170;167;143;153;184;174;171;221;177 05:22:59 INFO - PROCESS | 6899 | |12;stanford-crypto-pbkdf2;174;180;177;176;178;173;176;172;175;190 05:22:59 INFO - PROCESS | 6899 | |13;stanford-crypto-sha256-iterative;68;67;69;69;68;68;69;68;69;70 05:22:59 INFO - PROCESS | 6899 | __end_tp_report 05:22:59 INFO - PROCESS | 6899 | __start_cc_report 05:22:59 INFO - PROCESS | 6899 | _x_x_mozilla_cycle_collect,953 05:22:59 INFO - PROCESS | 6899 | __end_cc_report 05:22:59 INFO - PROCESS | 6899 | __startTimestamp1480080179722__endTimestamp 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | ------- Summary: start ------- 05:22:59 INFO - PROCESS | 6899 | Number of tests: 14 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | [#0] ai-astar Cycles:10 Average:129.60 Median:121.00 stddev:19.90 (16.4%) stddev-sans-first:19.93 05:22:59 INFO - PROCESS | 6899 | Values: 111.0 121.0 120.0 118.0 121.0 143.0 175.0 149.0 119.0 119.0 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | [#1] audio-beat-detection Cycles:10 Average:125.30 Median:119.50 stddev:14.09 (11.8%) stddev-sans-first:12.56 05:22:59 INFO - PROCESS | 6899 | Values: 147.0 114.0 116.0 152.0 119.0 118.0 116.0 120.0 135.0 116.0 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | [#2] audio-dft Cycles:10 Average:223.70 Median:212.00 stddev:33.38 (15.7%) stddev-sans-first:34.18 05:22:59 INFO - PROCESS | 6899 | Values: 199.0 201.0 213.0 245.0 282.0 204.0 281.0 211.0 197.0 204.0 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | [#3] audio-fft Cycles:10 Average:72.90 Median:76.00 stddev:16.21 (21.3%) stddev-sans-first:16.11 05:22:59 INFO - PROCESS | 6899 | Values: 89.0 59.0 64.0 100.0 85.0 51.0 77.0 52.0 76.0 76.0 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | [#4] audio-oscillator Cycles:10 Average:85.10 Median:81.50 stddev:12.76 (15.7%) stddev-sans-first:13.53 05:22:59 INFO - PROCESS | 6899 | Values: 86.0 71.0 90.0 107.0 104.0 87.0 80.0 72.0 71.0 83.0 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | [#5] imaging-gaussian-blur Cycles:10 Average:90.10 Median:89.50 stddev:4.01 (4.5%) stddev-sans-first:4.26 05:22:59 INFO - PROCESS | 6899 | Values: 90.0 87.0 89.0 87.0 90.0 101.0 90.0 89.0 90.0 88.0 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | [#6] imaging-darkroom Cycles:10 Average:102.50 Median:99.00 stddev:10.39 (10.5%) stddev-sans-first:0.83 05:22:59 INFO - PROCESS | 6899 | Values: 132.0 99.0 99.0 99.0 100.0 98.0 99.0 99.0 99.0 101.0 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | [#7] imaging-desaturate Cycles:10 Average:107.00 Median:92.00 stddev:20.14 (21.9%) stddev-sans-first:20.51 05:22:59 INFO - PROCESS | 6899 | Values: 91.0 97.0 95.0 105.0 126.0 93.0 136.0 143.0 93.0 91.0 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | [#8] json-parse-financial Cycles:10 Average:74.80 Median:80.00 stddev:14.85 (18.6%) stddev-sans-first:14.46 05:22:59 INFO - PROCESS | 6899 | Values: 58.0 65.0 70.0 91.0 92.0 74.0 49.0 73.0 86.0 90.0 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | [#9] json-stringify-tinderbox Cycles:10 Average:83.40 Median:74.00 stddev:18.72 (25.3%) stddev-sans-first:19.31 05:22:59 INFO - PROCESS | 6899 | Values: 71.0 72.0 76.0 104.0 128.0 88.0 72.0 75.0 73.0 75.0 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | [#10] stanford-crypto-aes Cycles:10 Average:100.80 Median:118.50 stddev:15.63 (13.2%) stddev-sans-first:16.57 05:22:59 INFO - PROCESS | 6899 | Values: 100.0 89.0 123.0 114.0 111.0 77.0 103.0 77.0 101.0 113.0 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | [#11] stanford-crypto-ccm Cycles:10 Average:172.00 Median:172.50 stddev:20.95 (12.1%) stddev-sans-first:21.77 05:22:59 INFO - PROCESS | 6899 | Values: 160.0 170.0 167.0 143.0 153.0 184.0 174.0 171.0 221.0 177.0 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.10 Median:176.50 stddev:5.11 (2.9%) stddev-sans-first:5.29 05:22:59 INFO - PROCESS | 6899 | Values: 174.0 180.0 177.0 176.0 178.0 173.0 176.0 172.0 175.0 190.0 05:22:59 INFO - PROCESS | 6899 | 05:22:59 INFO - PROCESS | 6899 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:0.85 (1.2%) stddev-sans-first:0.88 05:22:59 INFO - PROCESS | 6899 | Values: 68.0 67.0 69.0 69.0 68.0 68.0 69.0 68.0 69.0 70.0 05:22:59 INFO - PROCESS | 6899 | -------- Summary: end -------- 05:22:59 INFO - PROCESS | 6899 | 05:23:00 INFO - TEST-INFO | 6899: exit 0 05:23:00 INFO - TEST-OK | kraken | took 62248ms 05:23:00 INFO - SUITE-END | took 1179s 05:23:01 INFO - Completed test suite (00:19:39) 05:23:01 INFO - javascript benchmark 05:23:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7081.187968522843, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.0889005523477, 3342.715869151122, 3366.8653253869848, 3377.679120483554, 3397.490376443535, 5466.644668096581, 5612.270404847369, 5638.746256418466, 5649.8587535311635, 5708.857278568038, 5479.53423958964, 5721.456461636149, 5736.110902810061, 5799.391063938289, 5856.2386889704385, 4982.850514484553, 5126.333576635051, 5144.536991670749, 5250.0, 5327.653702509325, 5184.766685499161, 5300.151975683892, 5325.653832500894, 5334.546563542103, 5395.974764794696, 5093.694378337312, 5145.948540514584, 5237.4500677428705, 5271.683698978073, 5290.7354632268425, 5074.010567939254, 5163.277141200235, 5183.118869792144, 5239.449857764918, 5269.841904742864, 5117.437081921023, 5131.666441681279, 5146.665466744649, 5169.069567477853, 5194.584433245369, 5560.332760068777, 5688.772449102031, 5692.260006199209, 5709.857253568694, 5720.685362305072, 6083.939160608382, 6105.490073363223, 6139.079138129272, 6153.938460615426, 6167.044108163242, 6151.077338399231, 6178.62928224308, 6192.256929168483, 6204.131421600934, 6217.720202590849, 5861.941380586182, 5866.237389139427, 5870.618409803349, 5890.970545147246, 5892.734826932756, 6115.327313995457, 6131.040261761377, 6208.9689551551955, 6271.059341098871, 6284.183056202756, 6028.216331876915, 6125.724342404603, 6139.631622102688, 6148.1699970503405, 6163.414475624898, 6177.444030037318, 6185.195924529782, 6201.069839524017, 6207.96896015517, 6208.1308616793685, 6096.481799047042, 6115.205023346936, 6124.724387402578, 6135.32511423743, 6137.956547386955, 6174.166487524217, 6184.5980011299125, 6187.3503282155625, 6211.9689401552705, 6246.312905580383, 4885.511448855086, 4907.386576677915, 4932.1122198004705, 4948.90102197954, 4968.701877887338, 3388.9663652586196, 3936.1340505088833, 4293.635041021485, 4336.566343365701, 4386.780660966939, 5716.28546431696, 5896.852578685567, 5931.881362372815, 5969.224000879858, 5989.131575921467, 6189.597676151036, 6208.906866397007, 6212.223472065992, 6228.096925945712, 6228.875422491525, 4693.319468677023, 5758.913616295759, 5760.711964401763, 5881.852953676193, 5902.0, 5324.9733751331, 5353.892922141613, 5373.704446255493, 5431.293931788842, 5451.454854514517, 5521.503064724114, 6001.669908155093, 6338.429541341301, 6365.854146253719, 6406.910825159827, 5523.8066667668045, 5980.910286345621, 6191.535634827496, 6206.131141640084, 6233.065040244, 6071.301800293023, 6135.07955838608, 6218.347073557211, 6233.2208473940755, 6281.96859015702, 6160.691965401802, 6182.258129024546, 6184.226971628546, 6190.7214175363115, 6208.503319734501, 6037.366076562073, 6154.969225153845, 6191.504679625709, 6196.566240363131, 6235.314115447388, 5912.054071348563, 6000.339962604023, 6033.9698301508215, 6086.1175129606845, 6153.2616086068165, 5976.402359764163, 5994.730237139252, 6034.094885766995, 6104.0347610180015, 6106.096658337741, 5705.286839145107, 5895.557833162444, 5908.468237858441, 5943.851403714942, 6029.397060293935, 6054.27348718139, 6104.0, 6135.785247516315, 6145.033589251397, 6173.84565385869, 5759.481646651821, 5778.913316300175, 6016.187814644967, 6025.337212906665, 6081.331053584191], "value": 5661.297294874979, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.3852299395814, 458.662882781156, 468.1619900378323, 485.8348161625047, 507.69030891156393, 37672.24655506888, 39596.0, 39619.20761584766, 39712.80861574154, 39778.204435911335, 41841.95395115116, 42187.789061054726, 42213.78893105538, 42354.57645423545, 42369.57630423695, 7399.815004624874, 7661.923380766205, 9240.30697697672, 9430.29272804539, 9436.528173591327, 8360.790980225482, 11232.382218977953, 11243.988041076302, 11341.092712582973, 11424.086073114131, 9572.42565446072, 11246.931541503543, 11270.943645281763, 11331.206815522917, 11373.976342129206, 9505.524723763854, 11222.214444988855, 11275.985161335519, 11285.322880627189, 11322.830157547643, 10144.898551014468, 10644.095251903598, 11115.11043122671, 11130.443477826157, 11139.832902506469, 34791.4781278281, 36413.90758277229, 39028.0, 39393.0, 39436.40845387321, 3267.248532837437, 3399.915002124942, 3406.8416738308847, 3488.7557870949045, 3838.1172330363893, 29824.850875745484, 30452.238694032603, 30530.694693053007, 30614.38771224563, 30802.69197308043, 3115.7195852373397, 3305.008497450755, 3305.520699498559, 3350.447176215946, 3350.932981340384, 491514.0, 498419.0, 499852.0, 500594.0, 503216.99999999633, 57906.42093579094, 58435.70782146062, 58439.0, 58490.0, 58527.41472585305, 61041.69479152664, 61065.38934610686, 61290.38709612847, 61381.38618613757, 61445.0, 15413.152276624893, 15641.13973731433, 15647.765283520755, 15694.450694225647, 15729.292181851844, 8861.291096712377, 10820.513076911559, 11788.23376480526, 11969.341686207168, 12092.27446353204, 16339.754903676454, 16447.013179209047, 16477.01137931728, 16477.176141192893, 16480.752788708178, 6300.369963003663, 7159.534630249122, 7165.749198778018, 7172.533785303938, 7259.165196002422, 844.5692696724715, 983.9950800246096, 993.7217579077869, 1040.6357774778767, 1055.5672174408455, 28443.431131377256, 29333.12000639984, 29551.556726649116, 29699.96050138235, 30077.248068798017, 837.3426859914938, 937.6249500199974, 1025.6410256410375, 1029.9443070852435, 1093.2019625673304, 12846.0, 14454.421823127168, 15036.699266014837, 15332.310046047955, 15550.377984880479, 397500.0, 454492.0, 495055.0, 527731.0, 534117.0, 56063.0, 56541.151882721795, 56611.71694141503, 56664.15003774947, 56707.43292567104, 1362.5844117544243, 1373.8214032175754, 1374.71131062478, 1388.9861101389058, 1391.262630805695, 28396.57405138966, 29100.12699619015, 29189.708102919547, 29655.0, 29982.0, 49321.753391232814, 49322.0, 49532.0, 49567.50432495773, 49595.0, 578237.0, 583182.0, 584680.0, 585397.0, 588870.0, 38509.0, 39154.0, 39464.60535394724, 39496.605033949294, 40053.0, 37188.6281137196, 44454.55545444504, 44811.87970300768, 44843.32735008912, 46100.30849537192, 37874.242515148995, 38004.0, 38264.42603361008, 38340.41415371857, 38669.41995870008, 37166.07084822793, 37447.0, 37985.0, 38447.23105537929, 38543.0, 36664.63335366719, 37059.0, 37471.43792843055, 37553.06117347088, 37627.81186094161, 27632.86183569069, 30827.0, 33390.33219335551, 33391.33217335688, 33901.83049084739, 21830.57881864721, 24960.0, 25059.498810024063, 25322.74677253204, 25541.0], "value": 19736.59416883039, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.9232432755648, 351.5289512053847, 355.76163970140004, 368.20467789574525, 388.5007765021946, 18597.72103418448, 19247.422577322668, 19663.213471461124, 20239.79760202396, 20514.48713782156, 17520.386786462477, 19611.607767844635, 20077.297294594693, 20341.593168136664, 20350.491237719063, 5693.117566777156, 5694.430556944303, 6001.339852616209, 6002.069679199709, 6026.156338112646, 5708.286464191976, 5858.502027327682, 5914.1128830675525, 5926.0, 5938.079597662369, 5009.398872135331, 5572.634704497404, 5820.796272130477, 5871.266091738533, 5883.294004719418, 4843.878903027453, 5718.142278658193, 5774.595778295521, 5801.709914504279, 5849.415058494138, 4996.275540046673, 4996.675216110941, 5046.520580544842, 5080.77136528857, 5081.212412076134, 24172.0, 24505.754942450705, 24676.259712208663, 24778.177128374817, 25094.996200152156, 2203.0, 2216.213244298283, 2229.320057382498, 2444.8777561121874, 2963.140689200151, 13838.723225535434, 14687.63280917975, 15147.924260378737, 15175.861810364268, 15713.528594142193, 1782.5008997480727, 1807.5119717676155, 1808.3489943620286, 1880.9059547022594, 1923.1538123225737, 81467.9999999994, 101613.98386016118, 105118.42322365247, 105914.0, 106432.46783766031, 10247.38515689061, 10551.15590752738, 10672.199585031154, 10718.464076796128, 10880.78238435227, 13371.264580447974, 13380.732385352238, 13384.0, 13413.865861341457, 13451.461941522426, 8031.317338026216, 8258.504489730636, 8347.624356903954, 8488.363372747068, 8493.10822363643, 8189.795255118551, 8198.94543810043, 8356.373272004503, 8512.063672995966, 8516.190961858738, 6364.395382438661, 6534.0, 6547.0, 6550.70521826519, 6632.701528431232, 1514.5229252785336, 1794.5872449336464, 1845.5570663041042, 1857.3684947118045, 1867.9252829886652, 874.606427107804, 951.1439704266078, 986.649739342523, 1012.6101450941441, 1066.8293073108266, 16751.6649667006, 20573.69139462909, 21008.369748907557, 21081.683774743393, 21087.578248434944, 944.1644144931785, 945.0107523662729, 959.788846453779, 979.1383582447401, 994.5922171909482, 7640.312371886557, 10611.991860773214, 10804.027637512505, 11030.400591914127, 11208.04731597807, 551889.0, 585169.0, 585450.0, 597727.0795522135, 601150.0, 76458.61770691222, 96670.51664741631, 99175.50412247893, 99418.50872236922, 105192.99999999847, 878.7759121423996, 938.8544775559799, 1000.0299709281985, 1004.2417974429405, 1004.6684594083935, 18876.05619719036, 19179.424617261506, 19239.326623568668, 19319.51701207481, 19435.70846437333, 44570.10859782851, 50874.23688644599, 51020.48979510306, 52243.1714889977, 53934.730326348115, 57432.0, 74844.0, 75259.0, 76138.85791713018, 76581.2341876574, 5861.827634473208, 6964.547304425196, 7358.1538123116525, 7771.261730135628, 8018.83962320762, 10547.52536135891, 12202.938985305016, 12551.058670599925, 12619.053570982396, 12676.936615316865, 8536.40245182856, 10426.009529094725, 10472.94763526208, 10742.24804263694, 10928.453577321261, 10090.596376144871, 10488.842667360148, 10719.0, 10843.349399036084, 11116.61091861781, 11021.669349919515, 11057.889421106007, 11163.776724465628, 11236.606718764804, 11290.0, 7034.894476582753, 7252.238514955854, 7347.5224110432655, 7394.112706475043, 7493.88759168602, 9805.460699661588, 10434.634787782392, 10574.682759517227, 11205.495752690986, 11249.775004499701], "value": 9339.102697352408, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.0263901508722, 1442.8268607767066, 1569.0, 1641.7865677461928, 1743.093591332508, 6548.410643042125, 7505.211952744965, 7937.245961633643, 8422.663093476262, 8486.957565212173, 7166.319199676022, 7862.6854925803045, 8066.03207615087, 8207.425480216372, 8297.0, 2272.0798076778888, 2300.769923007703, 2301.1715782318433, 2305.1931823861596, 2309.8749150781236, 2298.804601608857, 2327.4530485336018, 2335.509542995973, 2339.4034521197154, 2347.647852822082, 2275.6927814744963, 2281.2927992322434, 2306.411864974438, 2308.0, 2315.6063469210276, 2270.71616047994, 2285.8628482291115, 2290.03818396274, 2290.4502919299416, 2310.838241323108, 2284.371797755614, 2312.814974802012, 2314.745378008418, 2315.895784689693, 2325.453518423169, 8171.71399001038, 8249.876251856227, 8317.085120636724, 8322.417430779788, 8326.333893288522, 4991.625628077909, 5109.029284435946, 5138.254953031789, 5144.871378215537, 5158.303629010074, 2531.886065127074, 3225.483922572377, 3442.9827850860584, 3446.052335607703, 3458.1354661334667, 1007.9899201007969, 1037.5590374091057, 1055.5777688924416, 1065.239703845427, 1074.6346242277664, 10671.413072281099, 10679.572817087308, 10737.51681174349, 10748.301360411548, 10773.622923197729, 3846.788426636506, 4400.471943366816, 4575.633949284049, 4672.696274742131, 4678.7660616969015, 7932.801679958047, 8568.186022327869, 8996.64013439455, 9289.72130836076, 9321.673741419017, 5100.821471248489, 5118.462561430996, 5132.666376685504, 5134.101532231844, 5145.716985565755, 3515.6308587598446, 4168.937465938014, 4539.591436770706, 4665.650076244296, 4711.104890070944, 3698.3158115748674, 3767.05823544114, 3791.810409479515, 3831.16104269952, 3854.1713531590844, 1229.557359350633, 1248.450681700049, 1249.0881656390886, 1256.6355756830417, 1256.9308688022247, 733.3839574757229, 777.5684495105157, 828.2545708862073, 829.6142293833396, 858.1032820702382, 5331.653442526223, 5401.567874570025, 5440.972795136078, 5478.331154640669, 5479.6164268500825, 426.9295566231537, 427.1904740516703, 438.6753802186359, 439.5867884188916, 443.0872402850139, 2808.2137001639576, 2889.4943384907556, 3572.9285414291567, 3645.5260816093737, 3920.5687374388795, 9674.516274186262, 9873.308868379288, 10019.448930308758, 10045.598176073021, 10053.346532475365, 9983.251256155818, 9991.750206244757, 10010.699679009642, 10037.19702423819, 10041.698749037541, 7747.915291859144, 7825.217478252129, 7831.138574756772, 7832.843343133105, 7840.9215907841335, 5280.471952804689, 5296.788128474818, 5460.945390546044, 5494.725263736877, 5518.862028449321, 9069.954650226706, 9105.317101217302, 9239.584218710039, 9257.166854983085, 9271.397359171633, 8883.111688831064, 9162.129597688208, 9255.76860578491, 9386.249100071842, 9397.624095036395, 6562.704678289393, 6785.9660701696175, 6910.481713871379, 7072.787816365517, 7246.96376518114, 7764.534127952341, 7796.454248202572, 7815.10126335456, 7860.882086768817, 7872.488288261245, 6953.061336719478, 7085.14978202619, 7095.361417472453, 7096.8935465967015, 7192.136943566806, 6943.305669433218, 6964.756233531802, 7012.333828286304, 7070.893936590853, 7092.113485814273, 6586.110875031809, 6613.537052406477, 6696.363845434676, 6721.294264102199, 6733.360330768569, 4772.49888761675, 4839.249916262985, 4845.079434907357, 4879.853604391874, 4928.359313289249, 4744.644151688568, 4768.809247630056, 4829.6860704054125, 4937.012597480447, 4938.901221975612], "value": 4236.664884387787, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.7332663939133, 299.3817766312565, 300.14159503819087, 307.6093361430982, 311.9890803821865, 4141.958580414195, 4572.748498832563, 5442.755076021579, 5470.726463676815, 5486.561075113991, 4220.89447763806, 4491.977540112295, 4864.500337188105, 5151.6908985460805, 5436.510714035736, 4133.6693064554765, 4195.056112374707, 4205.222033923718, 4212.810423530933, 4235.428217190686, 3363.7477189210786, 3467.878624248139, 3684.2631473705233, 3881.7088718346095, 3975.5428125765475, 3608.7113030957594, 4130.63688982635, 4135.607117323864, 4141.254574176647, 4142.337226043818, 4134.565870583576, 4144.150449157931, 4150.709450338478, 4165.396017577435, 4170.998960249518, 2854.3863069439963, 3625.1843335249623, 3632.346177688015, 3636.5999740028574, 3660.401115208179, 4355.803988820479, 4863.805447782084, 4977.800887964477, 5368.463153684639, 5390.946090539083, 4246.4691913510815, 4631.8378856739855, 4640.67515273931, 4658.55743704351, 4691.7184968901975, 4709.340692303081, 5724.079005880985, 5731.882282954825, 5736.311642602873, 5821.941780582225, 3376.4428869236544, 3565.554305711786, 3598.229081645267, 3850.8652197173246, 3855.0, 5076.898462030776, 6031.276246850362, 6046.3745234894905, 6079.209702738615, 6092.878142437171, 3301.9598826369615, 3572.374834403969, 3921.9803900980883, 3930.5872883346838, 3935.5080614923136, 3619.800910949923, 3984.1633257015774, 3991.5210174779213, 4007.519097708235, 4021.5978402160135, 3848.4034974578985, 4208.379773787114, 4231.428757117811, 4583.656225783079, 4584.404027476407, 4812.807487700523, 5772.3938986406665, 5805.303363596311, 5818.389069147764, 5865.35481097079, 963.6193703487223, 1131.643532287327, 1226.4113225651738, 1281.9743605128033, 1302.6873550347848, 850.5116046917893, 867.8915135608049, 868.6221493650282, 873.4759144513368, 880.7313769300425, 4975.502449755068, 5137.504683401949, 5201.349831271091, 5239.904038384677, 5251.894962100737, 5088.542031217208, 5326.547243484347, 5384.730763461811, 5619.100943848965, 5653.575981801299, 4114.773687447159, 4619.491855895849, 5056.763671250403, 5195.636305458657, 5228.882207816863, 2980.687027862043, 2987.985060074686, 3005.128512731327, 3021.743151832075, 3034.696530346992, 4472.064175384179, 4823.734694591831, 5674.099176775744, 5704.800331988444, 5853.64878107315, 4755.9286610700865, 4784.0, 5066.316047333637, 5641.323041235078, 5747.678034052108, 4788.6662570373865, 5099.792671046475, 5718.714064296852, 5778.508826749773, 5783.277090363705, 4725.503822098768, 5255.816046438356, 5321.5476684482255, 5467.835964921059, 5535.5848311376, 5479.589030822784, 5484.0, 5506.697131657797, 5509.531689806411, 5520.779168833202, 4755.476897541199, 4888.584470320063, 5653.660780353192, 5753.453421924911, 5788.392218817132, 3579.463080537836, 3834.712396570324, 3908.8241029154324, 3921.6862650988423, 3952.150287688161, 5618.6628802272, 5741.827745167651, 5807.466600074916, 5850.5027072699295, 5938.604427959513, 5400.405955344988, 5749.447649134794, 5764.0, 5796.913046304225, 5816.47651711348, 5196.064708352533, 5645.793883556777, 5717.485426311652, 5806.200077975841, 5834.066549352253, 4575.398610486436, 5305.495977882095, 5326.786928522815, 5434.619576629598, 5602.299712535933, 5078.669886457368, 5342.866428339323, 5774.94225057744, 5820.679862607428, 5864.06175011996, 4701.670883038291, 5370.865728356823, 5671.886562268649, 5702.258706368311, 5708.857278568069], "value": 4044.089653687262, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.2821086597102, 727.7452891487976, 730.4886579394425, 780.1067777394882, 823.0617096509977, 10459.529321180546, 14610.0, 14648.85351146491, 14683.486077987272, 14740.999999999987, 12742.04434667399, 13279.535216267432, 13373.732525349487, 13475.797863032063, 13622.250776207306, 833.162671515128, 848.7326492154982, 851.5018714052256, 852.9317654587618, 853.2363534636496, 604.9032154855224, 639.5833229396885, 813.9633716482773, 837.3259526081501, 841.5161282262693, 793.1513280789558, 819.1358117186365, 869.652139144341, 879.7580665317025, 882.6028287270722, 857.1985193843744, 872.6378552900538, 872.9083446238149, 873.7684513603837, 874.4796845876725, 648.8572514046903, 656.8489247473108, 662.595816551903, 664.670987861007, 665.6971078159426, 13490.325483725774, 13574.321283935764, 13608.727825443435, 13632.727345453135, 13680.726385472335, 10933.890661093366, 12842.715728427083, 13427.03736539598, 13581.932090339486, 13687.452501899912, 11308.151888608387, 11312.0, 11342.60300889465, 11408.372539510323, 11498.033178553385, 3634.800085995269, 3650.142216579102, 3674.9081272968388, 3676.466912297702, 3769.2461507698295, 69050.61898762047, 89901.65147522792, 90699.0, 93435.08550981028, 93968.0, 10406.219533535015, 10461.0, 10482.947585262025, 10521.894781052244, 10584.153267738562, 20010.59978800445, 20420.0, 20430.69353959692, 20555.0, 20707.0, 7494.6252687366505, 7605.087389513184, 7616.504927179715, 7661.808454788675, 7774.844503110019, 4246.808893599796, 4253.106847561985, 4272.957270427318, 4312.827486900552, 4331.242032644338, 3338.582677165354, 3417.4532074867902, 3417.8120203389053, 3451.0, 3451.6893479586456, 629.5026928726255, 636.0649844728242, 643.732850866885, 650.339904996429, 655.3021032600266, 566.8016194332033, 609.7560975609791, 626.8620903401336, 634.3181080338654, 648.1800522339245, 8514.707493699605, 8860.689875854443, 8909.198172164537, 9046.638134474679, 9062.093790620886, 467.9438467383936, 538.9757460914268, 616.6269407008784, 620.6772478311253, 658.940695337422, 4113.794310284534, 4202.747835129902, 4304.892377690583, 4312.259494207904, 4490.416245888079, 82636.76044859212, 91962.54018729864, 92404.53797731103, 93145.0, 107467.92532074735, 43467.0, 53255.46744532583, 60494.697526512966, 71207.64396177986, 91266.54366728256, 81106.78339824907, 83201.58399207966, 83527.0, 85291.5735421319, 85955.0, 7450.292222238879, 8187.345012398875, 9724.562394692115, 9726.36778609388, 9767.706968790948, 43093.784531077144, 44943.32585011162, 47271.818204543786, 51303.973920520635, 57441.71279143745, 51023.74488127536, 61029.0, 68302.0, 70788.93816592859, 71913.64043179751, 11969.341686207343, 12296.631101066982, 12388.81416778767, 12553.309567973849, 12762.42569084412, 11927.403629818647, 14002.43990240379, 14194.858051419353, 14303.642408939444, 14560.49038283655, 13109.739389500712, 13198.406071726604, 13227.86772132305, 13458.394372253077, 13932.094413863066, 12817.935910320388, 12852.0, 13220.140690855063, 13469.259190744211, 13489.0, 12671.80992285135, 13018.739625207632, 13332.3333833306, 13360.866391335961, 13494.662633434242, 10273.914666003235, 10525.9473702631, 11053.723656908642, 11242.494087765906, 11359.943200284277, 8471.279941205066, 9040.321975851968, 9480.620775168916, 10324.380537167794, 10922.18083643714], "value": 7050.985319170343, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1612.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 121.0, 120.0, 118.0, 121.0, 143.0, 175.0, 149.0, 119.0, 119.0], "value": 129.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 116.0, 152.0, 119.0, 118.0, 116.0, 120.0, 135.0, 116.0], "value": 125.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 201.0, 213.0, 245.0, 282.0, 204.0, 281.0, 211.0, 197.0, 204.0], "value": 223.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 59.0, 64.0, 100.0, 85.0, 51.0, 77.0, 52.0, 76.0, 76.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 90.0, 107.0, 104.0, 87.0, 80.0, 72.0, 71.0, 83.0], "value": 85.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 87.0, 90.0, 101.0, 90.0, 89.0, 90.0, 88.0], "value": 90.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 99.0, 99.0, 99.0, 100.0, 98.0, 99.0, 99.0, 99.0, 101.0], "value": 102.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 97.0, 95.0, 105.0, 126.0, 93.0, 136.0, 143.0, 93.0, 91.0], "value": 107.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 65.0, 70.0, 91.0, 92.0, 74.0, 49.0, 73.0, 86.0, 90.0], "value": 74.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 76.0, 104.0, 128.0, 88.0, 72.0, 75.0, 73.0, 75.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 89.0, 123.0, 114.0, 111.0, 77.0, 103.0, 77.0, 101.0, 113.0], "value": 100.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 170.0, 167.0, 143.0, 153.0, 184.0, 174.0, 171.0, 221.0, 177.0], "value": 172.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 180.0, 177.0, 176.0, 178.0, 173.0, 176.0, 172.0, 175.0, 190.0], "value": 177.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 69.0, 68.0, 68.0, 69.0, 68.0, 69.0, 70.0], "value": 68.5, "unit": "score"}]}]} 05:23:01 INFO - Return code: 0 05:23:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:23:01 ERROR - # TBPL SUCCESS # 05:23:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:23:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:23:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:23:01 INFO - 2016-11-25 05:23:01.550 screenresolution[6904:126932] starting screenresolution argv=screenresolution get 05:23:01 INFO - 2016-11-25 05:23:01.552 screenresolution[6904:126932] Display 0: 1600x1200x32@60 05:23:01 INFO - 2016-11-25 05:23:01.558 screenresolution[6905:126935] starting screenresolution argv=screenresolution list 05:23:01 INFO - Available Modes on Display 0 05:23:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:23:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:23:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:23:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:23:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:23:01 INFO - Intel Iris: 05:23:01 INFO - Chipset Model: Intel Iris 05:23:01 INFO - Type: GPU 05:23:01 INFO - Bus: Built-In 05:23:01 INFO - VRAM (Dynamic, Max): 1536 MB 05:23:01 INFO - Vendor: Intel (0x8086) 05:23:01 INFO - Device ID: 0x0a2e 05:23:01 INFO - Revision ID: 0x0009 05:23:01 INFO - Displays: 05:23:01 INFO - Display: 05:23:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:23:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:23:01 INFO - Display Serial Number: 1600x1200 60 05:23:01 INFO - Main Display: Yes 05:23:01 INFO - Mirror: Off 05:23:01 INFO - Online: Yes 05:23:01 INFO - Rotation: Supported 05:23:01 INFO - Return code: 0 05:23:01 INFO - Running post-action listener: _resource_record_post_action 05:23:01 INFO - [mozharness: 2016-11-25 13:23:01.793359Z] Finished run-tests step (success) 05:23:01 INFO - Running post-run listener: _resource_record_post_run 05:23:01 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 643584; Write bytes: 693884928; Read time: 5; Write time: 1839 05:23:01 INFO - TinderboxPrint: CPU usage
54.7% 05:23:01 INFO - TinderboxPrint: I/O read bytes / time
643,584 / 5 05:23:01 INFO - TinderboxPrint: I/O write bytes / time
693,884,928 / 1,839 05:23:01 INFO - TinderboxPrint: CPU idle
2,198.3 (45.3%) 05:23:01 INFO - TinderboxPrint: CPU system
986.3 (20.3%) 05:23:01 INFO - TinderboxPrint: CPU user
1,669.2 (34.4%) 05:23:01 INFO - TinderboxPrint: Swap in / out
383,262,720 / 0 05:23:01 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 378282496; Write bytes: 373613056; Read time: 28792; Write time: 704 05:23:01 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 643072; Write bytes: 305837568; Read time: 5; Write time: 1098 05:23:02 INFO - Running post-run listener: _upload_blobber_files 05:23:02 INFO - Blob upload gear active. 05:23:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:23:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:23:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:23:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:23:02 INFO - (blobuploader) - INFO - Open directory for files ... 05:23:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:23:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:23:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:03 INFO - (blobuploader) - INFO - Done attempting. 05:23:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:23:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:23:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:04 INFO - (blobuploader) - INFO - Done attempting. 05:23:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:23:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:23:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:23:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:23:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:23:06 INFO - (blobuploader) - INFO - Done attempting. 05:23:06 INFO - (blobuploader) - INFO - Iteration through files over. 05:23:06 INFO - Return code: 0 05:23:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:23:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:23:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c43448ab8a77be8ed03cdbfae0576e6f681e206d10bf0acbda1de9d8a0897fe45a6c626630368d0e593e1b03d10695798f530e46bf269bd31bb0be49d1449a82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/848b6b2629af8d36034ed1d2c6cc5b18bf6b442e3775ed91867809f917a2e9887dccf924e3c72951d69f061e8b34b1b9109a4d22cd3c5c4f16eba4dbba50f0e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7cd168f709ba504904a3d97f7f887bdb54422675fba30de6be078d2d423a6a64fd21b4ab53d55e5df79c571e66c444749e25714be697ac80169d79363b641ae9"} 05:23:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:23:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:23:06 INFO - Contents: 05:23:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c43448ab8a77be8ed03cdbfae0576e6f681e206d10bf0acbda1de9d8a0897fe45a6c626630368d0e593e1b03d10695798f530e46bf269bd31bb0be49d1449a82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/848b6b2629af8d36034ed1d2c6cc5b18bf6b442e3775ed91867809f917a2e9887dccf924e3c72951d69f061e8b34b1b9109a4d22cd3c5c4f16eba4dbba50f0e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7cd168f709ba504904a3d97f7f887bdb54422675fba30de6be078d2d423a6a64fd21b4ab53d55e5df79c571e66c444749e25714be697ac80169d79363b641ae9"} 05:23:06 INFO - Running post-run listener: copy_logs_to_upload_dir 05:23:06 INFO - Copying logs to upload dir... 05:23:06 INFO - mkdir: /builds/slave/test/build/upload/logs 05:23:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.233437 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-11-25 05:23:07.042076) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 05:23:07.046109) ========= 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.l8WHkFZWWe/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.wD9qpEP8nP/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c43448ab8a77be8ed03cdbfae0576e6f681e206d10bf0acbda1de9d8a0897fe45a6c626630368d0e593e1b03d10695798f530e46bf269bd31bb0be49d1449a82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/848b6b2629af8d36034ed1d2c6cc5b18bf6b442e3775ed91867809f917a2e9887dccf924e3c72951d69f061e8b34b1b9109a4d22cd3c5c4f16eba4dbba50f0e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7cd168f709ba504904a3d97f7f887bdb54422675fba30de6be078d2d423a6a64fd21b4ab53d55e5df79c571e66c444749e25714be697ac80169d79363b641ae9"} build_url:https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011502 build_url: 'https://queue.taskcluster.net/v1/task/TBCxDV-0RtiJWRgygvWYvQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/c43448ab8a77be8ed03cdbfae0576e6f681e206d10bf0acbda1de9d8a0897fe45a6c626630368d0e593e1b03d10695798f530e46bf269bd31bb0be49d1449a82", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/848b6b2629af8d36034ed1d2c6cc5b18bf6b442e3775ed91867809f917a2e9887dccf924e3c72951d69f061e8b34b1b9109a4d22cd3c5c4f16eba4dbba50f0e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7cd168f709ba504904a3d97f7f887bdb54422675fba30de6be078d2d423a6a64fd21b4ab53d55e5df79c571e66c444749e25714be697ac80169d79363b641ae9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 05:23:07.082283) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 05:23:07.082680) ========= 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.l8WHkFZWWe/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.wD9qpEP8nP/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.005357 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 05:23:07.132992) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 05:23:07.133403) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 05:23:07.133783) ========= ========= Total master_lag: 0.19 =========