builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0192 starttime: 1492040073.54 results: success (0) buildid: 20170412154507 builduid: 8ee6041ee70c4f8aaa18dcae8b4ff6f5 revision: bb3beaf897283fe2abeab514300bf79d5998a85d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 16:34:33.539950) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 16:34:33.540882) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 16:34:33.541537) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 16:34:33.574125) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:34:33.574790) ========= 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.NMAn1FNZhH/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.QThhf5THMh/Listeners TMPDIR=/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 --2017-04-12 16:34:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 28.7M=0s 2017-04-12 16:34:33 (28.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.154587 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:34:33.754376) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:34:33.757805) ========= 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.NMAn1FNZhH/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.QThhf5THMh/Listeners TMPDIR=/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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.021297 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:34:33.806687) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-12 16:34:33.807093) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev bb3beaf897283fe2abeab514300bf79d5998a85d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev bb3beaf897283fe2abeab514300bf79d5998a85d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NMAn1FNZhH/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.QThhf5THMh/Listeners TMPDIR=/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 2017-04-12 16:34:33,869 truncating revision to first 12 chars 2017-04-12 16:34:33,869 Setting DEBUG logging. 2017-04-12 16:34:33,869 attempt 1/10 2017-04-12 16:34:33,869 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/bb3beaf89728?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-12 16:34:35,119 unpacking tar archive at: pine-bb3beaf89728/testing/mozharness/ program finished with exit code 0 elapsedTime=1.623491 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-12 16:34:35.458143) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 16:34:35.458626) ========= script_repo_revision: bb3beaf897283fe2abeab514300bf79d5998a85d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 16:34:35.459076) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 16:34:35.459378) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 16:34:35.480447) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-12 16:34:35.481165) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NMAn1FNZhH/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.QThhf5THMh/Listeners TMPDIR=/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 16:34:35 INFO - MultiFileLogger online at 20170412 16:34:35 in /builds/slave/test 16:34:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 16:34:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:34:35 INFO - {'append_to_log': False, 16:34:35 INFO - 'base_work_dir': '/builds/slave/test', 16:34:35 INFO - 'blob_upload_branch': 'Pine', 16:34:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:34:35 INFO - 'branch': 'Pine', 16:34:35 INFO - 'buildbot_json_path': 'buildprops.json', 16:34:35 INFO - 'config_files': ('talos/mac_config.py',), 16:34:35 INFO - 'default_actions': ('clobber', 16:34:35 INFO - 'read-buildbot-config', 16:34:35 INFO - 'download-and-extract', 16:34:35 INFO - 'populate-webroot', 16:34:35 INFO - 'create-virtualenv', 16:34:35 INFO - 'install', 16:34:35 INFO - 'run-tests'), 16:34:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:34:35 INFO - 'download_minidump_stackwalk': True, 16:34:35 INFO - 'download_symbols': 'ondemand', 16:34:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:34:35 INFO - 'tooltool.py': '/tools/tooltool.py', 16:34:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:34:35 INFO - '/tools/misc-python/virtualenv.py')}, 16:34:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:34:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:34:35 INFO - 'gecko_profile': False, 16:34:35 INFO - 'gecko_profile_interval': 0, 16:34:35 INFO - 'installer_path': 'installer.exe', 16:34:35 INFO - 'log_level': 'info', 16:34:35 INFO - 'log_name': 'talos', 16:34:35 INFO - 'log_to_console': True, 16:34:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:34:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:34:35 INFO - 'opt_config_files': (), 16:34:35 INFO - 'pip_index': False, 16:34:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:34:35 INFO - 'cmd': ('bash', 16:34:35 INFO - '-c', 16:34:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:34:35 INFO - 'enabled': True, 16:34:35 INFO - 'halt_on_failure': False, 16:34:35 INFO - 'name': 'check_screen_resolution'},), 16:34:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:34:35 INFO - 'cmd': ('bash', 16:34:35 INFO - '-c', 16:34:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:34:35 INFO - 'enabled': True, 16:34:35 INFO - 'halt_on_failure': False, 16:34:35 INFO - 'name': 'check_screen_resolution'},), 16:34:35 INFO - 'run_cmd_checks_enabled': True, 16:34:35 INFO - 'suite': 'dromaeojs-e10s', 16:34:35 INFO - 'system_bits': '32', 16:34:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:34:35 INFO - 'title': 't-yosemite-r7-0192', 16:34:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:34:35 INFO - 'use_talos_json': True, 16:34:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:34:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:34:35 INFO - 'work_dir': 'build'} 16:34:35 INFO - [mozharness: 2017-04-12 23:34:35.672736Z] Running clobber step. 16:34:35 INFO - Running pre-action listener: _resource_record_pre_action 16:34:35 INFO - Running main action method: clobber 16:34:35 INFO - rmtree: /builds/slave/test/build 16:34:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:34:37 INFO - Running post-action listener: _resource_record_post_action 16:34:37 INFO - [mozharness: 2017-04-12 23:34:37.487575Z] Finished clobber step (success) 16:34:37 INFO - [mozharness: 2017-04-12 23:34:37.487703Z] Running read-buildbot-config step. 16:34:37 INFO - Running pre-action listener: _resource_record_pre_action 16:34:37 INFO - Running main action method: read_buildbot_config 16:34:37 INFO - Using buildbot properties: 16:34:37 INFO - { 16:34:37 INFO - "project": "", 16:34:37 INFO - "product": "firefox", 16:34:37 INFO - "script_repo_revision": "default", 16:34:37 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 16:34:37 INFO - "repository": "", 16:34:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 16:34:37 INFO - "buildid": "20170412154507", 16:34:37 INFO - "pgo_build": "False", 16:34:37 INFO - "basedir": "/builds/slave/test", 16:34:37 INFO - "buildnumber": 0, 16:34:37 INFO - "slavename": "t-yosemite-r7-0192", 16:34:37 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:34:37 INFO - "platform": "yosemite_r7", 16:34:37 INFO - "scheduler": "tests-pine-macosx64-talos", 16:34:37 INFO - "branch": "pine", 16:34:37 INFO - "repo_path": "projects/pine", 16:34:37 INFO - "revision": "bb3beaf897283fe2abeab514300bf79d5998a85d", 16:34:37 INFO - "stage_platform": "macosx64", 16:34:37 INFO - "builduid": "8ee6041ee70c4f8aaa18dcae8b4ff6f5", 16:34:37 INFO - "slavebuilddir": "test" 16:34:37 INFO - } 16:34:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:34:37 INFO - Running post-action listener: _resource_record_post_action 16:34:37 INFO - [mozharness: 2017-04-12 23:34:37.491049Z] Finished read-buildbot-config step (success) 16:34:37 INFO - [mozharness: 2017-04-12 23:34:37.491157Z] Running download-and-extract step. 16:34:37 INFO - Running pre-action listener: _resource_record_pre_action 16:34:37 INFO - Running main action method: download_and_extract 16:34:37 INFO - mkdir: /builds/slave/test/build/tests 16:34:37 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')]} 16:34:37 INFO - trying https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:34:37 INFO - Downloading https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:34:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 16:34:38 INFO - Downloaded 1325 bytes. 16:34:38 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:34:38 INFO - Using the following test package requirements: 16:34:38 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:34:38 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:34:38 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:34:38 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:34:38 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:34:38 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:34:38 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:34:38 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:34:38 INFO - u'jsshell-mac.zip'], 16:34:38 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:34:38 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:34:38 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:34:38 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:34:38 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:34:38 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:34:38 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:34:38 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:34:38 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:34:38 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:34:38 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:34:38 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:34:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:34:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:34:38 INFO - Fetch https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:34:40 INFO - Expected file size: 52581277 16:34:40 INFO - Obtained file size: 52581277 16:34:56 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:34:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:34:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:34:56 INFO - Fetch https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:34:58 INFO - Expected file size: 52581277 16:34:58 INFO - Obtained file size: 52581277 16:35:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:35:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:35:14 INFO - Fetch https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:35:15 INFO - Expected file size: 14405596 16:35:15 INFO - Obtained file size: 14405596 16:35:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:15 INFO - trying https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:35:15 INFO - Downloading https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:35:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 16:35:17 INFO - Downloaded 70998975 bytes. 16:35:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:35:17 INFO - mkdir: /builds/slave/test/properties 16:35:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:35:17 INFO - Writing to file /builds/slave/test/properties/build_url 16:35:17 INFO - Contents: 16:35:17 INFO - build_url:https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:35:18 INFO - Running post-action listener: _resource_record_post_action 16:35:18 INFO - Running post-action listener: set_extra_try_arguments 16:35:18 INFO - [mozharness: 2017-04-12 23:35:18.535663Z] Finished download-and-extract step (success) 16:35:18 INFO - [mozharness: 2017-04-12 23:35:18.535805Z] Running populate-webroot step. 16:35:18 INFO - Running pre-action listener: _resource_record_pre_action 16:35:18 INFO - Running main action method: populate_webroot 16:35:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:35:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:35:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:35:18 INFO - '--tppagecycles', 16:35:18 INFO - '7'], 16:35:18 INFO - 'tests': ['tsvgm']}}, 16:35:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:35:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:35:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:35:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:35:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:35:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:35:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:35:18 INFO - 'talos_options': ['--disable-e10s'], 16:35:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:35:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:35:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:35:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:35:18 INFO - 'talos_options': ['--disable-e10s'], 16:35:18 INFO - 'tests': ['damp', 'tps']}, 16:35:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:35:18 INFO - 'tests': ['damp', 'tps']}, 16:35:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:35:18 INFO - 'tests': ['dromaeo_dom']}, 16:35:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:35:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:35:18 INFO - 'tests': ['basic_compositor_video']}, 16:35:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:35:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:35:18 INFO - 'tests': ['a11yr', 16:35:18 INFO - 'ts_paint', 16:35:18 INFO - 'tpaint', 16:35:18 INFO - 'sessionrestore', 16:35:18 INFO - 'sessionrestore_no_auto_restore', 16:35:18 INFO - 'tabpaint']}, 16:35:18 INFO - 'other-e10s': {'tests': ['a11yr', 16:35:18 INFO - 'ts_paint', 16:35:18 INFO - 'tpaint', 16:35:18 INFO - 'sessionrestore', 16:35:18 INFO - 'sessionrestore_no_auto_restore', 16:35:18 INFO - 'tabpaint']}, 16:35:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:35:18 INFO - 'ts_paint', 16:35:18 INFO - 'tpaint', 16:35:18 INFO - 'sessionrestore', 16:35:18 INFO - 'sessionrestore_no_auto_restore', 16:35:18 INFO - 'tabpaint']}, 16:35:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:35:18 INFO - 'ts_paint', 16:35:18 INFO - 'tpaint', 16:35:18 INFO - 'sessionrestore', 16:35:18 INFO - 'sessionrestore_no_auto_restore', 16:35:18 INFO - 'tabpaint']}, 16:35:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:35:18 INFO - 'tests': ['a11yr', 16:35:18 INFO - 'ts_paint', 16:35:18 INFO - 'tpaint', 16:35:18 INFO - 'sessionrestore', 16:35:18 INFO - 'sessionrestore_no_auto_restore', 16:35:18 INFO - 'tabpaint']}, 16:35:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:35:18 INFO - 'tests': ['a11yr', 16:35:18 INFO - 'ts_paint', 16:35:18 INFO - 'tpaint', 16:35:18 INFO - 'sessionrestore', 16:35:18 INFO - 'sessionrestore_no_auto_restore', 16:35:18 INFO - 'tabpaint']}, 16:35:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:35:18 INFO - 'tests': ['tsvgx', 16:35:18 INFO - 'tsvgr_opacity', 16:35:18 INFO - 'tart', 16:35:18 INFO - 'tscrollx', 16:35:18 INFO - 'cart', 16:35:18 INFO - 'tsvg_static']}, 16:35:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:35:18 INFO - 'tsvgr_opacity', 16:35:18 INFO - 'tart', 16:35:18 INFO - 'tscrollx', 16:35:18 INFO - 'cart', 16:35:18 INFO - 'tsvg_static']}, 16:35:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:35:18 INFO - 'talos_options': ['--disable-e10s'], 16:35:18 INFO - 'tests': ['tp5o']}, 16:35:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:35:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:35:18 INFO - 'talos_options': ['--disable-e10s', 16:35:18 INFO - '--xperf_path', 16:35:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:35:18 INFO - 'tests': ['tp5n']}, 16:35:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:35:18 INFO - 'talos_options': ['--xperf_path', 16:35:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:35:18 INFO - 'tests': ['tp5n']}}, 16:35:18 INFO - 'talos.zip': {'path': '', 16:35:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:35:18 INFO - Running post-action listener: _resource_record_post_action 16:35:18 INFO - [mozharness: 2017-04-12 23:35:18.554168Z] Finished populate-webroot step (success) 16:35:18 INFO - [mozharness: 2017-04-12 23:35:18.554278Z] Running create-virtualenv step. 16:35:18 INFO - Running pre-action listener: _resource_record_pre_action 16:35:18 INFO - Running main action method: create_virtualenv 16:35:18 INFO - Creating virtualenv /builds/slave/test/build/venv 16:35:18 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 16:35:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:35:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:35:18 INFO - Using real prefix '/tools/python27' 16:35:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:35:19 INFO - Installing distribute.............................................................................................................................................................................................done. 16:35:22 INFO - Installing pip.................done. 16:35:22 INFO - Return code: 0 16:35:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:35:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:35:22 INFO - Reading from file tmpfile_stdout 16:35:22 INFO - Output received: 16:35:22 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) 16:35:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:35: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')]} 16:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35: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')]} 16:35:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:23 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 0x10458bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc009566ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104369d30>, '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 0x104290db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10435ebe8>, '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.NMAn1FNZhH/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.QThhf5THMh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 16:35:23 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 16:35:23 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 16:35:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMAn1FNZhH/Render', 16:35:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:23 INFO - 'HOME': '/Users/cltbld', 16:35:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:23 INFO - 'LOGNAME': 'cltbld', 16:35:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:23 INFO - 'MOZ_NO_REMOTE': '1', 16:35:23 INFO - 'NO_EM_RESTART': '1', 16:35:23 INFO - 'PAGER': '/bin/cat', 16:35:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:23 INFO - 'PWD': '/builds/slave/test', 16:35:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:23 INFO - 'SHELL': '/bin/bash', 16:35:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QThhf5THMh/Listeners', 16:35:23 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 16:35:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:23 INFO - 'USER': 'cltbld', 16:35:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:23 INFO - 'XPC_FLAGS': '0x0', 16:35:23 INFO - 'XPC_SERVICE_NAME': '0', 16:35:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:23 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 16:35:23 INFO - Cleaning up... 16:35:23 INFO - Return code: 0 16:35:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:35:23 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')]} 16:35:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:23 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')]} 16:35:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:23 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 0x10458bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc009566ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104369d30>, '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 0x104290db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10435ebe8>, '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.NMAn1FNZhH/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.QThhf5THMh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 16:35:23 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 16:35:23 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 16:35:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMAn1FNZhH/Render', 16:35:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:23 INFO - 'HOME': '/Users/cltbld', 16:35:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:23 INFO - 'LOGNAME': 'cltbld', 16:35:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:23 INFO - 'MOZ_NO_REMOTE': '1', 16:35:23 INFO - 'NO_EM_RESTART': '1', 16:35:23 INFO - 'PAGER': '/bin/cat', 16:35:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:23 INFO - 'PWD': '/builds/slave/test', 16:35:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:23 INFO - 'SHELL': '/bin/bash', 16:35:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QThhf5THMh/Listeners', 16:35:23 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 16:35:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:23 INFO - 'USER': 'cltbld', 16:35:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:23 INFO - 'XPC_FLAGS': '0x0', 16:35:23 INFO - 'XPC_SERVICE_NAME': '0', 16:35:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:23 INFO - Downloading/unpacking psutil>=3.1.1 16:35: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 16:35: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 16:35: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 16:35: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 16:35: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 16:35: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 16:35:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:35:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:35:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:35:27 INFO - Installing collected packages: psutil 16:35:27 INFO - Running setup.py install for psutil 16:35:27 INFO - building 'psutil._psutil_osx' extension 16:35:27 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 16:35:27 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 16:35:28 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 16:35:28 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 16:35:28 INFO - building 'psutil._psutil_posix' extension 16:35:28 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 16:35:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:35:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:35:28 INFO - ^ 16:35:28 INFO - 1 warning generated. 16:35:28 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 16:35:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:35:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:35:28 INFO - Successfully installed psutil 16:35:28 INFO - Cleaning up... 16:35:28 INFO - Return code: 0 16:35:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:28 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 0x10458bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc009566ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104369d30>, '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 0x104290db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10435ebe8>, '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.NMAn1FNZhH/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.QThhf5THMh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 16:35:28 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 16:35:28 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 16:35:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMAn1FNZhH/Render', 16:35:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:28 INFO - 'HOME': '/Users/cltbld', 16:35:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:28 INFO - 'LOGNAME': 'cltbld', 16:35:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:28 INFO - 'MOZ_NO_REMOTE': '1', 16:35:28 INFO - 'NO_EM_RESTART': '1', 16:35:28 INFO - 'PAGER': '/bin/cat', 16:35:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:28 INFO - 'PWD': '/builds/slave/test', 16:35:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:28 INFO - 'SHELL': '/bin/bash', 16:35:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QThhf5THMh/Listeners', 16:35:28 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 16:35:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:28 INFO - 'USER': 'cltbld', 16:35:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:28 INFO - 'XPC_FLAGS': '0x0', 16:35:28 INFO - 'XPC_SERVICE_NAME': '0', 16:35:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:35:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:35:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:35:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:35:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:35:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:35:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:35:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:35:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:35:31 INFO - Installing collected packages: mozsystemmonitor 16:35:31 INFO - Running setup.py install for mozsystemmonitor 16:35:31 INFO - Successfully installed mozsystemmonitor 16:35:31 INFO - Cleaning up... 16:35:31 INFO - Return code: 0 16:35:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:35:31 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')]} 16:35:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:31 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')]} 16:35:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:31 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 0x10458bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc009566ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104369d30>, '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 0x104290db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10435ebe8>, '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.NMAn1FNZhH/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.QThhf5THMh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 16:35:31 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 16:35:31 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 16:35:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMAn1FNZhH/Render', 16:35:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:31 INFO - 'HOME': '/Users/cltbld', 16:35:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:31 INFO - 'LOGNAME': 'cltbld', 16:35:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:31 INFO - 'MOZ_NO_REMOTE': '1', 16:35:31 INFO - 'NO_EM_RESTART': '1', 16:35:31 INFO - 'PAGER': '/bin/cat', 16:35:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:31 INFO - 'PWD': '/builds/slave/test', 16:35:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:31 INFO - 'SHELL': '/bin/bash', 16:35:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QThhf5THMh/Listeners', 16:35:31 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 16:35:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:31 INFO - 'USER': 'cltbld', 16:35:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:31 INFO - 'XPC_FLAGS': '0x0', 16:35:31 INFO - 'XPC_SERVICE_NAME': '0', 16:35:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:31 INFO - Downloading/unpacking jsonschema==2.5.1 16:35:31 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 16:35:31 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 16:35:31 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 16:35:31 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 16:35:31 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 16:35:31 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 16:35:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:35:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:35:34 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 16:35:34 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 16:35:34 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 16:35:34 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 16:35:34 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 16:35:34 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 16:35:34 INFO - Downloading functools32-3.2.3-2.tar.gz 16:35:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:35:35 INFO - warning: no files found matching '*.txt' 16:35:35 INFO - no previously-included directories found matching 'build' 16:35:35 INFO - no previously-included directories found matching 'dist' 16:35:35 INFO - no previously-included directories found matching '.git*' 16:35:35 INFO - Installing collected packages: jsonschema, functools32 16:35:35 INFO - Running setup.py install for functools32 16:35:35 INFO - warning: no files found matching '*.txt' 16:35:35 INFO - no previously-included directories found matching 'build' 16:35:35 INFO - no previously-included directories found matching 'dist' 16:35:35 INFO - no previously-included directories found matching '.git*' 16:35:35 INFO - Successfully installed jsonschema functools32 16:35:35 INFO - Cleaning up... 16:35:35 INFO - Return code: 0 16:35:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:35: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')]} 16:35:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35: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')]} 16:35:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10458bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc009566ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104369d30>, '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 0x104290db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10435ebe8>, '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.NMAn1FNZhH/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.QThhf5THMh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 16:35:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:35:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:35:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMAn1FNZhH/Render', 16:35:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:35 INFO - 'HOME': '/Users/cltbld', 16:35:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:35 INFO - 'LOGNAME': 'cltbld', 16:35:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:35 INFO - 'MOZ_NO_REMOTE': '1', 16:35:35 INFO - 'NO_EM_RESTART': '1', 16:35:35 INFO - 'PAGER': '/bin/cat', 16:35:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:35 INFO - 'PWD': '/builds/slave/test', 16:35:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:35 INFO - 'SHELL': '/bin/bash', 16:35:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QThhf5THMh/Listeners', 16:35:35 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 16:35:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:35 INFO - 'USER': 'cltbld', 16:35:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:35 INFO - 'XPC_FLAGS': '0x0', 16:35:35 INFO - 'XPC_SERVICE_NAME': '0', 16:35:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:35:35 INFO - Cleaning up... 16:35:35 INFO - Return code: 0 16:35:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:35: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')]} 16:35:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35: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')]} 16:35:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10458bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc009566ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104369d30>, '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 0x104290db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10435ebe8>, '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.NMAn1FNZhH/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.QThhf5THMh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 16:35:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:35:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:35:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMAn1FNZhH/Render', 16:35:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:35 INFO - 'HOME': '/Users/cltbld', 16:35:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:35 INFO - 'LOGNAME': 'cltbld', 16:35:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:35 INFO - 'MOZ_NO_REMOTE': '1', 16:35:35 INFO - 'NO_EM_RESTART': '1', 16:35:35 INFO - 'PAGER': '/bin/cat', 16:35:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:35 INFO - 'PWD': '/builds/slave/test', 16:35:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:35 INFO - 'SHELL': '/bin/bash', 16:35:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QThhf5THMh/Listeners', 16:35:35 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 16:35:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:35 INFO - 'USER': 'cltbld', 16:35:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:35 INFO - 'XPC_FLAGS': '0x0', 16:35:35 INFO - 'XPC_SERVICE_NAME': '0', 16:35:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:35 INFO - Downloading/unpacking blobuploader==1.2.4 16:35:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:35:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:35:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:35:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:35:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:35:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:35:38 INFO - Downloading blobuploader-1.2.4.tar.gz 16:35:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:35:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:35:38 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 16:35:38 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 16:35:38 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 16:35:38 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 16:35:38 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 16:35:38 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 16:35:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:35:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:35:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:35:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:35:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:35:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:35:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:35:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:35:39 INFO - Downloading docopt-0.6.1.tar.gz 16:35:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:35:39 INFO - Installing collected packages: blobuploader, requests, docopt 16:35:39 INFO - Running setup.py install for blobuploader 16:35:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:35:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:35:39 INFO - Running setup.py install for requests 16:35:40 INFO - Running setup.py install for docopt 16:35:40 INFO - Successfully installed blobuploader requests docopt 16:35:40 INFO - Cleaning up... 16:35:40 INFO - Return code: 0 16:35:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:35: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')]} 16:35:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35: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')]} 16:35:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:40 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 0x10458bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc009566ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104369d30>, '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 0x104290db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10435ebe8>, '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.NMAn1FNZhH/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.QThhf5THMh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 16:35:40 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 16:35:40 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 16:35:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMAn1FNZhH/Render', 16:35:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:40 INFO - 'HOME': '/Users/cltbld', 16:35:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:40 INFO - 'LOGNAME': 'cltbld', 16:35:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:40 INFO - 'MOZ_NO_REMOTE': '1', 16:35:40 INFO - 'NO_EM_RESTART': '1', 16:35:40 INFO - 'PAGER': '/bin/cat', 16:35:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:40 INFO - 'PWD': '/builds/slave/test', 16:35:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:40 INFO - 'SHELL': '/bin/bash', 16:35:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QThhf5THMh/Listeners', 16:35:40 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 16:35:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:40 INFO - 'USER': 'cltbld', 16:35:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:40 INFO - 'XPC_FLAGS': '0x0', 16:35:40 INFO - 'XPC_SERVICE_NAME': '0', 16:35:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:35:40 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-Sdpyov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:35:41 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-wtHxiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:35:41 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-nGuEZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:35:41 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-sTX0sw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:35:41 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-PWiPan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:35:41 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-OS2eVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:35:41 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-a0lVjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:35:41 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-Py4PUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:35:41 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-hLptAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:35:41 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-UBj652-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:35:42 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-taxuCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:35:42 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-k7dy0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:35:42 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-LIKVOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:35:42 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-mKHhXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:35:42 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-q7ptWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:35:42 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-usaJHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:35:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:35:42 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-CFB3B4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:35:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:35:42 INFO - Running setup.py install for manifestparser 16:35:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:35:42 INFO - Running setup.py install for mozcrash 16:35:43 INFO - Running setup.py install for mozdebug 16:35:43 INFO - Running setup.py install for mozdevice 16:35:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:35:43 INFO - Running setup.py install for mozfile 16:35:43 INFO - Running setup.py install for mozhttpd 16:35:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:35:43 INFO - Running setup.py install for mozinfo 16:35:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:35:43 INFO - Running setup.py install for mozInstall 16:35:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:35:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:35:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:35:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:35:43 INFO - Running setup.py install for mozleak 16:35:44 INFO - Running setup.py install for mozlog 16:35:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:35:44 INFO - Running setup.py install for moznetwork 16:35:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:35:44 INFO - Running setup.py install for mozprocess 16:35:44 INFO - Running setup.py install for mozprofile 16:35:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:35:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:35:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:35:44 INFO - Running setup.py install for mozrunner 16:35:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:35:44 INFO - Running setup.py install for mozscreenshot 16:35:45 INFO - Running setup.py install for moztest 16:35:45 INFO - Running setup.py install for mozversion 16:35:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:35:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:35:45 INFO - Cleaning up... 16:35:45 INFO - Return code: 0 16:35:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:35:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:45 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 0x10458bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc009566ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104369d30>, '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 0x104290db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10435ebe8>, '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.NMAn1FNZhH/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.QThhf5THMh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 16:35:45 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 16:35:45 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 16:35:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMAn1FNZhH/Render', 16:35:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:45 INFO - 'HOME': '/Users/cltbld', 16:35:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:45 INFO - 'LOGNAME': 'cltbld', 16:35:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:45 INFO - 'MOZ_NO_REMOTE': '1', 16:35:45 INFO - 'NO_EM_RESTART': '1', 16:35:45 INFO - 'PAGER': '/bin/cat', 16:35:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:45 INFO - 'PWD': '/builds/slave/test', 16:35:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:45 INFO - 'SHELL': '/bin/bash', 16:35:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QThhf5THMh/Listeners', 16:35:45 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 16:35:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:45 INFO - 'USER': 'cltbld', 16:35:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:45 INFO - 'XPC_FLAGS': '0x0', 16:35:45 INFO - 'XPC_SERVICE_NAME': '0', 16:35:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:35:45 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-5ArG01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:35:45 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)) 16:35:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:35:45 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-ykXNIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:35:45 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)) 16:35:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:35:45 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-Mn6wvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:35:45 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)) 16:35:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:35:45 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-TJG1kj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:35:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 16:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:35:46 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-LTtBlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:35:46 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)) 16:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:35:46 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-xCwpxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:35:46 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)) 16:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:35:46 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-j3EDWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:35:46 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)) 16:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:35:46 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-GeXQSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:35:46 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)) 16:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:35:46 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-rNQBFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:35:46 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)) 16:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:35:46 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-doWxYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:35:46 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)) 16:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:35:46 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-RalEAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:35:46 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)) 16:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:35:46 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-79Afdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:35:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 16:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:35:46 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-JAR1PG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:35:46 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)) 16:35:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:35:46 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-fXPTbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:35:47 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)) 16:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:35:47 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-0KL0zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:35:47 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)) 16:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:35:47 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-e7aFPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:35:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 16:35:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:35:47 INFO - Running setup.py (path:/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/pip-DEVrmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:35:47 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)) 16:35:47 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)) 16:35:47 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)) 16:35:47 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)) 16:35:47 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:35:47 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:35:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:35:47 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 16:35:47 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 16:35:47 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 16:35:47 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 16:35:47 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 16:35:47 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 16:35:50 INFO - Downloading blessings-1.6.tar.gz 16:35:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:35:50 INFO - Installing collected packages: blessings 16:35:50 INFO - Running setup.py install for blessings 16:35:50 INFO - Successfully installed blessings 16:35:50 INFO - Cleaning up... 16:35:50 INFO - Return code: 0 16:35:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:35:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:35:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:35:50 INFO - Reading from file tmpfile_stdout 16:35:50 INFO - Current package versions: 16:35:50 INFO - blessings == 1.6 16:35:50 INFO - blobuploader == 1.2.4 16:35:50 INFO - docopt == 0.6.1 16:35:50 INFO - functools32 == 3.2.3-2 16:35:50 INFO - jsonschema == 2.5.1 16:35:50 INFO - manifestparser == 1.1 16:35:50 INFO - mozInstall == 1.12 16:35:50 INFO - mozcrash == 1.0 16:35:50 INFO - mozdebug == 0.1 16:35:50 INFO - mozdevice == 0.50 16:35:50 INFO - mozfile == 1.2 16:35:50 INFO - mozhttpd == 0.7 16:35:50 INFO - mozinfo == 0.9 16:35:50 INFO - mozleak == 0.1 16:35:50 INFO - mozlog == 3.4 16:35:50 INFO - moznetwork == 0.27 16:35:50 INFO - mozprocess == 0.25 16:35:50 INFO - mozprofile == 0.28 16:35:50 INFO - mozrunner == 6.13 16:35:50 INFO - mozscreenshot == 0.1 16:35:50 INFO - mozsystemmonitor == 0.3 16:35:50 INFO - moztest == 0.8 16:35:50 INFO - mozversion == 1.4 16:35:50 INFO - psutil == 3.1.1 16:35:50 INFO - requests == 1.2.3 16:35:50 INFO - wsgiref == 0.1.2 16:35:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:35:50 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')]} 16:35:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:50 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')]} 16:35:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:50 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 0x10458bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc009566ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104369d30>, '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 0x104290db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10435ebe8>, '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.NMAn1FNZhH/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.QThhf5THMh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 16:35:50 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 16:35:50 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 16:35:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMAn1FNZhH/Render', 16:35:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:50 INFO - 'HOME': '/Users/cltbld', 16:35:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:50 INFO - 'LOGNAME': 'cltbld', 16:35:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:50 INFO - 'MOZ_NO_REMOTE': '1', 16:35:50 INFO - 'NO_EM_RESTART': '1', 16:35:50 INFO - 'PAGER': '/bin/cat', 16:35:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:50 INFO - 'PWD': '/builds/slave/test', 16:35:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:50 INFO - 'SHELL': '/bin/bash', 16:35:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QThhf5THMh/Listeners', 16:35:50 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 16:35:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:50 INFO - 'USER': 'cltbld', 16:35:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:50 INFO - 'XPC_FLAGS': '0x0', 16:35:50 INFO - 'XPC_SERVICE_NAME': '0', 16:35:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:50 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)) 16:35:50 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)) 16:35:50 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)) 16:35:50 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)) 16:35:50 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)) 16:35:50 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)) 16:35:50 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)) 16:35:50 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)) 16:35:50 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)) 16:35:50 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)) 16:35:50 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)) 16:35:50 INFO - Cleaning up... 16:35:50 INFO - Return code: 0 16:35:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:35:50 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')]} 16:35:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:50 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')]} 16:35:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:50 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 0x10458bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10458e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc009566ec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104369d30>, '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 0x104290db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10435ebe8>, '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.NMAn1FNZhH/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.QThhf5THMh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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 16:35:50 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 16:35:50 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 16:35:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMAn1FNZhH/Render', 16:35:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:50 INFO - 'HOME': '/Users/cltbld', 16:35:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:50 INFO - 'LOGNAME': 'cltbld', 16:35:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:50 INFO - 'MOZ_NO_REMOTE': '1', 16:35:50 INFO - 'NO_EM_RESTART': '1', 16:35:50 INFO - 'PAGER': '/bin/cat', 16:35:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:50 INFO - 'PWD': '/builds/slave/test', 16:35:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:50 INFO - 'SHELL': '/bin/bash', 16:35:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QThhf5THMh/Listeners', 16:35:50 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 16:35:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:50 INFO - 'USER': 'cltbld', 16:35:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:50 INFO - 'XPC_FLAGS': '0x0', 16:35:50 INFO - 'XPC_SERVICE_NAME': '0', 16:35:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:35:51 INFO - Cleaning up... 16:35:51 INFO - Return code: 0 16:35:51 INFO - Running post-action listener: _resource_record_post_action 16:35:51 INFO - Running post-action listener: _start_resource_monitoring 16:35:51 INFO - Starting resource monitoring. 16:35:51 INFO - [mozharness: 2017-04-12 23:35:51.241448Z] Finished create-virtualenv step (success) 16:35:51 INFO - [mozharness: 2017-04-12 23:35:51.242429Z] Running install step. 16:35:51 INFO - Running pre-action listener: _resource_record_pre_action 16:35:51 INFO - Running main action method: install 16:35:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:35:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:35:51 INFO - Reading from file tmpfile_stdout 16:35:51 INFO - Detecting whether we're running mozinstall >=1.0... 16:35:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:35:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:35:51 INFO - Reading from file tmpfile_stdout 16:35:51 INFO - Output received: 16:35:51 INFO - Usage: mozinstall [options] installer 16:35:51 INFO - Options: 16:35:51 INFO - -h, --help show this help message and exit 16:35:51 INFO - -d DEST, --destination=DEST 16:35:51 INFO - Directory to install application into. [default: 16:35:51 INFO - "/builds/slave/test"] 16:35:51 INFO - --app=APP Application being installed. [default: firefox] 16:35:51 INFO - mkdir: /builds/slave/test/build/application 16:35:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:35:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:36:11 INFO - Reading from file tmpfile_stdout 16:36:11 INFO - Output received: 16:36:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:36:11 INFO - Running post-action listener: _resource_record_post_action 16:36:11 INFO - [mozharness: 2017-04-12 23:36:11.578938Z] Finished install step (success) 16:36:11 INFO - [mozharness: 2017-04-12 23:36:11.579080Z] Running run-tests step. 16:36:11 INFO - Running pre-action listener: _resource_record_pre_action 16:36:11 INFO - Running main action method: run_tests 16:36:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:36:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:36:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:36:11 INFO - 2017-04-12 16:36:11.593 screenresolution[2740:41535] starting screenresolution argv=screenresolution get 16:36:11 INFO - 2017-04-12 16:36:11.596 screenresolution[2740:41535] Display 0: 1600x1200x32@60 16:36:11 INFO - 2017-04-12 16:36:11.601 screenresolution[2741:41537] starting screenresolution argv=screenresolution list 16:36:11 INFO - Available Modes on Display 0 16:36:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:36:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:36:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:36:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:36:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:36:11 INFO - Intel Iris: 16:36:11 INFO - Chipset Model: Intel Iris 16:36:11 INFO - Type: GPU 16:36:11 INFO - Bus: Built-In 16:36:11 INFO - VRAM (Dynamic, Max): 1536 MB 16:36:11 INFO - Vendor: Intel (0x8086) 16:36:11 INFO - Device ID: 0x0a2e 16:36:11 INFO - Revision ID: 0x0009 16:36:11 INFO - Displays: 16:36:11 INFO - Display: 16:36:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:36:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:36:11 INFO - Display Serial Number: 1600x1200 60 16:36:11 INFO - Main Display: Yes 16:36:11 INFO - Mirror: Off 16:36:11 INFO - Online: Yes 16:36:11 INFO - Rotation: Supported 16:36:11 INFO - Return code: 0 16:36:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:36:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:36:11 INFO - Python 2.7.3 16:36:11 INFO - Return code: 0 16:36:11 INFO - grabbing minidump binary from tooltool 16:36:11 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')]} 16:36:11 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 0x104369d30>, '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 0x104290db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10435ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:36:11 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 16:36:11 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 16:36:11 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 16:36:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:36:11 INFO - Return code: 0 16:36:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:36:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:36:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:36:11 INFO - ENV: RUST_BACKTRACE is now 1 16:36:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:36:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:36:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0192', '--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 16:36:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0192 --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 16:36:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMAn1FNZhH/Render', 16:36:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:36:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:36:11 INFO - 'HOME': '/Users/cltbld', 16:36:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:36:11 INFO - 'LOGNAME': 'cltbld', 16:36:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:36:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:36:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:36:11 INFO - 'MOZ_NO_REMOTE': '1', 16:36:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:36:11 INFO - 'NO_EM_RESTART': '1', 16:36:11 INFO - 'PAGER': '/bin/cat', 16:36:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:36:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:36:11 INFO - 'PWD': '/builds/slave/test', 16:36:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:36:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:36:11 INFO - 'RUST_BACKTRACE': '1', 16:36:11 INFO - 'SHELL': '/bin/bash', 16:36:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QThhf5THMh/Listeners', 16:36:11 INFO - 'TMPDIR': '/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/', 16:36:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:36:11 INFO - 'USER': 'cltbld', 16:36:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:36:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:36:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:36:11 INFO - 'XPC_FLAGS': '0x0', 16:36:11 INFO - 'XPC_SERVICE_NAME': '0', 16:36:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:36:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0192', '--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 16:36:12 INFO - mozversion application_buildid: 20170412154507 16:36:12 INFO - mozversion application_changeset: bb3beaf897283fe2abeab514300bf79d5998a85d 16:36:12 INFO - mozversion application_display_name: Nightly 16:36:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:36:12 INFO - mozversion application_name: Firefox 16:36:12 INFO - mozversion application_remotingname: firefox 16:36:12 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 16:36:12 INFO - mozversion application_vendor: Mozilla 16:36:12 INFO - mozversion application_version: 55.0a1 16:36:12 INFO - mozversion platform_buildid: 20170412154507 16:36:12 INFO - mozversion platform_changeset: bb3beaf897283fe2abeab514300bf79d5998a85d 16:36:12 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 16:36:12 INFO - mozversion platform_version: 55.0a1 16:36:12 INFO - using testdate: 1492040172 16:36:12 INFO - actual date: 1492040172 16:36:12 INFO - starting webserver on 'localhost:61105' 16:36:12 INFO - SUITE-START | Running 2 tests 16:36:12 INFO - TEST-START | dromaeo_css 16:36:12 INFO - operating with platform_type : mac_ 16:36:12 INFO - Initialising browser for dromaeo_css test... 16:36:12 INFO - TEST-INFO | started process 2751 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/tmpZbW1gl/profile http://localhost:61105/getInfo.html) 16:36:15 INFO - PID 2751 | 2017-04-12 16:36:15.319 plugin-container[2753:41706] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 16:36:15 INFO - PID 2751 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:36:15 INFO - PID 2751 | 2017-04-12 16:36:15.322 plugin-container[2753:41706] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b1f, name = 'com.apple.CFPasteboardClient' 16:36:15 INFO - PID 2751 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:36:15 INFO - PID 2751 | 2017-04-12 16:36:15.322 plugin-container[2753:41706] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:36:15 INFO - PID 2751 | __metrics Screen width/height:1600/1200 16:36:15 INFO - PID 2751 | colorDepth:24 16:36:15 INFO - PID 2751 | Browser inner width/height: 1024/689 16:36:15 INFO - PID 2751 | __metrics 16:36:15 ERROR - PID 2751 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 16:36:16 INFO - TEST-INFO | 2751: exit 0 16:36:16 INFO - Browser initialized. 16:36:16 INFO - Running cycle 1/1 for dromaeo_css test... 16:36:16 INFO - TEST-INFO | started process 2754 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/tmpZbW1gl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:36:18 INFO - PID 2754 | 2017-04-12 16:36:18.906 plugin-container[2755:41835] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 16:36:18 INFO - PID 2754 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:36:18 INFO - PID 2754 | 2017-04-12 16:36:18.909 plugin-container[2755:41835] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 16:36:18 INFO - PID 2754 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:36:18 INFO - PID 2754 | 2017-04-12 16:36:18.909 plugin-container[2755:41835] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:36:28 INFO - PID 2754 | RSS: Main: 236453888 16:36:28 INFO - PID 2754 | 16:39:32 INFO - PID 2754 | Cycle 1(1): loaded http://localhost:61105/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61105/tests/dromaeo/cssquery-ext.html) 16:39:34 INFO - PID 2754 | RSS: Main: 256774144 16:39:34 INFO - PID 2754 | 16:41:16 INFO - PID 2754 | ************************* 16:41:16 INFO - PID 2754 | A coding exception was thrown and uncaught in a Task. 16:41:16 INFO - PID 2754 | 16:41:16 ERROR - PID 2754 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:41:16 INFO - PID 2754 | Full stack: 16:41:16 INFO - PID 2754 | ************************* 16:41:16 INFO - PID 2754 | ************************* 16:41:16 INFO - PID 2754 | A coding exception was thrown and uncaught in a Task. 16:41:16 INFO - PID 2754 | 16:41:16 ERROR - PID 2754 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:41:16 INFO - PID 2754 | Full stack: 16:41:16 INFO - PID 2754 | ************************* 16:41:16 INFO - PID 2754 | ************************* 16:41:16 INFO - PID 2754 | A coding exception was thrown and uncaught in a Task. 16:41:16 INFO - PID 2754 | 16:41:16 ERROR - PID 2754 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:41:16 INFO - PID 2754 | Full stack: 16:41:16 INFO - PID 2754 | ************************* 16:41:16 INFO - PID 2754 | ************************* 16:41:16 INFO - PID 2754 | A coding exception was thrown and uncaught in a Task. 16:41:16 INFO - PID 2754 | 16:41:16 ERROR - PID 2754 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:41:16 INFO - PID 2754 | Full stack: 16:41:16 INFO - PID 2754 | ************************* 16:42:36 INFO - PID 2754 | Cycle 1(1): loaded http://localhost:61105/tests/dromaeo/cssquery-ext.html (next: http://localhost:61105/tests/dromaeo/cssquery-jquery.html) 16:42:36 INFO - PID 2754 | RSS: Main: 263852032 16:42:36 INFO - PID 2754 | 16:45:38 INFO - PID 2754 | Cycle 1(1): loaded http://localhost:61105/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61105/tests/dromaeo/cssquery-mootools.html) 16:45:39 INFO - PID 2754 | RSS: Main: 276746240 16:45:39 INFO - PID 2754 | 16:48:41 INFO - PID 2754 | Cycle 1(1): loaded http://localhost:61105/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61105/tests/dromaeo/cssquery-prototype.html) 16:48:41 INFO - PID 2754 | RSS: Main: 269049856 16:48:41 INFO - PID 2754 | 16:51:44 INFO - PID 2754 | Cycle 1(1): loaded http://localhost:61105/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61105/tests/dromaeo/cssquery-yui.html) 16:51:45 INFO - PID 2754 | RSS: Main: 279306240 16:51:45 INFO - PID 2754 | 16:54:46 INFO - PID 2754 | Cycle 1(1): loaded http://localhost:61105/tests/dromaeo/cssquery-yui.html (next: http://localhost:61105/tests/dromaeo/cssquery-dojo.html) 16:54:47 INFO - PID 2754 | RSS: Main: 272998400 16:54:47 INFO - PID 2754 | 16:54:47 INFO - PID 2754 | __start_tp_report 16:54:47 INFO - PID 2754 | _x_x_mozilla_page_load 16:54:47 INFO - PID 2754 | _x_x_mozilla_page_load_details 16:54:47 INFO - PID 2754 | |i|pagename|runs| 16:54:47 INFO - PID 2754 | |0;dojo.html;3500.859965601376;3511.332846759114;3525.1715846776005;3566.215432604826;3569.2682999985004;4986.800527978881;6057.575969682124;6127.953177891112;6194.504439644829;6231.345708700578;6141.117811339985;6155.953487907055;6171.814845554641;6190.3809619038075;6211.472024877879;5450.345958484987;5459.972700136494;5467.097928841747;5474.562035037187;5493.093639549469;5461.481159289861;5475.917861232065;5529.723513824292;5550.944490555104;5585.832425027235;5365.382980957201;5426.457354264581;5485.424030476783;5497.5601951843755;5542.75057622408;5395.919061214085;5419.160030195287;5423.864403389907;5486.451354864522;5497.697626630534;5381.058314794935;5436.456354364572;5436.565074794007;5442.102053161224;5466.781328746845;5148.330717006766;5218.504242097033;6214.347493513161;6217.471514921237;6238.656873871935;6414.294427612959;6529.608223506605;6584.769533066367;6700.93299067008;6737.124173857367;6754.628495432749;6784.660766961632;6792.728290868359;6807.0810440590385;6842.55523390978;6243.500519958392;6251.906221406683;6336.271328797247;6349.936500634934;6352.07894855248;5950.4049595041015;6656.800295991128;6781.559198652072;6957.478189135835;6961.79114626562;6355.237371515355;6459.709313080923;6486.318936511692;6578.934210657928;6668.6665666717445;6709.36261055199;6728.562643428161;6734.393904548614;6743.055972163901;6809.523333366716;6573.638449885302;6689.933100669028;6715.093462382516;6727;6734.090897728842;6468.741250349934;6562.8359291018105;6610.8347291318105;6708;6731.158605174353;5119.948800512022;5123.692578445305;5126.15418455958;5143.537081662594;5174.120399532088;4731.384919960383;4736.786844592001;4738.715677059387;4752.239641657387;4774.259989701602;5451.454854514517;6198.783042593487;6253.499720022478;6373.044043393528;6398.424141827165;6589.110470086573;6705.765298214539;6755.763548275885;6796.082528858639;6797.830054248584;5331.333583302087;6312.179416675895;6322.178116844781;6351.237851457762;6361.586496877688;5753.597248192586;5833.008388573952;5977.970110149421;5988.401159884064;5990.251218597676;6724.731010759515;6856.760013399507;6925.861482770216;6952.721891124298;6966.965165174344;6804.319568043156;6815.522913396015;6837;6841.521093523605;6842.178938527409;5393.541548968382;6882.827929301808;6897.586144831326;6914;6940.7917762467205;6389.840253993501;6519.902201466887;6594.967025165035;6596.5052621052655;6708.6981085850275;5077.720725360141;6755.966220168868;6781.491388146008;6806.6256355900905;6827.6244806536115;6347.5239357047485;6430.678466076771;6471.741130354733;6523.7716679915975;6574.4740420765265;6490.513211509061;6571.605703657796;6597.175353080865;6604.702788374439;6634.668266586555;6258.873402787542;6291.748330066746;6436.324185960407;6438.195225596801;6443.7744678936015;6362.013887847391;6593.472522198309;6595.340465953366;6595.472362211108;6603.306652801387;5348.625596208228;6269.96865015672;6334.334894835976;6382.744690212339;6407.519436042222 16:54:47 INFO - PID 2754 | |1;ext.html;625.543353352053;676.8003438985502;826.0376661189713;1034.4414016431128;1292.5153067599656;36843.447348289796;37915.81042094789;37925.86222413325;37996.81001594995;38176.236475270474;38105;38381;38387;38396.04009899754;38517.61482385182;7260.636968151598;8027.719029833957;8196.221358970888;8587.828243435128;8757.386982911199;8625.137486251357;8663.220310172082;8663.39356245063;8730.738077857643;8730.956345218265;8680.045195028542;8736.825263494726;8756.387052906299;8757;8770.868436973418;8641.351898607598;8690.913090869106;8718.912810871905;8719.128087191293;8728.869066964;8520.19058189471;8548.273396761282;8573.014103378067;8580.613872375758;8583.82832343356;37239.627603723886;37385.62614373849;37630.62369376299;37684.057898552484;37699.05752356185;3652.8356223969718;3655.1593133579427;3694.6305369463107;3695.57500887396;3706.796126213057;30620.387592248255;30696;30703.539446908315;30712;30716.38567228643;3118.1425108095227;3147.401993621205;3155.3373791503814;3168.3346497235607;3173.4763763978917;244567.77716111485;245037.77481112658;277822;278368;280171;47479.5252047482;48033.99575053118;48037.519624804;48107;48139.75930120397;47986;48205;48314.516854831;48316.30892918731;48383.274250886265;11330.546778128783;14185.645358866112;14231;14245.64385890361;14279.500217492337;11731.706707332385;11760.823587646191;11761;11774.88225117755;11777.411129443493;13043.478260869459;13114.737705245841;13127.081104322797;13213.867861321456;13230.272335021666;4956.702597844141;5922.49658779;5940.138679891478;5959.553033522503;6001.279846418458;1009.8636684047707;1034.1571619130384;1035.803197392508;1060.565168281001;1065.3607835298915;21891.343259702233;23234.53530929372;24318.027278908998;24573.5085298293;24826.751732482804;1125.926814757038;1140.2588317593631;1141.42358109156;1167.8131498960126;1180.710725872167;9980.80038399228;12471.314077725812;12580.937095314648;12606.87393126057;12620.747585048248;353049;353462.2326888401;356336;356489;357233;54373;54623.726881365335;54724.726376367864;54747.45252547423;54865.902681946936;1244.4514901712305;1460.123925644626;1542.8380020097952;1547.0562956596468;1550.1629120275152;20013.899930500254;24171;24247.636285456087;24340.51318973646;24350;25744.71276436133;26359.60460593131;36656.71701490435;37729.62270377261;37975.620243798316;297286;300560;303293;304409;304516.95483044884;33537.329253415286;34090.56822613045;34105.829470852485;34319.485207722406;34414.48378274378;38349.84950451491;43485.56514434942;45250.54749452463;45407;45642.77178614086;25103.121390751236;27380;28737.56893646639;30286.394272113994;31223.843880781358;25620.487590247718;30008;30012.699873001864;30717;31097.378052438373;36967.26065478729;36995.26009479849;37103;37156;37243.6275637251;29288.26779330534;30370.544441833834;30689.38621227518;31328.373432530767;31595.21011974719;18829.90585047066;22149.69712720629;24214.273571792874;24504.897279622815;24581.262562123164 16:54:47 INFO - PID 2754 | |2;jquery.html;399.22351027251995;423.37340735711166;445.52859610379;446.62260389970476;465.79039432255496;19552.99999999998;20913.47716307091;21707.565848683018;21806.672899906513;21820;22901.656475152886;22920.312390628307;23615.17346892859;23701.93341299641;23941.401464963386;5939.198208241875;6095.055266433699;6243.344448832875;6295.748170073191;6300.527460440462;6046.667433291189;6153.415425534589;6173.228346456693;6178.135061091451;6214.223222097238;5970.522358211354;6077.361877002917;6162.106494558287;6166.321704612489;6179.258488981306;6141.201643786324;6142.754289828424;6156.692165391735;6216.595921265126;6217.347178546278;5465.945340546623;5495.890082198373;5533.3083364579425;5545.390007099216;5552.86117847053;27476.313092172655;27549.17352479409;27635.032773852818;27769.16692499228;28003;2250.7749225077523;2310.1683393978155;2313.2481943368302;2313.4447732544236;2319.408550819547;17695.115244237866;17712.29150833965;17973.191206395742;18104.818951810445;18248.45254642363;2177.194438057913;2205.470687035116;2250.234920127148;2251.2008237075765;2251.997860951877;123322.38338808248;137250;147423;154646;155693;12709.618711438672;13044;13229.470821167239;13232.603021909357;13302.93348533251;16503.257353418885;16583.253753581117;16697.582560435843;16714.58213544671;16724.49826505207;9040.502772347583;9398.483083430478;9418.529073546295;9507.191888689538;9522.047795220422;9091.454512729257;9097.636094556276;9099.681511147079;9308.488033158106;9322.813543729088;6591.670416479157;7986.880196797053;8023.1575684553445;8054.355651547862;8117.553534555538;2214.6235140026233;2224.922127725522;2231.8884055797143;2240.24951641202;2410.20463247128;895.8835351404276;960.7502049467049;979.4221409368364;1002.5538635307364;1020.3010937507831;18080;18641.7203741944;21355.67966480504;21630.91845407755;21854.562908742053;931.5854444772011;990.2573070197352;995.66147509847;1026.743314171457;1029.994850025745;9163.58763855628;10845.186611004206;11347.581552305961;11358.659240222807;11362.261453005529;674738;679457;693476;698006;710718;122563;134680.32659836637;138246.30876845552;155451;156195;978.3151793744407;1028.9350522209477;1169.2925779903205;1194.7610477904454;1205.409349418796;21956.78043219547;22915.19796807104;23111.324069637223;23319.9506022226;23417.297481075595;50180;64752.35247647652;64937.67531162314;64964.675176623816;65430.67284663546;85681.57159214164;86495.56752216199;88498;95172;99337;8890.86663700058;9148.542572871196;9192.448453092837;9257.25941924631;9293.349465537347;14092.78860817068;14608.269586520419;14630.634234144232;14904.403823847359;14986.175760333286;12145.817812732992;12182.451789669663;12277.508899643914;12297.877021230031;12336.259824410565;11420.029297509805;11580.36308003068;11782.351970641697;11824.467898944396;11933.224340417844;12314.507419703112;12449.813252801396;12471;12517.812232816697;12541.184822986841;8911.777205569653;9127.178553930178;9133.862992054992;9236.168744813;9303.534823258946;11928.880711192776;11947.522099116286;12169.026477881926;12205.69485762863;12365.258084514959 16:54:47 INFO - PID 2754 | |3;mootools.html;1213.5327898758978;1406.8944829137818;1414.57400558043;1424.9643758906032;1442.2284078018258;11159.776804463905;11188.888111118886;11610.535578576857;12152.453139608717;12176.452059657315;11938.462769175387;11953.223040502362;12177.695557611065;12187.207831490947;12197.26816391015;2555.479489703628;2571.331453822001;2577.793776497885;2592.520383729007;2594.9221523354236;2484.391324125592;2541.9364515887064;2552.0302285131716;2553.859537725424;2556.4120252341972;2462.889169987346;2514.9185850084455;2526.178991827654;2550.7576780205945;2551.719310875802;2390.7489713580085;2502.5245203411387;2510.3222130024883;2544.4147845995526;2545.567253566898;1852.5831687870257;2354.458474550846;2570.8843102060264;2571.1386685460393;2574.214864466337;12167.209131406518;12239;12247.755044899051;12256.571020014257;12264.509419623204;6574.868502629968;6773.221079575863;6833.350831671033;6833.863322733568;6852.965735171292;3973.3443981742976;4091.2226676931286;4177.791110444466;4297.376880352363;4366.563343665608;1228.8341073954991;1241.1498123785145;1269.904757143218;1281.5386460874079;1292.650984234261;12497.812532811924;16763.329466821433;16949.49151525456;16961.067141307223;16980.830191698173;5651.604387692904;5695.40198279183;5841.503472204825;5896.705164741746;5954.255718035246;11750.823737643943;12505.499780008699;12529.248245105322;12535.185212961129;12544.310062946626;6679.231888332807;6679.265280819106;6684.131062961784;6696.564723293068;6702.564333318319;5953.7023148843255;6181.81454556364;6234.251889773165;6252.249730032425;6258.061290806416;4359.411479450234;4544.613707834805;4567.200739870509;4598.908021839545;4606.216943119611;1129.2829053551054;1273.0197747734194;1328.581496828496;1341.7383610196114;1366.344154805679;680.2992917295194;771.8070482379405;779.096248351909;788.9802754931058;801.587182600961;6043.576949613484;6082.756689732361;6176.444120029129;6274.278457977391;6392.20097487814;592.9644221346733;599.9700014999232;602.4939051197016;607.2682417686677;609.765240382456;3314.850831712579;4101.830978171207;4238.046439551138;4333.848315308949;4334.306510958231;14999.025063370846;15517.456889009056;15666.216689165496;15701.900866939433;15714.449994250375;11118.833217501744;15240.314185861664;15332.003419777679;15678.451254206047;15693.979891307028;8453.408261421642;10983.890161098447;11151.107911367071;11201.21591488588;11240.887591124148;6466.224053113657;6559.901601476077;6590.901136483052;6630.436412904957;6633.6351500666;13760.862391375958;13899.513517026855;13978.510752123628;14055.156690598596;14102.012859099765;13997.44010239579;14132.78800817968;14355.282235888373;14412.207328596609;14505.709885802014;8881.378303518692;9027.548622568976;9099.590518426554;9120.589573469077;9121.315901307296;10920.126389888632;10962.890371096188;10971.451427428756;11023.95272449148;11094.00153986145;9424.811503770023;9427.104425079606;9439.716808495756;9471.526423678652;9492.857607136037;9092.636294548409;9243.445393276425;9282.582283797381;9318.161365477139;9488.193503552275;8103.959480202561;8153.429259951747;8205.95897020511;8251.793705157419;8273.669053238043;6029.939700602938;6032.8793424132145;6174.629522228681;6178.9382106180165;6254.530910181664;5903.616264942765;5961.463468287875;6233.096201050732;6264;6400.583962042452 16:54:47 INFO - PID 2754 | |4;prototype.html;332.390064232134;401.9135885784556;403.8768175706408;412.3731927470245;416.860351782153;6155.169052177955;6175.351588083243;6281.214848143982;6300.842478938029;6321.83046136464;6006.939930600693;6119.9388006119925;6150.031989763277;6160.168377269068;6176.9691151544175;3690.6309369063147;4039.515258169024;4067.5932406759384;4541.795619197144;4545.931811022837;3529.8941031769086;4490.8877278067985;4520.344550040242;4527.728336299816;4551.249043907751;4220.198162349144;4501.5273396293405;4510.887227819298;4512.5261847506035;4514.390557274775;3938.3895496198134;4422.601965823059;4463.727837566311;4508.346289787995;4509.503954564995;3541.2386336937684;3710.740248182601;3832.6933845292588;3843.2889915365745;3847.1151635123797;5950.137230101611;5972.761089556412;5981.730822113015;5988;6004.04868417766;5105.846824595268;5340.465953404668;5370.234329320403;5416.241726158341;5427.728613569306;5289.566799272339;6570.737170513174;6592.208934927839;6596.505262105314;6638.336166383371;3675.246574452231;4141.91716165678;4372.803223854902;4390.1219756048595;4565.338025986247;5716.399778023331;6625.73497060117;6878.8624227515165;6914.619695916773;6943.79168624942;3669.321175582526;3685.37843348931;4406.647468202536;4430.091831174602;4430.113977204572;4004.6796256299426;4458.665600080007;4466.263066593976;4466.466536465426;4466.665000125004;4760.404949381327;4783.851875549843;5082.593392528589;5278.683279003272;5299.973500132474;5611;5657.632253903483;6388.01971388871;6547.770828020996;6585.473162147016;1334.5329134802935;1346.4210389532595;1353.7698591239514;1465.1648560320516;1465.6262653023412;906.9365144439957;958.6722389490467;1002.1081237698454;1011.7925825205815;1014.0974532665933;6264.812055638338;6268.373162683786;6270.059491076375;6270.278917924497;6317.842053948688;5755.971220143872;6753.189617245962;6867.828304292333;6868.896966545506;6869.965650171717;5017.774200161001;5941.524678025748;6211.720472578745;6261.592996455216;6269.028300613412;2881.481333359973;3546.8935931922083;3584.6773790358993;3594.6944509716445;3595.388783906742;6621.71550814968;7004.124484439445;7067.363937245673;7101.964490177516;7112.759480763646;6454.515911306671;6751.468199425216;6970.756023539152;7130.901405495096;7139.857202855914;6522.34776522344;6974.721011159497;7120.287971202942;7127.07789806177;7130.893036604559;5675.608172236474;5740.512056475246;5844.6785426801935;6433.131527243762;6885.277045910308;5965.642061476326;6121.969390153021;6545.919923212617;6663.966680166762;6830.7609233676585;6027.517798576191;6133.60131591463;6539.509536784855;7099.467539934422;7103.259701373197;3873.663586062804;3983;4071.3508983707616;4436.755978421218;4470.888227794331;6229.314775374614;6832.180138383227;6880.827979300558;6892.241853396223;6971.233164352018;5212.661177023481;6654.268030516542;6666.899996500153;6672.799816005528;6675.499337549801;5422.6475279106735;6466.676666166767;6561.114249576246;6640.369164929324;6644.435223005905;4861.538153875376;5821.748324110336;6138.14066030765;6489;6502.219733631994;5319.388270348804;6448.709808058742;6559.278479367361;6597.736090556324;6629.038789375417;4948.406191257072;5349.786008559614;5652.915206271991;5668.8095499945475;6481.2778632975205 16:54:47 INFO - PID 2754 | |5;yui.html;804.2841870735047;821.3839620284787;823.7199352220246;838.8783626374176;860.5998210831962;16534.42129525467;17021.914890425545;17075.316987320508;18393.172307246172;19069.427917162473;17867.73198402025;18257.726134107997;18326.175322110503;18410.907945460287;18674.346397876077;984.8375018122001;1004.3070281505774;1005.5173516711983;1006.9295149339549;1007.5919252702668;1004.9397036177816;1008.9545970431349;1012.8936461671528;1015.0864222200042;1018.9708394521549;1054.072416273678;1062.2245760594772;1069.887661795512;1070.464767616192;1077.6605369308643;1039.4127318065343;1060.6499855047816;1062.0123285344625;1063.7978784031047;1070.2401295080501;749.6439191384048;750.8610906982226;755.8261599832069;763.8510490454364;765.2959277464746;13735.862641373658;17152.970821750736;17223.22495487706;17294.56763580908;17368.47894563165;16811.495655130366;16887;17146.914265428717;17236;17270.96374217551;13760.724785504333;13840.515581954583;14029.859701402956;14076;14076.507322243775;3443.810590417526;3500.702440292578;3516.8241587920757;3520.559930008749;3528.4530897710897;97535;112764.99999999919;125572.74427255701;126555.36722316421;126811;11476;13422.865771342356;13586.93206533961;13697.794533082011;13767.793483097761;24816.875915620305;25344.746552534238;25420.74579254221;25709.485810283688;25798.09706660258;8933.731988040368;9034.909650903406;9093.181613654802;9097.090290970982;9116.270698344117;4788.808447662124;4795.736234507066;4815.15734746418;4842.031593681278;4903.141950158708;4281.165172791276;4343.087951530151;4387.420528609698;4388.648908087345;4391.3193455014525;943.254828685331;960.8174446855076;969.5733877094198;975.8877729061107;992.8907820139779;498.75560975122653;649.4317472211815;667.1027468055457;686;688.2669956496316;10888;11147.442627868573;11179.944100279608;11753.353565553976;12073.396330183456;633.297040285283;657.1489920552896;659.2122413715564;679.3987321220756;687.5015613680042;4665.813367465331;5486.835394938158;5580.609357344947;5633.295838020247;5725.541956643458;90717.54641226752;94670.52664736632;97932;99858;100286.4985675067;82263;87917.12082879218;87988;88373.55813221021;89337.55331223302;59434;59721;76561.99999999888;76914.61542692363;77418.6129069351;8914.73255802327;10146.289759716748;10454.947725261325;10638.787224255626;10845.728856778644;38197.47210111564;39239.41140882946;39293.60706393014;39833.80083099566;40054.59945400509;31658.525122122726;51279.48720512747;51782.48217517927;51846.74076629593;51903.48096518986;11308.604198853001;14300.928495357457;14377.856221437652;14444.988850780344;14586.562403127924;12823.61529154127;15359.540843619838;15574.221288935734;15673.526915766404;15926.124063176174;12429.875701242872;14517;14725.410983560538;14803.629909252355;14814.037087589271;14295.71408571802;14572.927135364256;14581.927090364481;14607.050541714754;14720;14268.57194284173;14592;15610.765838512658;16266.426357433615;16466;10206.59173633076;10428.739281518023;11789.469473873525;12031.217970831867;12344.259344439002;11920.70198245016;12985.220886746825;13774.65563360924;13776.724465510833;13791.655208619864 16:54:47 INFO - PID 2754 | __end_tp_report 16:54:47 INFO - PID 2754 | __start_cc_report 16:54:47 INFO - PID 2754 | _x_x_mozilla_cycle_collect,430 16:54:47 INFO - PID 2754 | __end_cc_report 16:54:47 INFO - PID 2754 | __startTimestamp1492041287181__endTimestamp 16:54:47 INFO - PID 2754 | 16:54:47 INFO - PID 2754 | ------- Summary: start ------- 16:54:47 INFO - PID 2754 | Number of tests: 6 16:54:47 INFO - PID 2754 | 16:54:47 INFO - PID 2754 | [#0] dojo.html Cycles:165 Average:6119.05 Median:6361.59 stddev:752.74 (11.8%) stddev-sans-first:726.48 16:54:47 INFO - PID 2754 | Values: 3500.9 3511.3 3525.2 3566.2 3569.3 4986.8 6057.6 6128.0 6194.5 6231.3 6141.1 6156.0 6171.8 6190.4 6211.5 5450.3 5460.0 5467.1 5474.6 5493.1 5461.5 5475.9 5529.7 5550.9 5585.8 5365.4 5426.5 5485.4 5497.6 5542.8 5395.9 5419.2 5423.9 5486.5 5497.7 5381.1 5436.5 5436.6 5442.1 5466.8 5148.3 5218.5 6214.3 6217.5 6238.7 6414.3 6529.6 6584.8 6700.9 6737.1 6754.6 6784.7 6792.7 6807.1 6842.6 6243.5 6251.9 6336.3 6349.9 6352.1 5950.4 6656.8 6781.6 6957.5 6961.8 6355.2 6459.7 6486.3 6578.9 6668.7 6709.4 6728.6 6734.4 6743.1 6809.5 6573.6 6689.9 6715.1 6727.0 6734.1 6468.7 6562.8 6610.8 6708.0 6731.2 5119.9 5123.7 5126.2 5143.5 5174.1 4731.4 4736.8 4738.7 4752.2 4774.3 5451.5 6198.8 6253.5 6373.0 6398.4 6589.1 6705.8 6755.8 6796.1 6797.8 5331.3 6312.2 6322.2 6351.2 6361.6 5753.6 5833.0 5978.0 5988.4 5990.3 6724.7 6856.8 6925.9 6952.7 6967.0 6804.3 6815.5 6837.0 6841.5 6842.2 5393.5 6882.8 6897.6 6914.0 6940.8 6389.8 6519.9 6595.0 6596.5 6708.7 5077.7 6756.0 6781.5 6806.6 6827.6 6347.5 6430.7 6471.7 6523.8 6574.5 6490.5 6571.6 6597.2 6604.7 6634.7 6258.9 6291.7 6436.3 6438.2 6443.8 6362.0 6593.5 6595.3 6595.5 6603.3 5348.6 6270.0 6334.3 6382.7 6407.5 16:54:47 INFO - PID 2754 | 16:54:47 INFO - PID 2754 | [#1] ext.html Cycles:180 Average:45410.77 Median:34212.66 stddev:81470.33 (238.1%) stddev-sans-first:81629.48 16:54:47 INFO - PID 2754 | Values: 625.5 676.8 826.0 1034.4 1292.5 36843.4 37915.8 37925.9 37996.8 38176.2 38105.0 38381.0 38387.0 38396.0 38517.6 7260.6 8027.7 8196.2 8587.8 8757.4 8625.1 8663.2 8663.4 8730.7 8731.0 8680.0 8736.8 8756.4 8757.0 8770.9 8641.4 8690.9 8718.9 8719.1 8728.9 8520.2 8548.3 8573.0 8580.6 8583.8 37239.6 37385.6 37630.6 37684.1 37699.1 3652.8 3655.2 3694.6 3695.6 3706.8 30620.4 30696.0 30703.5 30712.0 30716.4 3118.1 3147.4 3155.3 3168.3 3173.5 244567.8 245037.8 277822.0 278368.0 280171.0 47479.5 48034.0 48037.5 48107.0 48139.8 47986.0 48205.0 48314.5 48316.3 48383.3 11330.5 14185.6 14231.0 14245.6 14279.5 11731.7 11760.8 11761.0 11774.9 11777.4 13043.5 13114.7 13127.1 13213.9 13230.3 4956.7 5922.5 5940.1 5959.6 6001.3 1009.9 1034.2 1035.8 1060.6 1065.4 21891.3 23234.5 24318.0 24573.5 24826.8 1125.9 1140.3 1141.4 1167.8 1180.7 9980.8 12471.3 12580.9 12606.9 12620.7 353049.0 353462.2 356336.0 356489.0 357233.0 54373.0 54623.7 54724.7 54747.5 54865.9 1244.5 1460.1 1542.8 1547.1 1550.2 20013.9 24171.0 24247.6 24340.5 24350.0 25744.7 26359.6 36656.7 37729.6 37975.6 297286.0 300560.0 303293.0 304409.0 304517.0 33537.3 34090.6 34105.8 34319.5 34414.5 38349.8 43485.6 45250.5 45407.0 45642.8 25103.1 27380.0 28737.6 30286.4 31223.8 25620.5 30008.0 30012.7 30717.0 31097.4 36967.3 36995.3 37103.0 37156.0 37243.6 29288.3 30370.5 30689.4 31328.4 31595.2 18829.9 22149.7 24214.3 24504.9 24581.3 16:54:47 INFO - PID 2754 | 16:54:47 INFO - PID 2754 | [#2] jquery.html Cycles:180 Average:40325.96 Median:12819.68 stddev:115508.01 (901.0%) stddev-sans-first:115793.14 16:54:47 INFO - PID 2754 | Values: 399.2 423.4 445.5 446.6 465.8 19553.0 20913.5 21707.6 21806.7 21820.0 22901.7 22920.3 23615.2 23701.9 23941.4 5939.2 6095.1 6243.3 6295.7 6300.5 6046.7 6153.4 6173.2 6178.1 6214.2 5970.5 6077.4 6162.1 6166.3 6179.3 6141.2 6142.8 6156.7 6216.6 6217.3 5465.9 5495.9 5533.3 5545.4 5552.9 27476.3 27549.2 27635.0 27769.2 28003.0 2250.8 2310.2 2313.2 2313.4 2319.4 17695.1 17712.3 17973.2 18104.8 18248.5 2177.2 2205.5 2250.2 2251.2 2252.0 123322.4 137250.0 147423.0 154646.0 155693.0 12709.6 13044.0 13229.5 13232.6 13302.9 16503.3 16583.3 16697.6 16714.6 16724.5 9040.5 9398.5 9418.5 9507.2 9522.0 9091.5 9097.6 9099.7 9308.5 9322.8 6591.7 7986.9 8023.2 8054.4 8117.6 2214.6 2224.9 2231.9 2240.2 2410.2 895.9 960.8 979.4 1002.6 1020.3 18080.0 18641.7 21355.7 21630.9 21854.6 931.6 990.3 995.7 1026.7 1030.0 9163.6 10845.2 11347.6 11358.7 11362.3 674738.0 679457.0 693476.0 698006.0 710718.0 122563.0 134680.3 138246.3 155451.0 156195.0 978.3 1028.9 1169.3 1194.8 1205.4 21956.8 22915.2 23111.3 23320.0 23417.3 50180.0 64752.4 64937.7 64964.7 65430.7 85681.6 86495.6 88498.0 95172.0 99337.0 8890.9 9148.5 9192.4 9257.3 9293.3 14092.8 14608.3 14630.6 14904.4 14986.2 12145.8 12182.5 12277.5 12297.9 12336.3 11420.0 11580.4 11782.4 11824.5 11933.2 12314.5 12449.8 12471.0 12517.8 12541.2 8911.8 9127.2 9133.9 9236.2 9303.5 11928.9 11947.5 12169.0 12205.7 12365.3 16:54:47 INFO - PID 2754 | 16:54:47 INFO - PID 2754 | [#3] mootools.html Cycles:180 Average:7262.40 Median:2576.00 stddev:4650.29 (180.5%) stddev-sans-first:4641.12 16:54:47 INFO - PID 2754 | Values: 1213.5 1406.9 1414.6 1425.0 1442.2 11159.8 11188.9 11610.5 12152.5 12176.5 11938.5 11953.2 12177.7 12187.2 12197.3 2555.5 2571.3 2577.8 2592.5 2594.9 2484.4 2541.9 2552.0 2553.9 2556.4 2462.9 2514.9 2526.2 2550.8 2551.7 2390.7 2502.5 2510.3 2544.4 2545.6 1852.6 2354.5 2570.9 2571.1 2574.2 12167.2 12239.0 12247.8 12256.6 12264.5 6574.9 6773.2 6833.4 6833.9 6853.0 3973.3 4091.2 4177.8 4297.4 4366.6 1228.8 1241.1 1269.9 1281.5 1292.7 12497.8 16763.3 16949.5 16961.1 16980.8 5651.6 5695.4 5841.5 5896.7 5954.3 11750.8 12505.5 12529.2 12535.2 12544.3 6679.2 6679.3 6684.1 6696.6 6702.6 5953.7 6181.8 6234.3 6252.2 6258.1 4359.4 4544.6 4567.2 4598.9 4606.2 1129.3 1273.0 1328.6 1341.7 1366.3 680.3 771.8 779.1 789.0 801.6 6043.6 6082.8 6176.4 6274.3 6392.2 593.0 600.0 602.5 607.3 609.8 3314.9 4101.8 4238.0 4333.8 4334.3 14999.0 15517.5 15666.2 15701.9 15714.4 11118.8 15240.3 15332.0 15678.5 15694.0 8453.4 10983.9 11151.1 11201.2 11240.9 6466.2 6559.9 6590.9 6630.4 6633.6 13760.9 13899.5 13978.5 14055.2 14102.0 13997.4 14132.8 14355.3 14412.2 14505.7 8881.4 9027.5 9099.6 9120.6 9121.3 10920.1 10962.9 10971.5 11024.0 11094.0 9424.8 9427.1 9439.7 9471.5 9492.9 9092.6 9243.4 9282.6 9318.2 9488.2 8104.0 8153.4 8206.0 8251.8 8273.7 6029.9 6032.9 6174.6 6178.9 6254.5 5903.6 5961.5 6233.1 6264.0 6400.6 16:54:47 INFO - PID 2754 | 16:54:47 INFO - PID 2754 | [#4] prototype.html Cycles:180 Average:5227.71 Median:5893.10 stddev:1693.74 (28.7%) stddev-sans-first:1658.15 16:54:47 INFO - PID 2754 | Values: 332.4 401.9 403.9 412.4 416.9 6155.2 6175.4 6281.2 6300.8 6321.8 6006.9 6119.9 6150.0 6160.2 6177.0 3690.6 4039.5 4067.6 4541.8 4545.9 3529.9 4490.9 4520.3 4527.7 4551.2 4220.2 4501.5 4510.9 4512.5 4514.4 3938.4 4422.6 4463.7 4508.3 4509.5 3541.2 3710.7 3832.7 3843.3 3847.1 5950.1 5972.8 5981.7 5988.0 6004.0 5105.8 5340.5 5370.2 5416.2 5427.7 5289.6 6570.7 6592.2 6596.5 6638.3 3675.2 4141.9 4372.8 4390.1 4565.3 5716.4 6625.7 6878.9 6914.6 6943.8 3669.3 3685.4 4406.6 4430.1 4430.1 4004.7 4458.7 4466.3 4466.5 4466.7 4760.4 4783.9 5082.6 5278.7 5300.0 5611.0 5657.6 6388.0 6547.8 6585.5 1334.5 1346.4 1353.8 1465.2 1465.6 906.9 958.7 1002.1 1011.8 1014.1 6264.8 6268.4 6270.1 6270.3 6317.8 5756.0 6753.2 6867.8 6868.9 6870.0 5017.8 5941.5 6211.7 6261.6 6269.0 2881.5 3546.9 3584.7 3594.7 3595.4 6621.7 7004.1 7067.4 7102.0 7112.8 6454.5 6751.5 6970.8 7130.9 7139.9 6522.3 6974.7 7120.3 7127.1 7130.9 5675.6 5740.5 5844.7 6433.1 6885.3 5965.6 6122.0 6545.9 6664.0 6830.8 6027.5 6133.6 6539.5 7099.5 7103.3 3873.7 3983.0 4071.4 4436.8 4470.9 6229.3 6832.2 6880.8 6892.2 6971.2 5212.7 6654.3 6666.9 6672.8 6675.5 5422.6 6466.7 6561.1 6640.4 6644.4 4861.5 5821.7 6138.1 6489.0 6502.2 5319.4 6448.7 6559.3 6597.7 6629.0 4948.4 5349.8 5652.9 5668.8 6481.3 16:54:47 INFO - PID 2754 | 16:54:47 INFO - PID 2754 | [#5] yui.html Cycles:180 Average:20426.91 Median:17618.11 stddev:28385.60 (161.1%) stddev-sans-first:28426.99 16:54:47 INFO - PID 2754 | Values: 804.3 821.4 823.7 838.9 860.6 16534.4 17021.9 17075.3 18393.2 19069.4 17867.7 18257.7 18326.2 18410.9 18674.3 984.8 1004.3 1005.5 1006.9 1007.6 1004.9 1009.0 1012.9 1015.1 1019.0 1054.1 1062.2 1069.9 1070.5 1077.7 1039.4 1060.6 1062.0 1063.8 1070.2 749.6 750.9 755.8 763.9 765.3 13735.9 17153.0 17223.2 17294.6 17368.5 16811.5 16887.0 17146.9 17236.0 17271.0 13760.7 13840.5 14029.9 14076.0 14076.5 3443.8 3500.7 3516.8 3520.6 3528.5 97535.0 112765.0 125572.7 126555.4 126811.0 11476.0 13422.9 13586.9 13697.8 13767.8 24816.9 25344.7 25420.7 25709.5 25798.1 8933.7 9034.9 9093.2 9097.1 9116.3 4788.8 4795.7 4815.2 4842.0 4903.1 4281.2 4343.1 4387.4 4388.6 4391.3 943.3 960.8 969.6 975.9 992.9 498.8 649.4 667.1 686.0 688.3 10888.0 11147.4 11179.9 11753.4 12073.4 633.3 657.1 659.2 679.4 687.5 4665.8 5486.8 5580.6 5633.3 5725.5 90717.5 94670.5 97932.0 99858.0 100286.5 82263.0 87917.1 87988.0 88373.6 89337.6 59434.0 59721.0 76562.0 76914.6 77418.6 8914.7 10146.3 10454.9 10638.8 10845.7 38197.5 39239.4 39293.6 39833.8 40054.6 31658.5 51279.5 51782.5 51846.7 51903.5 11308.6 14300.9 14377.9 14445.0 14586.6 12823.6 15359.5 15574.2 15673.5 15926.1 12429.9 14517.0 14725.4 14803.6 14814.0 14295.7 14572.9 14581.9 14607.1 14720.0 14268.6 14592.0 15610.8 16266.4 16466.0 10206.6 10428.7 11789.5 12031.2 12344.3 11920.7 12985.2 13774.7 13776.7 13791.7 16:54:47 INFO - PID 2754 | -------- Summary: end -------- 16:54:47 INFO - PID 2754 | 16:54:47 INFO - TEST-INFO | 2754: exit 0 16:54:47 INFO - TEST-OK | dromaeo_css | took 1115664ms 16:54:47 INFO - TEST-START | kraken 16:54:47 INFO - operating with platform_type : mac_ 16:54:47 INFO - Initialising browser for kraken test... 16:54:47 INFO - TEST-INFO | started process 2759 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/tmpAz4Kuv/profile http://localhost:61105/getInfo.html) 16:54:50 INFO - PID 2759 | 2017-04-12 16:54:50.690 plugin-container[2760:43897] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 16:54:50 INFO - PID 2759 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:54:50 INFO - PID 2759 | 2017-04-12 16:54:50.693 plugin-container[2760:43897] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 16:54:50 INFO - PID 2759 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:54:50 INFO - PID 2759 | 2017-04-12 16:54:50.693 plugin-container[2760:43897] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:54:50 INFO - PID 2759 | __metrics Screen width/height:1600/1200 16:54:50 INFO - PID 2759 | colorDepth:24 16:54:50 INFO - PID 2759 | Browser inner width/height: 1024/689 16:54:50 INFO - PID 2759 | __metrics 16:54:50 ERROR - PID 2759 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 16:54:51 INFO - TEST-INFO | 2759: exit 0 16:54:51 INFO - Browser initialized. 16:54:51 INFO - Running cycle 1/1 for kraken test... 16:54:51 INFO - TEST-INFO | started process 2761 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/T/tmpAz4Kuv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:54:54 INFO - PID 2761 | 2017-04-12 16:54:54.401 plugin-container[2762:44018] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 16:54:54 INFO - PID 2761 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:54:54 INFO - PID 2761 | 2017-04-12 16:54:54.401 plugin-container[2762:44018] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 16:54:54 INFO - PID 2761 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:54:54 INFO - PID 2761 | 2017-04-12 16:54:54.401 plugin-container[2762:44018] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:55:04 INFO - PID 2761 | RSS: Main: 235479040 16:55:04 INFO - PID 2761 | 16:55:07 INFO - PID 2761 | Cycle 1(1): loaded http://localhost:61105/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61105/tests/kraken/driver.html?testName=audio-beat-detection) 16:55:08 INFO - PID 2761 | RSS: Main: 250327040 16:55:08 INFO - PID 2761 | 16:55:10 INFO - PID 2761 | Cycle 1(1): loaded http://localhost:61105/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61105/tests/kraken/driver.html?testName=audio-dft) 16:55:11 INFO - PID 2761 | RSS: Main: 258793472 16:55:11 INFO - PID 2761 | 16:55:15 INFO - PID 2761 | Cycle 1(1): loaded http://localhost:61105/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61105/tests/kraken/driver.html?testName=audio-fft) 16:55:15 INFO - PID 2761 | RSS: Main: 261128192 16:55:15 INFO - PID 2761 | 16:55:17 INFO - PID 2761 | Cycle 1(1): loaded http://localhost:61105/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61105/tests/kraken/driver.html?testName=audio-oscillator) 16:55:18 INFO - PID 2761 | RSS: Main: 263110656 16:55:18 INFO - PID 2761 | 16:55:20 INFO - PID 2761 | Cycle 1(1): loaded http://localhost:61105/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61105/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:55:21 INFO - PID 2761 | RSS: Main: 263372800 16:55:21 INFO - PID 2761 | 16:55:24 INFO - PID 2761 | Cycle 1(1): loaded http://localhost:61105/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61105/tests/kraken/driver.html?testName=imaging-darkroom) 16:55:24 INFO - PID 2761 | RSS: Main: 269361152 16:55:24 INFO - PID 2761 | 16:55:28 INFO - PID 2761 | Cycle 1(1): loaded http://localhost:61105/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61105/tests/kraken/driver.html?testName=imaging-desaturate) 16:55:29 INFO - PID 2761 | RSS: Main: 267223040 16:55:29 INFO - PID 2761 | 16:55:32 INFO - PID 2761 | Cycle 1(1): loaded http://localhost:61105/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61105/tests/kraken/driver.html?testName=json-parse-financial) 16:55:33 INFO - PID 2761 | RSS: Main: 268386304 16:55:33 INFO - PID 2761 | 16:55:34 INFO - PID 2761 | Cycle 1(1): loaded http://localhost:61105/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61105/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:55:35 INFO - PID 2761 | RSS: Main: 268931072 16:55:35 INFO - PID 2761 | 16:55:37 INFO - PID 2761 | Cycle 1(1): loaded http://localhost:61105/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61105/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:55:37 INFO - PID 2761 | RSS: Main: 268505088 16:55:37 INFO - PID 2761 | 16:55:40 INFO - PID 2761 | Cycle 1(1): loaded http://localhost:61105/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61105/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:55:40 INFO - PID 2761 | RSS: Main: 268533760 16:55:40 INFO - PID 2761 | 16:55:43 INFO - PID 2761 | Cycle 1(1): loaded http://localhost:61105/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61105/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:55:44 INFO - PID 2761 | RSS: Main: 269058048 16:55:44 INFO - PID 2761 | 16:55:48 INFO - PID 2761 | Cycle 1(1): loaded http://localhost:61105/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61105/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:55:48 INFO - PID 2761 | RSS: Main: 266838016 16:55:48 INFO - PID 2761 | 16:55:50 INFO - PID 2761 | Cycle 1(1): loaded http://localhost:61105/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61105/tests/kraken/driver.html?testName=ai-astar) 16:55:51 INFO - PID 2761 | RSS: Main: 270221312 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | __start_tp_report 16:55:51 INFO - PID 2761 | _x_x_mozilla_page_load 16:55:51 INFO - PID 2761 | _x_x_mozilla_page_load_details 16:55:51 INFO - PID 2761 | |i|pagename|runs| 16:55:51 INFO - PID 2761 | |0;ai-astar;108;122;122;166;184;130;124;121;119;167 16:55:51 INFO - PID 2761 | |1;audio-beat-detection;154;114;98;200;159;103;113;152;180;109 16:55:51 INFO - PID 2761 | |2;audio-dft;223;227;216;232;214;212;212;225;301;211 16:55:51 INFO - PID 2761 | |3;audio-fft;81;57;59;93;78;80;60;61;79;48 16:55:51 INFO - PID 2761 | |4;audio-oscillator;93;69;87;81;87;59;113;108;109;125 16:55:51 INFO - PID 2761 | |5;imaging-gaussian-blur;96;138;90;93;94;90;92;91;92;90 16:55:51 INFO - PID 2761 | |6;imaging-darkroom;99;99;109;162;148;100;142;163;121;160 16:55:51 INFO - PID 2761 | |7;imaging-desaturate;103;101;102;130;108;159;161;103;103;138 16:55:51 INFO - PID 2761 | |8;json-parse-financial;52;53;51;53;52;53;53;52;59;69 16:55:51 INFO - PID 2761 | |9;json-stringify-tinderbox;62;76;72;69;64;61;67;94;91;81 16:55:51 INFO - PID 2761 | |10;stanford-crypto-aes;89;90;88;88;87;90;91;88;101;115 16:55:51 INFO - PID 2761 | |11;stanford-crypto-ccm;173;183;165;167;152;159;171;167;191;139 16:55:51 INFO - PID 2761 | |12;stanford-crypto-pbkdf2;192;280;236;223;268;204;214;228;204;203 16:55:51 INFO - PID 2761 | |13;stanford-crypto-sha256-iterative;78;98;111;109;73;71;71;72;72;75 16:55:51 INFO - PID 2761 | __end_tp_report 16:55:51 INFO - PID 2761 | __start_cc_report 16:55:51 INFO - PID 2761 | _x_x_mozilla_cycle_collect,1027 16:55:51 INFO - PID 2761 | __end_cc_report 16:55:51 INFO - PID 2761 | __startTimestamp1492041351025__endTimestamp 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | ------- Summary: start ------- 16:55:51 INFO - PID 2761 | Number of tests: 14 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | [#0] ai-astar Cycles:10 Average:136.30 Median:127.00 stddev:25.89 (20.4%) stddev-sans-first:25.36 16:55:51 INFO - PID 2761 | Values: 108.0 122.0 122.0 166.0 184.0 130.0 124.0 121.0 119.0 167.0 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:156.50 stddev:35.54 (22.7%) stddev-sans-first:37.23 16:55:51 INFO - PID 2761 | Values: 154.0 114.0 98.0 200.0 159.0 103.0 113.0 152.0 180.0 109.0 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | [#2] audio-dft Cycles:10 Average:227.30 Median:224.00 stddev:26.91 (12.0%) stddev-sans-first:28.49 16:55:51 INFO - PID 2761 | Values: 223.0 227.0 216.0 232.0 214.0 212.0 212.0 225.0 301.0 211.0 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | [#3] audio-fft Cycles:10 Average:69.60 Median:78.50 stddev:14.33 (18.3%) stddev-sans-first:14.59 16:55:51 INFO - PID 2761 | Values: 81.0 57.0 59.0 93.0 78.0 80.0 60.0 61.0 79.0 48.0 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:75.00 stddev:20.69 (27.6%) stddev-sans-first:21.95 16:55:51 INFO - PID 2761 | Values: 93.0 69.0 87.0 81.0 87.0 59.0 113.0 108.0 109.0 125.0 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | [#5] imaging-gaussian-blur Cycles:10 Average:96.60 Median:92.00 stddev:14.68 (16.0%) stddev-sans-first:15.56 16:55:51 INFO - PID 2761 | Values: 96.0 138.0 90.0 93.0 94.0 90.0 92.0 91.0 92.0 90.0 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | [#6] imaging-darkroom Cycles:10 Average:130.30 Median:161.00 stddev:27.54 (17.1%) stddev-sans-first:26.78 16:55:51 INFO - PID 2761 | Values: 99.0 99.0 109.0 162.0 148.0 100.0 142.0 163.0 121.0 160.0 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | [#7] imaging-desaturate Cycles:10 Average:120.80 Median:119.00 stddev:24.30 (20.4%) stddev-sans-first:24.91 16:55:51 INFO - PID 2761 | Values: 103.0 101.0 102.0 130.0 108.0 159.0 161.0 103.0 103.0 138.0 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:53.00 stddev:5.48 (10.3%) stddev-sans-first:5.72 16:55:51 INFO - PID 2761 | Values: 52.0 53.0 51.0 53.0 52.0 53.0 53.0 52.0 59.0 69.0 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | [#9] json-stringify-tinderbox Cycles:10 Average:73.70 Median:74.00 stddev:11.70 (15.8%) stddev-sans-first:11.62 16:55:51 INFO - PID 2761 | Values: 62.0 76.0 72.0 69.0 64.0 61.0 67.0 94.0 91.0 81.0 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | [#10] stanford-crypto-aes Cycles:10 Average:92.70 Median:88.50 stddev:8.79 (9.9%) stddev-sans-first:9.23 16:55:51 INFO - PID 2761 | Values: 89.0 90.0 88.0 88.0 87.0 90.0 91.0 88.0 101.0 115.0 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | [#11] stanford-crypto-ccm Cycles:10 Average:166.70 Median:169.00 stddev:14.76 (8.7%) stddev-sans-first:15.48 16:55:51 INFO - PID 2761 | Values: 173.0 183.0 165.0 167.0 152.0 159.0 171.0 167.0 191.0 139.0 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:225.20 Median:225.50 stddev:29.03 (12.9%) stddev-sans-first:28.19 16:55:51 INFO - PID 2761 | Values: 192.0 280.0 236.0 223.0 268.0 204.0 214.0 228.0 204.0 203.0 16:55:51 INFO - PID 2761 | 16:55:51 INFO - PID 2761 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.00 Median:72.50 stddev:16.34 (22.5%) stddev-sans-first:17.23 16:55:51 INFO - PID 2761 | Values: 78.0 98.0 111.0 109.0 73.0 71.0 71.0 72.0 72.0 75.0 16:55:51 INFO - PID 2761 | -------- Summary: end -------- 16:55:51 INFO - PID 2761 | 16:55:52 INFO - TEST-INFO | 2761: exit 0 16:55:52 INFO - TEST-OK | kraken | took 64242ms 16:55:52 INFO - SUITE-END | took 1179s 16:55:52 INFO - Completed test suite (00:19:40) 16:55:52 INFO - javascript benchmark 16:55:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7863.445951376058, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3500.859965601376, 3511.332846759114, 3525.1715846776005, 3566.215432604826, 3569.2682999985004, 4986.800527978881, 6057.575969682124, 6127.953177891112, 6194.504439644829, 6231.345708700578, 6141.117811339985, 6155.953487907055, 6171.814845554641, 6190.3809619038075, 6211.472024877879, 5450.345958484987, 5459.972700136494, 5467.097928841747, 5474.562035037187, 5493.093639549469, 5461.481159289861, 5475.917861232065, 5529.723513824292, 5550.944490555104, 5585.832425027235, 5365.382980957201, 5426.457354264581, 5485.424030476783, 5497.5601951843755, 5542.75057622408, 5395.919061214085, 5419.160030195287, 5423.864403389907, 5486.451354864522, 5497.697626630534, 5381.058314794935, 5436.456354364572, 5436.565074794007, 5442.102053161224, 5466.781328746845, 5148.330717006766, 5218.504242097033, 6214.347493513161, 6217.471514921237, 6238.656873871935, 6414.294427612959, 6529.608223506605, 6584.769533066367, 6700.93299067008, 6737.124173857367, 6754.628495432749, 6784.660766961632, 6792.728290868359, 6807.0810440590385, 6842.55523390978, 6243.500519958392, 6251.906221406683, 6336.271328797247, 6349.936500634934, 6352.07894855248, 5950.4049595041015, 6656.800295991128, 6781.559198652072, 6957.478189135835, 6961.79114626562, 6355.237371515355, 6459.709313080923, 6486.318936511692, 6578.934210657928, 6668.6665666717445, 6709.36261055199, 6728.562643428161, 6734.393904548614, 6743.055972163901, 6809.523333366716, 6573.638449885302, 6689.933100669028, 6715.093462382516, 6727.0, 6734.090897728842, 6468.741250349934, 6562.8359291018105, 6610.8347291318105, 6708.0, 6731.158605174353, 5119.948800512022, 5123.692578445305, 5126.15418455958, 5143.537081662594, 5174.120399532088, 4731.384919960383, 4736.786844592001, 4738.715677059387, 4752.239641657387, 4774.259989701602, 5451.454854514517, 6198.783042593487, 6253.499720022478, 6373.044043393528, 6398.424141827165, 6589.110470086573, 6705.765298214539, 6755.763548275885, 6796.082528858639, 6797.830054248584, 5331.333583302087, 6312.179416675895, 6322.178116844781, 6351.237851457762, 6361.586496877688, 5753.597248192586, 5833.008388573952, 5977.970110149421, 5988.401159884064, 5990.251218597676, 6724.731010759515, 6856.760013399507, 6925.861482770216, 6952.721891124298, 6966.965165174344, 6804.319568043156, 6815.522913396015, 6837.0, 6841.521093523605, 6842.178938527409, 5393.541548968382, 6882.827929301808, 6897.586144831326, 6914.0, 6940.7917762467205, 6389.840253993501, 6519.902201466887, 6594.967025165035, 6596.5052621052655, 6708.6981085850275, 5077.720725360141, 6755.966220168868, 6781.491388146008, 6806.6256355900905, 6827.6244806536115, 6347.5239357047485, 6430.678466076771, 6471.741130354733, 6523.7716679915975, 6574.4740420765265, 6490.513211509061, 6571.605703657796, 6597.175353080865, 6604.702788374439, 6634.668266586555, 6258.873402787542, 6291.748330066746, 6436.324185960407, 6438.195225596801, 6443.7744678936015, 6362.013887847391, 6593.472522198309, 6595.340465953366, 6595.472362211108, 6603.306652801387, 5348.625596208228, 6269.96865015672, 6334.334894835976, 6382.744690212339, 6407.519436042222], "value": 6070.449996848384, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [625.543353352053, 676.8003438985502, 826.0376661189713, 1034.4414016431128, 1292.5153067599656, 36843.447348289796, 37915.81042094789, 37925.86222413325, 37996.81001594995, 38176.236475270474, 38105.0, 38381.0, 38387.0, 38396.04009899754, 38517.61482385182, 7260.636968151598, 8027.719029833957, 8196.221358970888, 8587.828243435128, 8757.386982911199, 8625.137486251357, 8663.220310172082, 8663.39356245063, 8730.738077857643, 8730.956345218265, 8680.045195028542, 8736.825263494726, 8756.387052906299, 8757.0, 8770.868436973418, 8641.351898607598, 8690.913090869106, 8718.912810871905, 8719.128087191293, 8728.869066964, 8520.19058189471, 8548.273396761282, 8573.014103378067, 8580.613872375758, 8583.82832343356, 37239.627603723886, 37385.62614373849, 37630.62369376299, 37684.057898552484, 37699.05752356185, 3652.8356223969718, 3655.1593133579427, 3694.6305369463107, 3695.57500887396, 3706.796126213057, 30620.387592248255, 30696.0, 30703.539446908315, 30712.0, 30716.38567228643, 3118.1425108095227, 3147.401993621205, 3155.3373791503814, 3168.3346497235607, 3173.4763763978917, 244567.77716111485, 245037.77481112658, 277822.0, 278368.0, 280171.0, 47479.5252047482, 48033.99575053118, 48037.519624804, 48107.0, 48139.75930120397, 47986.0, 48205.0, 48314.516854831, 48316.30892918731, 48383.274250886265, 11330.546778128783, 14185.645358866112, 14231.0, 14245.64385890361, 14279.500217492337, 11731.706707332385, 11760.823587646191, 11761.0, 11774.88225117755, 11777.411129443493, 13043.478260869459, 13114.737705245841, 13127.081104322797, 13213.867861321456, 13230.272335021666, 4956.702597844141, 5922.49658779, 5940.138679891478, 5959.553033522503, 6001.279846418458, 1009.8636684047707, 1034.1571619130384, 1035.803197392508, 1060.565168281001, 1065.3607835298915, 21891.343259702233, 23234.53530929372, 24318.027278908998, 24573.5085298293, 24826.751732482804, 1125.926814757038, 1140.2588317593631, 1141.42358109156, 1167.8131498960126, 1180.710725872167, 9980.80038399228, 12471.314077725812, 12580.937095314648, 12606.87393126057, 12620.747585048248, 353049.0, 353462.2326888401, 356336.0, 356489.0, 357233.0, 54373.0, 54623.726881365335, 54724.726376367864, 54747.45252547423, 54865.902681946936, 1244.4514901712305, 1460.123925644626, 1542.8380020097952, 1547.0562956596468, 1550.1629120275152, 20013.899930500254, 24171.0, 24247.636285456087, 24340.51318973646, 24350.0, 25744.71276436133, 26359.60460593131, 36656.71701490435, 37729.62270377261, 37975.620243798316, 297286.0, 300560.0, 303293.0, 304409.0, 304516.95483044884, 33537.329253415286, 34090.56822613045, 34105.829470852485, 34319.485207722406, 34414.48378274378, 38349.84950451491, 43485.56514434942, 45250.54749452463, 45407.0, 45642.77178614086, 25103.121390751236, 27380.0, 28737.56893646639, 30286.394272113994, 31223.843880781358, 25620.487590247718, 30008.0, 30012.699873001864, 30717.0, 31097.378052438373, 36967.26065478729, 36995.26009479849, 37103.0, 37156.0, 37243.6275637251, 29288.26779330534, 30370.544441833834, 30689.38621227518, 31328.373432530767, 31595.21011974719, 18829.90585047066, 22149.69712720629, 24214.273571792874, 24504.897279622815, 24581.262562123164], "value": 17384.21696848215, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.22351027251995, 423.37340735711166, 445.52859610379, 446.62260389970476, 465.79039432255496, 19552.99999999998, 20913.47716307091, 21707.565848683018, 21806.672899906513, 21820.0, 22901.656475152886, 22920.312390628307, 23615.17346892859, 23701.93341299641, 23941.401464963386, 5939.198208241875, 6095.055266433699, 6243.344448832875, 6295.748170073191, 6300.527460440462, 6046.667433291189, 6153.415425534589, 6173.228346456693, 6178.135061091451, 6214.223222097238, 5970.522358211354, 6077.361877002917, 6162.106494558287, 6166.321704612489, 6179.258488981306, 6141.201643786324, 6142.754289828424, 6156.692165391735, 6216.595921265126, 6217.347178546278, 5465.945340546623, 5495.890082198373, 5533.3083364579425, 5545.390007099216, 5552.86117847053, 27476.313092172655, 27549.17352479409, 27635.032773852818, 27769.16692499228, 28003.0, 2250.7749225077523, 2310.1683393978155, 2313.2481943368302, 2313.4447732544236, 2319.408550819547, 17695.115244237866, 17712.29150833965, 17973.191206395742, 18104.818951810445, 18248.45254642363, 2177.194438057913, 2205.470687035116, 2250.234920127148, 2251.2008237075765, 2251.997860951877, 123322.38338808248, 137250.0, 147423.0, 154646.0, 155693.0, 12709.618711438672, 13044.0, 13229.470821167239, 13232.603021909357, 13302.93348533251, 16503.257353418885, 16583.253753581117, 16697.582560435843, 16714.58213544671, 16724.49826505207, 9040.502772347583, 9398.483083430478, 9418.529073546295, 9507.191888689538, 9522.047795220422, 9091.454512729257, 9097.636094556276, 9099.681511147079, 9308.488033158106, 9322.813543729088, 6591.670416479157, 7986.880196797053, 8023.1575684553445, 8054.355651547862, 8117.553534555538, 2214.6235140026233, 2224.922127725522, 2231.8884055797143, 2240.24951641202, 2410.20463247128, 895.8835351404276, 960.7502049467049, 979.4221409368364, 1002.5538635307364, 1020.3010937507831, 18080.0, 18641.7203741944, 21355.67966480504, 21630.91845407755, 21854.562908742053, 931.5854444772011, 990.2573070197352, 995.66147509847, 1026.743314171457, 1029.994850025745, 9163.58763855628, 10845.186611004206, 11347.581552305961, 11358.659240222807, 11362.261453005529, 674738.0, 679457.0, 693476.0, 698006.0, 710718.0, 122563.0, 134680.32659836637, 138246.30876845552, 155451.0, 156195.0, 978.3151793744407, 1028.9350522209477, 1169.2925779903205, 1194.7610477904454, 1205.409349418796, 21956.78043219547, 22915.19796807104, 23111.324069637223, 23319.9506022226, 23417.297481075595, 50180.0, 64752.35247647652, 64937.67531162314, 64964.675176623816, 65430.67284663546, 85681.57159214164, 86495.56752216199, 88498.0, 95172.0, 99337.0, 8890.86663700058, 9148.542572871196, 9192.448453092837, 9257.25941924631, 9293.349465537347, 14092.78860817068, 14608.269586520419, 14630.634234144232, 14904.403823847359, 14986.175760333286, 12145.817812732992, 12182.451789669663, 12277.508899643914, 12297.877021230031, 12336.259824410565, 11420.029297509805, 11580.36308003068, 11782.351970641697, 11824.467898944396, 11933.224340417844, 12314.507419703112, 12449.813252801396, 12471.0, 12517.812232816697, 12541.184822986841, 8911.777205569653, 9127.178553930178, 9133.862992054992, 9236.168744813, 9303.534823258946, 11928.880711192776, 11947.522099116286, 12169.026477881926, 12205.69485762863, 12365.258084514959], "value": 10810.540333538409, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1213.5327898758978, 1406.8944829137818, 1414.57400558043, 1424.9643758906032, 1442.2284078018258, 11159.776804463905, 11188.888111118886, 11610.535578576857, 12152.453139608717, 12176.452059657315, 11938.462769175387, 11953.223040502362, 12177.695557611065, 12187.207831490947, 12197.26816391015, 2555.479489703628, 2571.331453822001, 2577.793776497885, 2592.520383729007, 2594.9221523354236, 2484.391324125592, 2541.9364515887064, 2552.0302285131716, 2553.859537725424, 2556.4120252341972, 2462.889169987346, 2514.9185850084455, 2526.178991827654, 2550.7576780205945, 2551.719310875802, 2390.7489713580085, 2502.5245203411387, 2510.3222130024883, 2544.4147845995526, 2545.567253566898, 1852.5831687870257, 2354.458474550846, 2570.8843102060264, 2571.1386685460393, 2574.214864466337, 12167.209131406518, 12239.0, 12247.755044899051, 12256.571020014257, 12264.509419623204, 6574.868502629968, 6773.221079575863, 6833.350831671033, 6833.863322733568, 6852.965735171292, 3973.3443981742976, 4091.2226676931286, 4177.791110444466, 4297.376880352363, 4366.563343665608, 1228.8341073954991, 1241.1498123785145, 1269.904757143218, 1281.5386460874079, 1292.650984234261, 12497.812532811924, 16763.329466821433, 16949.49151525456, 16961.067141307223, 16980.830191698173, 5651.604387692904, 5695.40198279183, 5841.503472204825, 5896.705164741746, 5954.255718035246, 11750.823737643943, 12505.499780008699, 12529.248245105322, 12535.185212961129, 12544.310062946626, 6679.231888332807, 6679.265280819106, 6684.131062961784, 6696.564723293068, 6702.564333318319, 5953.7023148843255, 6181.81454556364, 6234.251889773165, 6252.249730032425, 6258.061290806416, 4359.411479450234, 4544.613707834805, 4567.200739870509, 4598.908021839545, 4606.216943119611, 1129.2829053551054, 1273.0197747734194, 1328.581496828496, 1341.7383610196114, 1366.344154805679, 680.2992917295194, 771.8070482379405, 779.096248351909, 788.9802754931058, 801.587182600961, 6043.576949613484, 6082.756689732361, 6176.444120029129, 6274.278457977391, 6392.20097487814, 592.9644221346733, 599.9700014999232, 602.4939051197016, 607.2682417686677, 609.765240382456, 3314.850831712579, 4101.830978171207, 4238.046439551138, 4333.848315308949, 4334.306510958231, 14999.025063370846, 15517.456889009056, 15666.216689165496, 15701.900866939433, 15714.449994250375, 11118.833217501744, 15240.314185861664, 15332.003419777679, 15678.451254206047, 15693.979891307028, 8453.408261421642, 10983.890161098447, 11151.107911367071, 11201.21591488588, 11240.887591124148, 6466.224053113657, 6559.901601476077, 6590.901136483052, 6630.436412904957, 6633.6351500666, 13760.862391375958, 13899.513517026855, 13978.510752123628, 14055.156690598596, 14102.012859099765, 13997.44010239579, 14132.78800817968, 14355.282235888373, 14412.207328596609, 14505.709885802014, 8881.378303518692, 9027.548622568976, 9099.590518426554, 9120.589573469077, 9121.315901307296, 10920.126389888632, 10962.890371096188, 10971.451427428756, 11023.95272449148, 11094.00153986145, 9424.811503770023, 9427.104425079606, 9439.716808495756, 9471.526423678652, 9492.857607136037, 9092.636294548409, 9243.445393276425, 9282.582283797381, 9318.161365477139, 9488.193503552275, 8103.959480202561, 8153.429259951747, 8205.95897020511, 8251.793705157419, 8273.669053238043, 6029.939700602938, 6032.8793424132145, 6174.629522228681, 6178.9382106180165, 6254.530910181664, 5903.616264942765, 5961.463468287875, 6233.096201050732, 6264.0, 6400.583962042452], "value": 5398.868666784578, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.390064232134, 401.9135885784556, 403.8768175706408, 412.3731927470245, 416.860351782153, 6155.169052177955, 6175.351588083243, 6281.214848143982, 6300.842478938029, 6321.83046136464, 6006.939930600693, 6119.9388006119925, 6150.031989763277, 6160.168377269068, 6176.9691151544175, 3690.6309369063147, 4039.515258169024, 4067.5932406759384, 4541.795619197144, 4545.931811022837, 3529.8941031769086, 4490.8877278067985, 4520.344550040242, 4527.728336299816, 4551.249043907751, 4220.198162349144, 4501.5273396293405, 4510.887227819298, 4512.5261847506035, 4514.390557274775, 3938.3895496198134, 4422.601965823059, 4463.727837566311, 4508.346289787995, 4509.503954564995, 3541.2386336937684, 3710.740248182601, 3832.6933845292588, 3843.2889915365745, 3847.1151635123797, 5950.137230101611, 5972.761089556412, 5981.730822113015, 5988.0, 6004.04868417766, 5105.846824595268, 5340.465953404668, 5370.234329320403, 5416.241726158341, 5427.728613569306, 5289.566799272339, 6570.737170513174, 6592.208934927839, 6596.505262105314, 6638.336166383371, 3675.246574452231, 4141.91716165678, 4372.803223854902, 4390.1219756048595, 4565.338025986247, 5716.399778023331, 6625.73497060117, 6878.8624227515165, 6914.619695916773, 6943.79168624942, 3669.321175582526, 3685.37843348931, 4406.647468202536, 4430.091831174602, 4430.113977204572, 4004.6796256299426, 4458.665600080007, 4466.263066593976, 4466.466536465426, 4466.665000125004, 4760.404949381327, 4783.851875549843, 5082.593392528589, 5278.683279003272, 5299.973500132474, 5611.0, 5657.632253903483, 6388.01971388871, 6547.770828020996, 6585.473162147016, 1334.5329134802935, 1346.4210389532595, 1353.7698591239514, 1465.1648560320516, 1465.6262653023412, 906.9365144439957, 958.6722389490467, 1002.1081237698454, 1011.7925825205815, 1014.0974532665933, 6264.812055638338, 6268.373162683786, 6270.059491076375, 6270.278917924497, 6317.842053948688, 5755.971220143872, 6753.189617245962, 6867.828304292333, 6868.896966545506, 6869.965650171717, 5017.774200161001, 5941.524678025748, 6211.720472578745, 6261.592996455216, 6269.028300613412, 2881.481333359973, 3546.8935931922083, 3584.6773790358993, 3594.6944509716445, 3595.388783906742, 6621.71550814968, 7004.124484439445, 7067.363937245673, 7101.964490177516, 7112.759480763646, 6454.515911306671, 6751.468199425216, 6970.756023539152, 7130.901405495096, 7139.857202855914, 6522.34776522344, 6974.721011159497, 7120.287971202942, 7127.07789806177, 7130.893036604559, 5675.608172236474, 5740.512056475246, 5844.6785426801935, 6433.131527243762, 6885.277045910308, 5965.642061476326, 6121.969390153021, 6545.919923212617, 6663.966680166762, 6830.7609233676585, 6027.517798576191, 6133.60131591463, 6539.509536784855, 7099.467539934422, 7103.259701373197, 3873.663586062804, 3983.0, 4071.3508983707616, 4436.755978421218, 4470.888227794331, 6229.314775374614, 6832.180138383227, 6880.827979300558, 6892.241853396223, 6971.233164352018, 5212.661177023481, 6654.268030516542, 6666.899996500153, 6672.799816005528, 6675.499337549801, 5422.6475279106735, 6466.676666166767, 6561.114249576246, 6640.369164929324, 6644.435223005905, 4861.538153875376, 5821.748324110336, 6138.14066030765, 6489.0, 6502.219733631994, 5319.388270348804, 6448.709808058742, 6559.278479367361, 6597.736090556324, 6629.038789375417, 4948.406191257072, 5349.786008559614, 5652.915206271991, 5668.8095499945475, 6481.2778632975205], "value": 4696.519985823487, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.2841870735047, 821.3839620284787, 823.7199352220246, 838.8783626374176, 860.5998210831962, 16534.42129525467, 17021.914890425545, 17075.316987320508, 18393.172307246172, 19069.427917162473, 17867.73198402025, 18257.726134107997, 18326.175322110503, 18410.907945460287, 18674.346397876077, 984.8375018122001, 1004.3070281505774, 1005.5173516711983, 1006.9295149339549, 1007.5919252702668, 1004.9397036177816, 1008.9545970431349, 1012.8936461671528, 1015.0864222200042, 1018.9708394521549, 1054.072416273678, 1062.2245760594772, 1069.887661795512, 1070.464767616192, 1077.6605369308643, 1039.4127318065343, 1060.6499855047816, 1062.0123285344625, 1063.7978784031047, 1070.2401295080501, 749.6439191384048, 750.8610906982226, 755.8261599832069, 763.8510490454364, 765.2959277464746, 13735.862641373658, 17152.970821750736, 17223.22495487706, 17294.56763580908, 17368.47894563165, 16811.495655130366, 16887.0, 17146.914265428717, 17236.0, 17270.96374217551, 13760.724785504333, 13840.515581954583, 14029.859701402956, 14076.0, 14076.507322243775, 3443.810590417526, 3500.702440292578, 3516.8241587920757, 3520.559930008749, 3528.4530897710897, 97535.0, 112764.99999999919, 125572.74427255701, 126555.36722316421, 126811.0, 11476.0, 13422.865771342356, 13586.93206533961, 13697.794533082011, 13767.793483097761, 24816.875915620305, 25344.746552534238, 25420.74579254221, 25709.485810283688, 25798.09706660258, 8933.731988040368, 9034.909650903406, 9093.181613654802, 9097.090290970982, 9116.270698344117, 4788.808447662124, 4795.736234507066, 4815.15734746418, 4842.031593681278, 4903.141950158708, 4281.165172791276, 4343.087951530151, 4387.420528609698, 4388.648908087345, 4391.3193455014525, 943.254828685331, 960.8174446855076, 969.5733877094198, 975.8877729061107, 992.8907820139779, 498.75560975122653, 649.4317472211815, 667.1027468055457, 686.0, 688.2669956496316, 10888.0, 11147.442627868573, 11179.944100279608, 11753.353565553976, 12073.396330183456, 633.297040285283, 657.1489920552896, 659.2122413715564, 679.3987321220756, 687.5015613680042, 4665.813367465331, 5486.835394938158, 5580.609357344947, 5633.295838020247, 5725.541956643458, 90717.54641226752, 94670.52664736632, 97932.0, 99858.0, 100286.4985675067, 82263.0, 87917.12082879218, 87988.0, 88373.55813221021, 89337.55331223302, 59434.0, 59721.0, 76561.99999999888, 76914.61542692363, 77418.6129069351, 8914.73255802327, 10146.289759716748, 10454.947725261325, 10638.787224255626, 10845.728856778644, 38197.47210111564, 39239.41140882946, 39293.60706393014, 39833.80083099566, 40054.59945400509, 31658.525122122726, 51279.48720512747, 51782.48217517927, 51846.74076629593, 51903.48096518986, 11308.604198853001, 14300.928495357457, 14377.856221437652, 14444.988850780344, 14586.562403127924, 12823.61529154127, 15359.540843619838, 15574.221288935734, 15673.526915766404, 15926.124063176174, 12429.875701242872, 14517.0, 14725.410983560538, 14803.629909252355, 14814.037087589271, 14295.71408571802, 14572.927135364256, 14581.927090364481, 14607.050541714754, 14720.0, 14268.57194284173, 14592.0, 15610.765838512658, 16266.426357433615, 16466.0, 10206.59173633076, 10428.739281518023, 11789.469473873525, 12031.217970831867, 12344.259344439002, 11920.70198245016, 12985.220886746825, 13774.65563360924, 13776.724465510833, 13791.655208619864], "value": 8172.288863985273, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1708.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 122.0, 122.0, 166.0, 184.0, 130.0, 124.0, 121.0, 119.0, 167.0], "value": 136.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 114.0, 98.0, 200.0, 159.0, 103.0, 113.0, 152.0, 180.0, 109.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [223.0, 227.0, 216.0, 232.0, 214.0, 212.0, 212.0, 225.0, 301.0, 211.0], "value": 227.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 57.0, 59.0, 93.0, 78.0, 80.0, 60.0, 61.0, 79.0, 48.0], "value": 69.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 69.0, 87.0, 81.0, 87.0, 59.0, 113.0, 108.0, 109.0, 125.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 138.0, 90.0, 93.0, 94.0, 90.0, 92.0, 91.0, 92.0, 90.0], "value": 96.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 109.0, 162.0, 148.0, 100.0, 142.0, 163.0, 121.0, 160.0], "value": 130.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 101.0, 102.0, 130.0, 108.0, 159.0, 161.0, 103.0, 103.0, 138.0], "value": 120.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 51.0, 53.0, 52.0, 53.0, 53.0, 52.0, 59.0, 69.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 76.0, 72.0, 69.0, 64.0, 61.0, 67.0, 94.0, 91.0, 81.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 88.0, 88.0, 87.0, 90.0, 91.0, 88.0, 101.0, 115.0], "value": 92.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 183.0, 165.0, 167.0, 152.0, 159.0, 171.0, 167.0, 191.0, 139.0], "value": 166.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 280.0, 236.0, 223.0, 268.0, 204.0, 214.0, 228.0, 204.0, 203.0], "value": 225.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 98.0, 111.0, 109.0, 73.0, 71.0, 71.0, 72.0, 72.0, 75.0], "value": 83.0, "unit": "score"}]}]} 16:55:52 INFO - Return code: 0 16:55:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:55:52 ERROR - # TBPL SUCCESS # 16:55:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:55:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:55:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:55:52 INFO - 2017-04-12 16:55:52.387 screenresolution[2766:44407] starting screenresolution argv=screenresolution get 16:55:52 INFO - 2017-04-12 16:55:52.390 screenresolution[2766:44407] Display 0: 1600x1200x32@60 16:55:52 INFO - 2017-04-12 16:55:52.396 screenresolution[2767:44410] starting screenresolution argv=screenresolution list 16:55:52 INFO - Available Modes on Display 0 16:55:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:55:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:55:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:55:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:55:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:55:52 INFO - Intel Iris: 16:55:52 INFO - Chipset Model: Intel Iris 16:55:52 INFO - Type: GPU 16:55:52 INFO - Bus: Built-In 16:55:52 INFO - VRAM (Dynamic, Max): 1536 MB 16:55:52 INFO - Vendor: Intel (0x8086) 16:55:52 INFO - Device ID: 0x0a2e 16:55:52 INFO - Revision ID: 0x0009 16:55:52 INFO - Displays: 16:55:52 INFO - Display: 16:55:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:55:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:55:52 INFO - Display Serial Number: 1600x1200 60 16:55:52 INFO - Main Display: Yes 16:55:52 INFO - Mirror: Off 16:55:52 INFO - Online: Yes 16:55:52 INFO - Rotation: Supported 16:55:52 INFO - Return code: 0 16:55:52 INFO - Running post-action listener: _resource_record_post_action 16:55:52 INFO - [mozharness: 2017-04-12 23:55:52.638399Z] Finished run-tests step (success) 16:55:52 INFO - Running post-run listener: _resource_record_post_run 16:55:52 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 512000; Write bytes: 515817984; Read time: 4; Write time: 1579 16:55:52 INFO - TinderboxPrint: CPU usage
54.1% 16:55:52 INFO - TinderboxPrint: I/O read bytes / time
512,000 / 4 16:55:52 INFO - TinderboxPrint: I/O write bytes / time
515,817,984 / 1,579 16:55:52 INFO - TinderboxPrint: CPU idle
2,205.2 (45.9%) 16:55:52 INFO - TinderboxPrint: CPU system
967.0 (20.1%) 16:55:52 INFO - TinderboxPrint: CPU user
1,629.6 (33.9%) 16:55:52 INFO - TinderboxPrint: Swap in / out
207,925,248 / 0 16:55:52 INFO - install - Wall time: 20s; CPU: 55.0%; Read bytes: 205036032; Write bytes: 204506624; Read time: 15572; Write time: 411 16:55:52 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 475136; Write bytes: 304233472; Read time: 3; Write time: 1138 16:55:53 INFO - Running post-run listener: _upload_blobber_files 16:55:53 INFO - Blob upload gear active. 16:55:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:55:53 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 16:55:53 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:55:53 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:55:53 INFO - (blobuploader) - INFO - Open directory for files ... 16:55:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:55:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:55:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:55:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:55:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:55:54 INFO - (blobuploader) - INFO - Done attempting. 16:55:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:55:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:55:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:55:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:55:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:55:55 INFO - (blobuploader) - INFO - Done attempting. 16:55:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:55:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:55:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:55:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:55:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:55:55 INFO - (blobuploader) - INFO - Done attempting. 16:55:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:55:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:55:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:55:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:55:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:55:57 INFO - (blobuploader) - INFO - Done attempting. 16:55:57 INFO - (blobuploader) - INFO - Iteration through files over. 16:55:57 INFO - Return code: 0 16:55:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:55:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:55:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0cedacc2097266e93c6c8ffcc8a0fa21171c09f7e606906314ac483da0ab00d6bb5878b64b57cf7f3d8514bf895b7893d44543c47d0e22d2a04cffa2ddb0867e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2821f682bdb9811506e72fee6379d50ce2f2a3c0becda909f242bfb85645099f38439385f1ff1c9f26c9cd3e1a31acd7a9b022942934a089ed9f09059a2f16f9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cbb894b7118e259c174fdcb0b7c739345de81bc56e0f65c76a9a4e6076e564f903544fc30564b804705928cf2c1e96ae7cb0d8bf0be63ad22f23fed55ce170bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/95ed7ea246eef69360ef6d1a2014aae4b901fdf165b1bfe7bed9651b5b196a03ed2e3358bcf42616d0ee96b8706ad2b157ff39e165af072a99a3a9ec4677b9f5"} 16:55:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:55:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:55:57 INFO - Contents: 16:55:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0cedacc2097266e93c6c8ffcc8a0fa21171c09f7e606906314ac483da0ab00d6bb5878b64b57cf7f3d8514bf895b7893d44543c47d0e22d2a04cffa2ddb0867e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2821f682bdb9811506e72fee6379d50ce2f2a3c0becda909f242bfb85645099f38439385f1ff1c9f26c9cd3e1a31acd7a9b022942934a089ed9f09059a2f16f9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cbb894b7118e259c174fdcb0b7c739345de81bc56e0f65c76a9a4e6076e564f903544fc30564b804705928cf2c1e96ae7cb0d8bf0be63ad22f23fed55ce170bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/95ed7ea246eef69360ef6d1a2014aae4b901fdf165b1bfe7bed9651b5b196a03ed2e3358bcf42616d0ee96b8706ad2b157ff39e165af072a99a3a9ec4677b9f5"} 16:55:57 INFO - Running post-run listener: copy_logs_to_upload_dir 16:55:57 INFO - Copying logs to upload dir... 16:55:57 INFO - mkdir: /builds/slave/test/build/upload/logs 16:55:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.507139 ========= master_lag: 0.17 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-12 16:55:58.153341) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 16:55:58.158920) ========= 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.NMAn1FNZhH/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.QThhf5THMh/Listeners TMPDIR=/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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/Pine/sha512/0cedacc2097266e93c6c8ffcc8a0fa21171c09f7e606906314ac483da0ab00d6bb5878b64b57cf7f3d8514bf895b7893d44543c47d0e22d2a04cffa2ddb0867e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2821f682bdb9811506e72fee6379d50ce2f2a3c0becda909f242bfb85645099f38439385f1ff1c9f26c9cd3e1a31acd7a9b022942934a089ed9f09059a2f16f9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cbb894b7118e259c174fdcb0b7c739345de81bc56e0f65c76a9a4e6076e564f903544fc30564b804705928cf2c1e96ae7cb0d8bf0be63ad22f23fed55ce170bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/95ed7ea246eef69360ef6d1a2014aae4b901fdf165b1bfe7bed9651b5b196a03ed2e3358bcf42616d0ee96b8706ad2b157ff39e165af072a99a3a9ec4677b9f5"} build_url:https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011010 build_url: 'https://queue.taskcluster.net/v1/task/W6sqc004TI-Gq9LUxMRD1Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/0cedacc2097266e93c6c8ffcc8a0fa21171c09f7e606906314ac483da0ab00d6bb5878b64b57cf7f3d8514bf895b7893d44543c47d0e22d2a04cffa2ddb0867e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2821f682bdb9811506e72fee6379d50ce2f2a3c0becda909f242bfb85645099f38439385f1ff1c9f26c9cd3e1a31acd7a9b022942934a089ed9f09059a2f16f9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cbb894b7118e259c174fdcb0b7c739345de81bc56e0f65c76a9a4e6076e564f903544fc30564b804705928cf2c1e96ae7cb0d8bf0be63ad22f23fed55ce170bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/95ed7ea246eef69360ef6d1a2014aae4b901fdf165b1bfe7bed9651b5b196a03ed2e3358bcf42616d0ee96b8706ad2b157ff39e165af072a99a3a9ec4677b9f5"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 16:55:58.198057) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:55:58.198833) ========= 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.NMAn1FNZhH/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.QThhf5THMh/Listeners TMPDIR=/var/folders/jw/kvh0w009673bgvcgwhm3z30m00000w/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.005269 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 16:55:58.250732) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 16:55:58.251406) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 16:55:58.252164) ========= ========= Total master_lag: 0.32 =========