builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0089 starttime: 1490830237.41 results: success (0) buildid: 20170329152123 builduid: ae6f9242b9684ab19901695c8da0ce78 revision: fc2eda92be4091d5ea0828f51fe4aaee41ed8319 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 16:30:37.411091) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 16:30:37.411864) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-29 16:30:37.412369) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-29 16:30:37.441215) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:30:37.441857) ========= 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.WeSBNJ8DkE/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.hGIenzHAJu/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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-03-29 16:30:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 184M=0s 2017-03-29 16:30:37 (184 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138317 ========= master_lag: 0.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:30:37.812433) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:30:37.812777) ========= 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.WeSBNJ8DkE/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.hGIenzHAJu/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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.021972 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:30:37.857081) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-29 16:30:37.857467) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fc2eda92be4091d5ea0828f51fe4aaee41ed8319 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev fc2eda92be4091d5ea0828f51fe4aaee41ed8319 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WeSBNJ8DkE/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.hGIenzHAJu/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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-03-29 16:30:37,948 truncating revision to first 12 chars 2017-03-29 16:30:37,948 Setting DEBUG logging. 2017-03-29 16:30:37,948 attempt 1/10 2017-03-29 16:30:37,949 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fc2eda92be40?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-29 16:30:38,892 unpacking tar archive at: mozilla-central-fc2eda92be40/testing/mozharness/ program finished with exit code 0 elapsedTime=1.215125 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-29 16:30:39.095947) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 16:30:39.096336) ========= script_repo_revision: fc2eda92be4091d5ea0828f51fe4aaee41ed8319 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 16:30:39.097008) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-29 16:30:39.097467) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-29 16:30:39.117953) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-29 16:30:39.118354) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WeSBNJ8DkE/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.hGIenzHAJu/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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:30:39 INFO - MultiFileLogger online at 20170329 16:30:39 in /builds/slave/test 16:30:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:30:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:30:39 INFO - {'append_to_log': False, 16:30:39 INFO - 'base_work_dir': '/builds/slave/test', 16:30:39 INFO - 'blob_upload_branch': 'Firefox', 16:30:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:30:39 INFO - 'branch': 'Firefox', 16:30:39 INFO - 'buildbot_json_path': 'buildprops.json', 16:30:39 INFO - 'config_files': ('talos/mac_config.py',), 16:30:39 INFO - 'default_actions': ('clobber', 16:30:39 INFO - 'read-buildbot-config', 16:30:39 INFO - 'download-and-extract', 16:30:39 INFO - 'populate-webroot', 16:30:39 INFO - 'create-virtualenv', 16:30:39 INFO - 'install', 16:30:39 INFO - 'run-tests'), 16:30:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:30:39 INFO - 'download_minidump_stackwalk': True, 16:30:39 INFO - 'download_symbols': 'ondemand', 16:30:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:30:39 INFO - 'tooltool.py': '/tools/tooltool.py', 16:30:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:30:39 INFO - '/tools/misc-python/virtualenv.py')}, 16:30:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:30:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:30:39 INFO - 'gecko_profile': False, 16:30:39 INFO - 'gecko_profile_interval': 0, 16:30:39 INFO - 'installer_path': 'installer.exe', 16:30:39 INFO - 'log_level': 'info', 16:30:39 INFO - 'log_name': 'talos', 16:30:39 INFO - 'log_to_console': True, 16:30:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:30:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:30:39 INFO - 'opt_config_files': (), 16:30:39 INFO - 'pip_index': False, 16:30:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:30:39 INFO - 'cmd': ('bash', 16:30:39 INFO - '-c', 16:30:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:30:39 INFO - 'enabled': True, 16:30:39 INFO - 'halt_on_failure': False, 16:30:39 INFO - 'name': 'check_screen_resolution'},), 16:30:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:30:39 INFO - 'cmd': ('bash', 16:30:39 INFO - '-c', 16:30:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:30:39 INFO - 'enabled': True, 16:30:39 INFO - 'halt_on_failure': False, 16:30:39 INFO - 'name': 'check_screen_resolution'},), 16:30:39 INFO - 'run_cmd_checks_enabled': True, 16:30:39 INFO - 'suite': 'dromaeojs-e10s', 16:30:39 INFO - 'system_bits': '32', 16:30:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:30:39 INFO - 'title': 't-yosemite-r7-0089', 16:30:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:30:39 INFO - 'use_talos_json': True, 16:30:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:30:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:30:39 INFO - 'work_dir': 'build'} 16:30:39 INFO - [mozharness: 2017-03-29 23:30:39.330133Z] Running clobber step. 16:30:39 INFO - Running pre-action listener: _resource_record_pre_action 16:30:39 INFO - Running main action method: clobber 16:30:39 INFO - rmtree: /builds/slave/test/build 16:30:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:30:42 INFO - Running post-action listener: _resource_record_post_action 16:30:42 INFO - [mozharness: 2017-03-29 23:30:42.580162Z] Finished clobber step (success) 16:30:42 INFO - [mozharness: 2017-03-29 23:30:42.580384Z] Running read-buildbot-config step. 16:30:42 INFO - Running pre-action listener: _resource_record_pre_action 16:30:42 INFO - Running main action method: read_buildbot_config 16:30:42 INFO - Using buildbot properties: 16:30:42 INFO - { 16:30:42 INFO - "project": "", 16:30:42 INFO - "product": "firefox", 16:30:42 INFO - "script_repo_revision": "production", 16:30:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:30:42 INFO - "repository": "", 16:30:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:30:42 INFO - "buildid": "20170329152123", 16:30:42 INFO - "pgo_build": "False", 16:30:42 INFO - "basedir": "/builds/slave/test", 16:30:42 INFO - "buildnumber": 136, 16:30:42 INFO - "slavename": "t-yosemite-r7-0089", 16:30:42 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:30:42 INFO - "platform": "yosemite_r7", 16:30:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:30:42 INFO - "branch": "mozilla-central", 16:30:42 INFO - "repo_path": "mozilla-central", 16:30:42 INFO - "revision": "fc2eda92be4091d5ea0828f51fe4aaee41ed8319", 16:30:42 INFO - "stage_platform": "macosx64", 16:30:42 INFO - "builduid": "ae6f9242b9684ab19901695c8da0ce78", 16:30:42 INFO - "slavebuilddir": "test" 16:30:42 INFO - } 16:30:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 16:30:42 INFO - Running post-action listener: _resource_record_post_action 16:30:42 INFO - [mozharness: 2017-03-29 23:30:42.585780Z] Finished read-buildbot-config step (success) 16:30:42 INFO - [mozharness: 2017-03-29 23:30:42.585983Z] Running download-and-extract step. 16:30:42 INFO - Running pre-action listener: _resource_record_pre_action 16:30:42 INFO - Running main action method: download_and_extract 16:30:42 INFO - mkdir: /builds/slave/test/build/tests 16:30:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:42 INFO - trying https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:30:42 INFO - Downloading https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/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:30:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/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:30:44 INFO - Downloaded 1325 bytes. 16:30:44 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:30:44 INFO - Using the following test package requirements: 16:30:44 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:30:44 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:30:44 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:30:44 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:30:44 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:30:44 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:30:44 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:30:44 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:30:44 INFO - u'jsshell-mac.zip'], 16:30:44 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:30:44 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:30:44 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:30:44 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:30:44 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:30:44 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:30:44 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:30:44 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:30:44 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:30:44 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:30:44 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:30:44 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:30:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:30:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:30:44 INFO - Fetch https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:30:45 INFO - Expected file size: 52504514 16:30:45 INFO - Obtained file size: 52504514 16:31:02 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:31:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:31:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:31:02 INFO - Fetch https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:31:03 INFO - Expected file size: 52504514 16:31:03 INFO - Obtained file size: 52504514 16:31:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:31:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:31:19 INFO - Fetch https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:31:20 INFO - Expected file size: 14404171 16:31:20 INFO - Obtained file size: 14404171 16:31:20 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:31:20 INFO - trying https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:31:20 INFO - Downloading https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:31:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/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:31:22 INFO - Downloaded 75552434 bytes. 16:31:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:31:22 INFO - mkdir: /builds/slave/test/properties 16:31:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:31:22 INFO - Writing to file /builds/slave/test/properties/build_url 16:31:22 INFO - Contents: 16:31:22 INFO - build_url:https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:31:23 INFO - Running post-action listener: _resource_record_post_action 16:31:23 INFO - Running post-action listener: set_extra_try_arguments 16:31:23 INFO - [mozharness: 2017-03-29 23:31:23.415337Z] Finished download-and-extract step (success) 16:31:23 INFO - [mozharness: 2017-03-29 23:31:23.415492Z] Running populate-webroot step. 16:31:23 INFO - Running pre-action listener: _resource_record_pre_action 16:31:23 INFO - Running main action method: populate_webroot 16:31:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:31:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:31:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:31:23 INFO - '--tppagecycles', 16:31:23 INFO - '7'], 16:31:23 INFO - 'tests': ['tsvgm']}}, 16:31:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:31:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:31:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:31:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:31:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:31:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:31:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:31:23 INFO - 'talos_options': ['--disable-e10s'], 16:31:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:31:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:31:23 INFO - 'talos_options': ['--disable-e10s'], 16:31:23 INFO - 'tests': ['damp', 'tps']}, 16:31:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:31:23 INFO - 'tests': ['damp', 'tps']}, 16:31:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:31:23 INFO - 'tests': ['dromaeo_dom']}, 16:31:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:31:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:31:23 INFO - 'tests': ['basic_compositor_video']}, 16:31:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:31:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:31:23 INFO - 'tests': ['a11yr', 16:31:23 INFO - 'ts_paint', 16:31:23 INFO - 'tpaint', 16:31:23 INFO - 'sessionrestore', 16:31:23 INFO - 'sessionrestore_no_auto_restore', 16:31:23 INFO - 'tabpaint']}, 16:31:23 INFO - 'other-e10s': {'tests': ['a11yr', 16:31:23 INFO - 'ts_paint', 16:31:23 INFO - 'tpaint', 16:31:23 INFO - 'sessionrestore', 16:31:23 INFO - 'sessionrestore_no_auto_restore', 16:31:23 INFO - 'tabpaint']}, 16:31:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:31:23 INFO - 'ts_paint', 16:31:23 INFO - 'tpaint', 16:31:23 INFO - 'sessionrestore', 16:31:23 INFO - 'sessionrestore_no_auto_restore', 16:31:23 INFO - 'tabpaint']}, 16:31:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:31:23 INFO - 'ts_paint', 16:31:23 INFO - 'tpaint', 16:31:23 INFO - 'sessionrestore', 16:31:23 INFO - 'sessionrestore_no_auto_restore', 16:31:23 INFO - 'tabpaint']}, 16:31:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:31:23 INFO - 'tests': ['a11yr', 16:31:23 INFO - 'ts_paint', 16:31:23 INFO - 'tpaint', 16:31:23 INFO - 'sessionrestore', 16:31:23 INFO - 'sessionrestore_no_auto_restore', 16:31:23 INFO - 'tabpaint']}, 16:31:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:31:23 INFO - 'tests': ['a11yr', 16:31:23 INFO - 'ts_paint', 16:31:23 INFO - 'tpaint', 16:31:23 INFO - 'sessionrestore', 16:31:23 INFO - 'sessionrestore_no_auto_restore', 16:31:23 INFO - 'tabpaint']}, 16:31:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:31:23 INFO - 'tests': ['tsvgx', 16:31:23 INFO - 'tsvgr_opacity', 16:31:23 INFO - 'tart', 16:31:23 INFO - 'tscrollx', 16:31:23 INFO - 'cart', 16:31:23 INFO - 'tsvg_static']}, 16:31:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:31:23 INFO - 'tsvgr_opacity', 16:31:23 INFO - 'tart', 16:31:23 INFO - 'tscrollx', 16:31:23 INFO - 'cart', 16:31:23 INFO - 'tsvg_static']}, 16:31:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:31:23 INFO - 'talos_options': ['--disable-e10s'], 16:31:23 INFO - 'tests': ['tp5o']}, 16:31:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:31:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:31:23 INFO - 'talos_options': ['--disable-e10s', 16:31:23 INFO - '--xperf_path', 16:31:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:23 INFO - 'tests': ['tp5n']}, 16:31:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:31:23 INFO - 'talos_options': ['--xperf_path', 16:31:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:23 INFO - 'tests': ['tp5n']}}, 16:31:23 INFO - 'talos.zip': {'path': '', 16:31:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:31:23 INFO - Running post-action listener: _resource_record_post_action 16:31:23 INFO - [mozharness: 2017-03-29 23:31:23.433936Z] Finished populate-webroot step (success) 16:31:23 INFO - [mozharness: 2017-03-29 23:31:23.434069Z] Running create-virtualenv step. 16:31:23 INFO - Running pre-action listener: _resource_record_pre_action 16:31:23 INFO - Running main action method: create_virtualenv 16:31:23 INFO - Creating virtualenv /builds/slave/test/build/venv 16:31:23 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:31:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:31:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:31:23 INFO - Using real prefix '/tools/python27' 16:31:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:31:24 INFO - Installing distribute.............................................................................................................................................................................................done. 16:31:27 INFO - Installing pip.................done. 16:31:27 INFO - Return code: 0 16:31:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:31:27 INFO - Reading from file tmpfile_stdout 16:31:27 INFO - Output received: 16:31:27 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:31:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:31:27 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:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:27 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:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:27 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 0x109d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7badb50e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b3ed30>, '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 0x109a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b33be8>, '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.WeSBNJ8DkE/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.hGIenzHAJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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:31:27 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:31:27 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:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WeSBNJ8DkE/Render', 16:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:27 INFO - 'HOME': '/Users/cltbld', 16:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:27 INFO - 'LOGNAME': 'cltbld', 16:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:27 INFO - 'MOZ_NO_REMOTE': '1', 16:31:27 INFO - 'NO_EM_RESTART': '1', 16:31:27 INFO - 'PAGER': '/bin/cat', 16:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:27 INFO - 'PWD': '/builds/slave/test', 16:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:27 INFO - 'SHELL': '/bin/bash', 16:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGIenzHAJu/Listeners', 16:31:27 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 16:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:27 INFO - 'USER': 'cltbld', 16:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:27 INFO - 'XPC_FLAGS': '0x0', 16:31:27 INFO - 'XPC_SERVICE_NAME': '0', 16:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:27 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:31:27 INFO - Cleaning up... 16:31:27 INFO - Return code: 0 16:31:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:31:27 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:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:27 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:31:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:27 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 0x109d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7badb50e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b3ed30>, '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 0x109a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b33be8>, '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.WeSBNJ8DkE/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.hGIenzHAJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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:31:27 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:31:27 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:31:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WeSBNJ8DkE/Render', 16:31:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:27 INFO - 'HOME': '/Users/cltbld', 16:31:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:27 INFO - 'LOGNAME': 'cltbld', 16:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:27 INFO - 'MOZ_NO_REMOTE': '1', 16:31:27 INFO - 'NO_EM_RESTART': '1', 16:31:27 INFO - 'PAGER': '/bin/cat', 16:31:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:27 INFO - 'PWD': '/builds/slave/test', 16:31:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:27 INFO - 'SHELL': '/bin/bash', 16:31:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGIenzHAJu/Listeners', 16:31:27 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 16:31:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:27 INFO - 'USER': 'cltbld', 16:31:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:27 INFO - 'XPC_FLAGS': '0x0', 16:31:27 INFO - 'XPC_SERVICE_NAME': '0', 16:31:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:27 INFO - Downloading/unpacking psutil>=3.1.1 16:31:27 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:31:27 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:31:27 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:31:27 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:31:27 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:31:27 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:31:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:31:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:31:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:31:31 INFO - Installing collected packages: psutil 16:31:31 INFO - Running setup.py install for psutil 16:31:31 INFO - building 'psutil._psutil_osx' extension 16:31:31 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:31:31 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:31:32 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:31:32 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:31:32 INFO - building 'psutil._psutil_posix' extension 16:31:32 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:31:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:31:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:31:32 INFO - ^ 16:31:32 INFO - 1 warning generated. 16:31:32 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:31:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:31:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:31:32 INFO - Successfully installed psutil 16:31:32 INFO - Cleaning up... 16:31:32 INFO - Return code: 0 16:31:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:32 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 0x109d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7badb50e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b3ed30>, '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 0x109a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b33be8>, '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.WeSBNJ8DkE/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.hGIenzHAJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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:31:32 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:31:32 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:31:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WeSBNJ8DkE/Render', 16:31:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:32 INFO - 'HOME': '/Users/cltbld', 16:31:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:32 INFO - 'LOGNAME': 'cltbld', 16:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:32 INFO - 'MOZ_NO_REMOTE': '1', 16:31:32 INFO - 'NO_EM_RESTART': '1', 16:31:32 INFO - 'PAGER': '/bin/cat', 16:31:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:32 INFO - 'PWD': '/builds/slave/test', 16:31:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:32 INFO - 'SHELL': '/bin/bash', 16:31:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGIenzHAJu/Listeners', 16:31:32 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 16:31:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:32 INFO - 'USER': 'cltbld', 16:31:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:32 INFO - 'XPC_FLAGS': '0x0', 16:31:32 INFO - 'XPC_SERVICE_NAME': '0', 16:31:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:31:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:31:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:31:35 INFO - Installing collected packages: mozsystemmonitor 16:31:35 INFO - Running setup.py install for mozsystemmonitor 16:31:35 INFO - Successfully installed mozsystemmonitor 16:31:35 INFO - Cleaning up... 16:31:36 INFO - Return code: 0 16:31:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:31:36 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:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:36 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:31:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:36 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 0x109d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7badb50e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b3ed30>, '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 0x109a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b33be8>, '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.WeSBNJ8DkE/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.hGIenzHAJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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:31:36 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:31:36 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:31:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WeSBNJ8DkE/Render', 16:31:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:36 INFO - 'HOME': '/Users/cltbld', 16:31:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:36 INFO - 'LOGNAME': 'cltbld', 16:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:36 INFO - 'MOZ_NO_REMOTE': '1', 16:31:36 INFO - 'NO_EM_RESTART': '1', 16:31:36 INFO - 'PAGER': '/bin/cat', 16:31:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:36 INFO - 'PWD': '/builds/slave/test', 16:31:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:36 INFO - 'SHELL': '/bin/bash', 16:31:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGIenzHAJu/Listeners', 16:31:36 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 16:31:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:36 INFO - 'USER': 'cltbld', 16:31:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:36 INFO - 'XPC_FLAGS': '0x0', 16:31:36 INFO - 'XPC_SERVICE_NAME': '0', 16:31:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:36 INFO - Downloading/unpacking jsonschema==2.5.1 16:31:36 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:31:36 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:31:36 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:31:36 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:31:36 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:31:36 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:31:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:31:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:31: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:31: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:31: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:31: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:31: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:31: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:31:39 INFO - Downloading functools32-3.2.3-2.tar.gz 16:31:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:31:39 INFO - warning: no files found matching '*.txt' 16:31:39 INFO - no previously-included directories found matching 'build' 16:31:39 INFO - no previously-included directories found matching 'dist' 16:31:39 INFO - no previously-included directories found matching '.git*' 16:31:39 INFO - Installing collected packages: jsonschema, functools32 16:31:39 INFO - Running setup.py install for functools32 16:31:39 INFO - warning: no files found matching '*.txt' 16:31:39 INFO - no previously-included directories found matching 'build' 16:31:39 INFO - no previously-included directories found matching 'dist' 16:31:39 INFO - no previously-included directories found matching '.git*' 16:31:39 INFO - Successfully installed jsonschema functools32 16:31:39 INFO - Cleaning up... 16:31:39 INFO - Return code: 0 16:31:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7badb50e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b3ed30>, '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 0x109a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b33be8>, '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.WeSBNJ8DkE/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.hGIenzHAJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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:31:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WeSBNJ8DkE/Render', 16:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:39 INFO - 'HOME': '/Users/cltbld', 16:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:39 INFO - 'LOGNAME': 'cltbld', 16:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:39 INFO - 'MOZ_NO_REMOTE': '1', 16:31:39 INFO - 'NO_EM_RESTART': '1', 16:31:39 INFO - 'PAGER': '/bin/cat', 16:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:39 INFO - 'PWD': '/builds/slave/test', 16:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:39 INFO - 'SHELL': '/bin/bash', 16:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGIenzHAJu/Listeners', 16:31:39 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 16:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:39 INFO - 'USER': 'cltbld', 16:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:39 INFO - 'XPC_FLAGS': '0x0', 16:31:39 INFO - 'XPC_SERVICE_NAME': '0', 16:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:31:39 INFO - Cleaning up... 16:31:39 INFO - Return code: 0 16:31:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7badb50e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b3ed30>, '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 0x109a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b33be8>, '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.WeSBNJ8DkE/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.hGIenzHAJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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:31:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:31:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WeSBNJ8DkE/Render', 16:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:39 INFO - 'HOME': '/Users/cltbld', 16:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:39 INFO - 'LOGNAME': 'cltbld', 16:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:39 INFO - 'MOZ_NO_REMOTE': '1', 16:31:39 INFO - 'NO_EM_RESTART': '1', 16:31:39 INFO - 'PAGER': '/bin/cat', 16:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:39 INFO - 'PWD': '/builds/slave/test', 16:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:39 INFO - 'SHELL': '/bin/bash', 16:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGIenzHAJu/Listeners', 16:31:39 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 16:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:39 INFO - 'USER': 'cltbld', 16:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:39 INFO - 'XPC_FLAGS': '0x0', 16:31:39 INFO - 'XPC_SERVICE_NAME': '0', 16:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:40 INFO - Downloading/unpacking blobuploader==1.2.4 16:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:42 INFO - Downloading blobuploader-1.2.4.tar.gz 16:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:31:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:31:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:31:43 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:31:43 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:31:43 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:31:43 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:31:43 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:31:43 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:31:44 INFO - Downloading docopt-0.6.1.tar.gz 16:31:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:31:44 INFO - Installing collected packages: blobuploader, requests, docopt 16:31:44 INFO - Running setup.py install for blobuploader 16:31:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:31:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:31:44 INFO - Running setup.py install for requests 16:31:44 INFO - Running setup.py install for docopt 16:31:45 INFO - Successfully installed blobuploader requests docopt 16:31:45 INFO - Cleaning up... 16:31:45 INFO - Return code: 0 16:31:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31: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:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31: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:31:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:45 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 0x109d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7badb50e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b3ed30>, '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 0x109a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b33be8>, '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.WeSBNJ8DkE/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.hGIenzHAJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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:31:45 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:31:45 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:31:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WeSBNJ8DkE/Render', 16:31:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:45 INFO - 'HOME': '/Users/cltbld', 16:31:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:45 INFO - 'LOGNAME': 'cltbld', 16:31:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:45 INFO - 'MOZ_NO_REMOTE': '1', 16:31:45 INFO - 'NO_EM_RESTART': '1', 16:31:45 INFO - 'PAGER': '/bin/cat', 16:31:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:45 INFO - 'PWD': '/builds/slave/test', 16:31:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:45 INFO - 'SHELL': '/bin/bash', 16:31:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGIenzHAJu/Listeners', 16:31:45 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 16:31:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:45 INFO - 'USER': 'cltbld', 16:31:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:45 INFO - 'XPC_FLAGS': '0x0', 16:31:45 INFO - 'XPC_SERVICE_NAME': '0', 16:31:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-1pkMNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-Ur1rwd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-WUs58E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-KVSEKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-XIExhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-ozyXCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:31:45 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-mfw8ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-FLk1dA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-t4Pr5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-A39dg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-yFXq5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-4s62TV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-o8gcmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-bFrBoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-F05yxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:31:46 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-p1Dci5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:31:47 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-E3kD1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:31:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:31:47 INFO - Running setup.py install for manifestparser 16:31:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:31:47 INFO - Running setup.py install for mozcrash 16:31:47 INFO - Running setup.py install for mozdebug 16:31:47 INFO - Running setup.py install for mozdevice 16:31:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:31:47 INFO - Running setup.py install for mozfile 16:31:47 INFO - Running setup.py install for mozhttpd 16:31:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:31:47 INFO - Running setup.py install for mozinfo 16:31:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:31:48 INFO - Running setup.py install for mozInstall 16:31:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:31:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:31:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:31:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:31:48 INFO - Running setup.py install for mozleak 16:31:48 INFO - Running setup.py install for mozlog 16:31:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:31:48 INFO - Running setup.py install for moznetwork 16:31:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:31:48 INFO - Running setup.py install for mozprocess 16:31:48 INFO - Running setup.py install for mozprofile 16:31:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:31:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:31:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:31:49 INFO - Running setup.py install for mozrunner 16:31:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:31:49 INFO - Running setup.py install for mozscreenshot 16:31:49 INFO - Running setup.py install for moztest 16:31:49 INFO - Running setup.py install for mozversion 16:31:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:31:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:31:49 INFO - Cleaning up... 16:31:49 INFO - Return code: 0 16:31:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31:49 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:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:49 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:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:49 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 0x109d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7badb50e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b3ed30>, '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 0x109a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b33be8>, '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.WeSBNJ8DkE/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.hGIenzHAJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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:31:49 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:31:49 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:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WeSBNJ8DkE/Render', 16:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:49 INFO - 'HOME': '/Users/cltbld', 16:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:49 INFO - 'LOGNAME': 'cltbld', 16:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:49 INFO - 'MOZ_NO_REMOTE': '1', 16:31:49 INFO - 'NO_EM_RESTART': '1', 16:31:49 INFO - 'PAGER': '/bin/cat', 16:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:49 INFO - 'PWD': '/builds/slave/test', 16:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:49 INFO - 'SHELL': '/bin/bash', 16:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGIenzHAJu/Listeners', 16:31:49 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 16:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:49 INFO - 'USER': 'cltbld', 16:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:49 INFO - 'XPC_FLAGS': '0x0', 16:31:49 INFO - 'XPC_SERVICE_NAME': '0', 16:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:31:49 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-kHsTa7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:31:50 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:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-ozHc8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:31:50 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:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-blqrGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:31:50 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:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-PkRth5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-mYiMsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:31:50 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:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-uJTnlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:31:50 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:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-Px0wK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:31:50 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:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-f79bLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:31:50 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:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-6twB9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:31:50 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:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-bbR0NM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:31:50 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:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:31:50 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-LQ2NlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:31:51 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:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:31:51 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-3PWo00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:31:51 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:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:31:51 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-dOcZZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:31:51 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:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:31:51 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-j3zton-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:31:51 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:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:51 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-lziLJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:51 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:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:31:51 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-ByRNW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:31:51 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:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:31:51 INFO - Running setup.py (path:/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/pip-nZqVSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:31:51 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:31:51 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:31:51 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:31:51 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:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:31:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:31:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:31:51 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:31:51 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:31:51 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:31:51 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:31:51 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:31:51 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:31:54 INFO - Downloading blessings-1.6.tar.gz 16:31:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:31:54 INFO - Installing collected packages: blessings 16:31:54 INFO - Running setup.py install for blessings 16:31:54 INFO - Successfully installed blessings 16:31:54 INFO - Cleaning up... 16:31:54 INFO - Return code: 0 16:31:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:31:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:31:55 INFO - Reading from file tmpfile_stdout 16:31:55 INFO - Current package versions: 16:31:55 INFO - blessings == 1.6 16:31:55 INFO - blobuploader == 1.2.4 16:31:55 INFO - docopt == 0.6.1 16:31:55 INFO - functools32 == 3.2.3-2 16:31:55 INFO - jsonschema == 2.5.1 16:31:55 INFO - manifestparser == 1.1 16:31:55 INFO - mozInstall == 1.12 16:31:55 INFO - mozcrash == 1.0 16:31:55 INFO - mozdebug == 0.1 16:31:55 INFO - mozdevice == 0.49 16:31:55 INFO - mozfile == 1.2 16:31:55 INFO - mozhttpd == 0.7 16:31:55 INFO - mozinfo == 0.9 16:31:55 INFO - mozleak == 0.1 16:31:55 INFO - mozlog == 3.4 16:31:55 INFO - moznetwork == 0.27 16:31:55 INFO - mozprocess == 0.25 16:31:55 INFO - mozprofile == 0.28 16:31:55 INFO - mozrunner == 6.13 16:31:55 INFO - mozscreenshot == 0.1 16:31:55 INFO - mozsystemmonitor == 0.3 16:31:55 INFO - moztest == 0.8 16:31:55 INFO - mozversion == 1.4 16:31:55 INFO - psutil == 3.1.1 16:31:55 INFO - requests == 1.2.3 16:31:55 INFO - wsgiref == 0.1.2 16:31:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31:55 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:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:55 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:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:55 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 0x109d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7badb50e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b3ed30>, '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 0x109a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b33be8>, '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.WeSBNJ8DkE/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.hGIenzHAJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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:31:55 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:31:55 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:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WeSBNJ8DkE/Render', 16:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:55 INFO - 'HOME': '/Users/cltbld', 16:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:55 INFO - 'LOGNAME': 'cltbld', 16:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:55 INFO - 'MOZ_NO_REMOTE': '1', 16:31:55 INFO - 'NO_EM_RESTART': '1', 16:31:55 INFO - 'PAGER': '/bin/cat', 16:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:55 INFO - 'PWD': '/builds/slave/test', 16:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:55 INFO - 'SHELL': '/bin/bash', 16:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGIenzHAJu/Listeners', 16:31:55 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 16:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:55 INFO - 'USER': 'cltbld', 16:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:55 INFO - 'XPC_FLAGS': '0x0', 16:31:55 INFO - 'XPC_SERVICE_NAME': '0', 16:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:55 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:31:55 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:31:55 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:31:55 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:31:55 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:31:55 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:31:55 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:31:55 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:31:55 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:31:55 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:31:55 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:31:55 INFO - Cleaning up... 16:31:55 INFO - Return code: 0 16:31:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:31:55 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:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:55 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:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:55 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 0x109d60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109d63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7badb50e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b3ed30>, '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 0x109a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b33be8>, '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.WeSBNJ8DkE/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.hGIenzHAJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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:31:55 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:31:55 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:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WeSBNJ8DkE/Render', 16:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:55 INFO - 'HOME': '/Users/cltbld', 16:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:55 INFO - 'LOGNAME': 'cltbld', 16:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:55 INFO - 'MOZ_NO_REMOTE': '1', 16:31:55 INFO - 'NO_EM_RESTART': '1', 16:31:55 INFO - 'PAGER': '/bin/cat', 16:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:55 INFO - 'PWD': '/builds/slave/test', 16:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:55 INFO - 'SHELL': '/bin/bash', 16:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGIenzHAJu/Listeners', 16:31:55 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 16:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:55 INFO - 'USER': 'cltbld', 16:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:55 INFO - 'XPC_FLAGS': '0x0', 16:31:55 INFO - 'XPC_SERVICE_NAME': '0', 16:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:31:55 INFO - Cleaning up... 16:31:55 INFO - Return code: 0 16:31:55 INFO - Running post-action listener: _resource_record_post_action 16:31:55 INFO - Running post-action listener: _start_resource_monitoring 16:31:55 INFO - Starting resource monitoring. 16:31:55 INFO - [mozharness: 2017-03-29 23:31:55.622907Z] Finished create-virtualenv step (success) 16:31:55 INFO - [mozharness: 2017-03-29 23:31:55.623898Z] Running install step. 16:31:55 INFO - Running pre-action listener: _resource_record_pre_action 16:31:55 INFO - Running main action method: install 16:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:31:55 INFO - Reading from file tmpfile_stdout 16:31:55 INFO - Detecting whether we're running mozinstall >=1.0... 16:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:31:55 INFO - Reading from file tmpfile_stdout 16:31:55 INFO - Output received: 16:31:55 INFO - Usage: mozinstall [options] installer 16:31:55 INFO - Options: 16:31:55 INFO - -h, --help show this help message and exit 16:31:55 INFO - -d DEST, --destination=DEST 16:31:55 INFO - Directory to install application into. [default: 16:31:55 INFO - "/builds/slave/test"] 16:31:55 INFO - --app=APP Application being installed. [default: firefox] 16:31:55 INFO - mkdir: /builds/slave/test/build/application 16:31:55 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:31:55 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:32:16 INFO - Reading from file tmpfile_stdout 16:32:16 INFO - Output received: 16:32:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:32:16 INFO - Running post-action listener: _resource_record_post_action 16:32:16 INFO - [mozharness: 2017-03-29 23:32:16.171265Z] Finished install step (success) 16:32:16 INFO - [mozharness: 2017-03-29 23:32:16.171399Z] Running run-tests step. 16:32:16 INFO - Running pre-action listener: _resource_record_pre_action 16:32:16 INFO - Running main action method: run_tests 16:32:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:32:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:32:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:32:16 INFO - 2017-03-29 16:32:16.185 screenresolution[1961:10744] starting screenresolution argv=screenresolution get 16:32:16 INFO - 2017-03-29 16:32:16.188 screenresolution[1961:10744] Display 0: 1600x1200x32@60 16:32:16 INFO - 2017-03-29 16:32:16.194 screenresolution[1962:10746] starting screenresolution argv=screenresolution list 16:32:16 INFO - Available Modes on Display 0 16:32:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:32:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:32:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:32:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:32:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:32:16 INFO - Intel Iris: 16:32:16 INFO - Chipset Model: Intel Iris 16:32:16 INFO - Type: GPU 16:32:16 INFO - Bus: Built-In 16:32:16 INFO - VRAM (Dynamic, Max): 1536 MB 16:32:16 INFO - Vendor: Intel (0x8086) 16:32:16 INFO - Device ID: 0x0a2e 16:32:16 INFO - Revision ID: 0x0009 16:32:16 INFO - Displays: 16:32:16 INFO - Display: 16:32:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:32:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:32:16 INFO - Display Serial Number: 1600x1200 60 16:32:16 INFO - Main Display: Yes 16:32:16 INFO - Mirror: Off 16:32:16 INFO - Online: Yes 16:32:16 INFO - Rotation: Supported 16:32:16 INFO - Return code: 0 16:32:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:32:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:32:16 INFO - Python 2.7.3 16:32:16 INFO - Return code: 0 16:32:16 INFO - grabbing minidump binary from tooltool 16:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:32:16 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 0x109b3ed30>, '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 0x109a65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109b33be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:32:16 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:32:16 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:32:16 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:32:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:32:16 INFO - Return code: 0 16:32:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:32:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:32:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:32:16 INFO - ENV: RUST_BACKTRACE is now 1 16:32:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:32:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:32:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0089', '--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:32:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0089 --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:32:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WeSBNJ8DkE/Render', 16:32:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:16 INFO - 'HOME': '/Users/cltbld', 16:32:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:16 INFO - 'LOGNAME': 'cltbld', 16:32:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:32:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:32:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:16 INFO - 'MOZ_NO_REMOTE': '1', 16:32:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:32:16 INFO - 'NO_EM_RESTART': '1', 16:32:16 INFO - 'PAGER': '/bin/cat', 16:32:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:16 INFO - 'PWD': '/builds/slave/test', 16:32:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:32:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:16 INFO - 'RUST_BACKTRACE': '1', 16:32:16 INFO - 'SHELL': '/bin/bash', 16:32:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hGIenzHAJu/Listeners', 16:32:16 INFO - 'TMPDIR': '/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/', 16:32:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:16 INFO - 'USER': 'cltbld', 16:32:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:16 INFO - 'XPC_FLAGS': '0x0', 16:32:16 INFO - 'XPC_SERVICE_NAME': '0', 16:32:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0089', '--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:32:16 INFO - mozversion application_buildid: 20170329152123 16:32:16 INFO - mozversion application_changeset: fc2eda92be4091d5ea0828f51fe4aaee41ed8319 16:32:16 INFO - mozversion application_display_name: Nightly 16:32:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:32:16 INFO - mozversion application_name: Firefox 16:32:16 INFO - mozversion application_remotingname: firefox 16:32:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:32:16 INFO - mozversion application_vendor: Mozilla 16:32:16 INFO - mozversion application_version: 55.0a1 16:32:16 INFO - mozversion platform_buildid: 20170329152123 16:32:16 INFO - mozversion platform_changeset: fc2eda92be4091d5ea0828f51fe4aaee41ed8319 16:32:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:32:16 INFO - mozversion platform_version: 55.0a1 16:32:16 INFO - using testdate: 1490830336 16:32:16 INFO - actual date: 1490830336 16:32:16 INFO - starting webserver on 'localhost:49394' 16:32:16 INFO - SUITE-START | Running 2 tests 16:32:16 INFO - TEST-START | dromaeo_css 16:32:16 INFO - operating with platform_type : mac_ 16:32:16 INFO - Initialising browser for dromaeo_css test... 16:32:16 INFO - TEST-INFO | started process 1972 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmp9gaMbX/profile http://localhost:49394/getInfo.html) 16:32:19 INFO - PID 1972 | 2017-03-29 16:32:19.958 plugin-container[1974:10918] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 16:32:19 INFO - PID 1972 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:32:19 INFO - PID 1972 | 2017-03-29 16:32:19.962 plugin-container[1974:10918] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 16:32:19 INFO - PID 1972 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:32:19 INFO - PID 1972 | 2017-03-29 16:32:19.963 plugin-container[1974:10918] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:32:20 INFO - PID 1972 | __metrics Screen width/height:1600/1200 16:32:20 INFO - PID 1972 | colorDepth:24 16:32:20 INFO - PID 1972 | Browser inner width/height: 1024/689 16:32:20 INFO - PID 1972 | __metrics 16:32:20 ERROR - PID 1972 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 16:32:20 ERROR - PID 1972 | 1490830340164 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmp9gaMbX/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmp9gaMbX/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmp9gaMbX/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmp9gaMbX/profile/extensions/talos-powers@TalosPowersService.js:55:9 16:32:20 INFO - TEST-INFO | 1972: exit 0 16:32:20 INFO - Browser initialized. 16:32:20 INFO - Running cycle 1/1 for dromaeo_css test... 16:32:20 INFO - TEST-INFO | started process 1975 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmp9gaMbX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:32:23 INFO - PID 1975 | 2017-03-29 16:32:23.487 plugin-container[1976:11038] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 16:32:23 INFO - PID 1975 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:32:23 INFO - PID 1975 | 2017-03-29 16:32:23.488 plugin-container[1976:11038] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 16:32:23 INFO - PID 1975 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:32:23 INFO - PID 1975 | 2017-03-29 16:32:23.488 plugin-container[1976:11038] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:32:33 INFO - PID 1975 | RSS: Main: 230301696 16:32:33 INFO - PID 1975 | 16:35:37 INFO - PID 1975 | Cycle 1(1): loaded http://localhost:49394/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49394/tests/dromaeo/cssquery-ext.html) 16:35:39 INFO - PID 1975 | RSS: Main: 256561152 16:35:39 INFO - PID 1975 | 16:37:21 INFO - PID 1975 | ************************* 16:37:21 INFO - PID 1975 | A coding exception was thrown and uncaught in a Task. 16:37:21 INFO - PID 1975 | 16:37:21 ERROR - PID 1975 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:37:21 INFO - PID 1975 | Full stack: 16:37:21 INFO - PID 1975 | ************************* 16:37:21 INFO - PID 1975 | ************************* 16:37:21 INFO - PID 1975 | A coding exception was thrown and uncaught in a Task. 16:37:21 INFO - PID 1975 | 16:37:21 ERROR - PID 1975 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:37:21 INFO - PID 1975 | Full stack: 16:37:21 INFO - PID 1975 | ************************* 16:37:21 INFO - PID 1975 | ************************* 16:37:21 INFO - PID 1975 | A coding exception was thrown and uncaught in a Task. 16:37:21 INFO - PID 1975 | 16:37:21 ERROR - PID 1975 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:37:21 INFO - PID 1975 | Full stack: 16:37:21 INFO - PID 1975 | ************************* 16:37:21 INFO - PID 1975 | ************************* 16:37:21 INFO - PID 1975 | A coding exception was thrown and uncaught in a Task. 16:37:21 INFO - PID 1975 | 16:37:21 ERROR - PID 1975 | Full message: TypeError: NetworkError when attempting to fetch resource. 16:37:21 INFO - PID 1975 | Full stack: 16:37:21 INFO - PID 1975 | ************************* 16:38:41 INFO - PID 1975 | Cycle 1(1): loaded http://localhost:49394/tests/dromaeo/cssquery-ext.html (next: http://localhost:49394/tests/dromaeo/cssquery-jquery.html) 16:38:41 INFO - PID 1975 | RSS: Main: 262483968 16:38:41 INFO - PID 1975 | 16:41:43 INFO - PID 1975 | Cycle 1(1): loaded http://localhost:49394/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49394/tests/dromaeo/cssquery-mootools.html) 16:41:44 INFO - PID 1975 | RSS: Main: 275734528 16:41:44 INFO - PID 1975 | 16:44:46 INFO - PID 1975 | Cycle 1(1): loaded http://localhost:49394/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49394/tests/dromaeo/cssquery-prototype.html) 16:44:47 INFO - PID 1975 | RSS: Main: 268230656 16:44:47 INFO - PID 1975 | 16:47:50 INFO - PID 1975 | Cycle 1(1): loaded http://localhost:49394/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49394/tests/dromaeo/cssquery-yui.html) 16:47:50 INFO - PID 1975 | RSS: Main: 279474176 16:47:50 INFO - PID 1975 | 16:50:53 INFO - PID 1975 | Cycle 1(1): loaded http://localhost:49394/tests/dromaeo/cssquery-yui.html (next: http://localhost:49394/tests/dromaeo/cssquery-dojo.html) 16:50:53 INFO - PID 1975 | RSS: Main: 273248256 16:50:53 INFO - PID 1975 | 16:50:53 INFO - PID 1975 | __start_tp_report 16:50:53 INFO - PID 1975 | _x_x_mozilla_page_load 16:50:53 INFO - PID 1975 | _x_x_mozilla_page_load_details 16:50:53 INFO - PID 1975 | |i|pagename|runs| 16:50:53 INFO - PID 1975 | |0;dojo.html;3606.9458958115624;3612.9458058129117;3649.9270014599724;3666.5416822897128;3676.5771936227325;6112.564273424373;6204.100405441209;6224.595401298912;6279.686015699214;6289.37106289372;6235.625862448245;6239.157713708648;6247.875042499159;6287.0569414587835;6307.747690092391;5639.323281206261;5695.686737229472;5777.682227477487;5797.89839930414;5825.825225243249;5558.333000040001;5684.2326285951285;5809.302883653946;5832.970835145839;5855.79504717335;4641.930371044437;5496.230527726121;5643.153526970947;5710.685912274823;5748.712564371806;5567.164925261202;5569.331680198362;5584.96678114546;5601.935632229864;5606.243157173811;5178.766955487012;5213.973930130325;5650.6892120933335;5658.773649054033;5677.318721753375;6091.664958427285;6214.098955651405;6297.425643589103;6311.526635502356;6325.652089135096;6367.936320636781;6546.149000629887;6698.59808411492;6764.966175169142;6833;5777.480026797609;6777.661116944133;6828.829279268009;6867.759628413031;6894.896576551356;5236.24074509194;6298.527610429144;6310.463610593151;6355.491560675135;6357.65032923189;6706.765263215764;7029.61337126463;7046.1192350956135;7058.788236352918;7059.6117213552725;6441.420272175526;6571.2771595124495;6614.8346291343105;6641.966790166019;6678.165229346332;6836;6841.828954276083;6848.3494068063455;6867.038614593961;6868.862622747617;5642;6684.532082754161;6693.799186024427;6772.864542709118;6775.627340496221;6469.061986012096;6561.671916404161;6656.800295991128;6718.932810671929;6721.4958878084335;4407.911841763211;5427.9185812212845;5432.076546986943;5440.102383106743;5650.672092058363;4797.4482934462285;4818.831340903052;4819.012102518976;4827.565519103297;4831.4685384607665;6282.654454005074;6339.2392912850755;6343.358095694519;6371.044343348442;6388.201474815648;6841.692123854339;6845.8973115403305;6873.931260687429;6877.243503214642;6879.931200688029;6403.583767055126;6429.228492580826;6431;6438.903416448757;6451.903221451682;5844.035734103911;5956.6723830188885;5975.372585878507;6113.449789518964;6130.509559235251;6875.07186529812;7007.894881576678;7077.787666370017;7104.502684812013;7125.038119853961;6538.574992625464;6794.558353706993;6835.69239384219;6853.1433570801655;6864.725410983505;6772.55978361405;6905.827354316182;6982.022516847747;7025.437964962688;7064.646767661494;5067.41724701664;6698.464122870256;6733.865322693617;6741.865162696817;6750.189977202767;6518.73925042993;6610.933890661224;6755.831104222434;6756.189257289157;6839.521233513607;5309.230161626465;6608.140941677551;6655.401013908771;6665;6696;6600.471962242913;6621.3047629998155;6634.104395906684;6644.966775166094;6667.133272674715;6277.937220627735;6343.682815859281;6346.7461301549265;6415.711292991733;6428.453581445629;6611.966940165461;6654.234763002123;6654.600723956578;6669.333066693486;6669.666516674244;6355.841103972438;6481.5786973848435;6496.155499784998;6496.902546461902;6499.3175716549085 16:50:53 INFO - PID 1975 | |1;ext.html;751.4101430377156;810.339573247803;833.5998720614107;1274.674957885739;1282.1025282302392;38573.03567410816;39036;39064.60935390645;39076.21847563047;39184;37831.05422364442;38137;38185.61814381855;38277.23445531094;38433.807830960875;7422.517536360147;8004.439689221742;8154.184581541828;8452.74641760748;8677.913220867773;8531.616077276507;8599.785005374853;8634.26608738258;8657.913420865774;8663.480191188517;8616.913830861673;8685.392022558424;8697.869531957025;8702.608382622766;8732.17044380786;8601.010883748355;8623.568821558896;8628.956855215714;8632.956835215846;8776.385653004292;8499.447535910145;8560.571971401405;8578.613962371708;8585.35609829259;8601.096884827128;30917.38165236705;37592.24815503702;37644.682436114876;37715.81142094299;37784;2598.8570628615416;3069.2020074780494;3123.7500999919953;3126.7811253212285;3134.8589313480948;27210.455790884072;27765.583516247272;28392.716072839215;28393.858030709922;28591.57112643312;2808.057992069365;2823.0683874321426;2829.2926768307925;2834.9716502834913;2925.6781754007043;270046;317920;318584;319586;321147;49179.754101229264;49211.75394123006;49287.260691089665;49396;49463.010739785;50526.494735052176;50601;50964;51026;51062.74468627633;14315.713685726227;14390.712185756227;14541.709165816625;14609.561713148623;14661.706765864623;10039.347442416365;11912.404379780975;11922.940385298192;11936.701582460508;11944;13226.4048117833;13315.40080696371;13435.798463023062;13443.663908402368;13591.52429664957;5276.947230527722;5704.971475142681;5937.892048322869;5940.34656187819;5983.371745966698;830.5930094253772;876.4303202918027;949.9762505937268;973.4500007495742;1010.4139599032543;23920.52158956811;24006.039758409468;24243.272701818973;24263.878680606485;24311.0275588978;1082.4966390628394;1098.747288123736;1105.2870898270571;1126.1778901401858;1129.870064942542;11737.471813768401;12307.6923076922;12354.876451235552;12371.443285052193;12373.319467429377;428730;429507;433022;433085;435072;52904.14835480817;53149.7342513285;53266.93466130656;53413.73293133587;53521.66195845057;1470.1767010474168;1496.8428315026983;1497.0792962328308;1512.5008747113427;1514.379104567122;26031.609525856747;28293;28396;29046.854765726035;29098.56352154677;37126.88619341424;37246.88259352224;37294.694685686954;37502;37626.81186594049;317909;318069;319023;319727;320954.18568721955;31458.685413145577;32787.83606081954;33790;33928;33982.66017339795;39862.800685996386;42731.83395322158;43092;43442.34836477392;44255;28353.43293134167;29813.5527967085;30038.24904377408;30352.089437316918;30400.5439918397;29762;30320.54519182258;30438;30522.84738576293;30569.388612227187;35711.82144089263;36296.27407451889;36439.08902277358;36598.08504787402;36727.632723672425;29225.415491690474;29727.70272297249;29848.552271715507;30044.798208071432;30231;21760.782392175875;23589.764102358757;23977.28068157958;24106.51786964286;24661.506769864143 16:50:53 INFO - PID 1975 | |2;jquery.html;369.28358983571866;387.45369029668154;418.20332267031296;419.5929947950487;424.55209753709835;17916.462506124804;21231.57536849262;21489.570208595836;21730.9134543273;22328.995195216234;21565.892170539126;22061.669074963847;22141.225057123003;22205.555888882212;22312.8884355578;5823.5341172706185;6129.172561704179;6151.323354431009;6166.290876549209;6168.907466387986;5407.567394608442;5862.267216597926;5930.199423077873;5955.255593050869;6034.0948857671265;5848.707564621774;5891;5900.940990590104;5904.911426328608;5906.232189815318;5391.218273350361;5868;5885.558583106263;5943.524518038546;5948.940510594882;5268.0517506848755;5270.051390749663;5272.604554658416;5272.6836389816735;5273.997940391313;25407;26124;26372.60441093385;26427.603585946224;26524;1992.9402117936486;1993.5414854583528;2004.6692295771181;2010.8491863110326;2081.42760740796;15859.048457092496;15895.443659471863;16178.676426471522;16276.104814235212;16478.34086636533;1358.8844948179417;1539.9692006159814;1582.1614544291544;1590.747631514887;1631.6410389714363;137993.3100334502;140965;167327;171369;172647.99999999875;12299.754004919852;12410.193337433038;12456.377181140906;12479.438425270884;12563.811542826865;15911.602209944844;15916.920415397848;15948.2823272953;15965.361385544447;15976.201189940457;8804.119588041145;9004.8649270261;9025.50359730208;9035.277177825757;9040.231580315616;8621.69824056155;8645.95677021611;8672.739817805475;8829.867551986725;8843.557822108869;6374.872502550016;7658.463907526531;7735.7679269622;7740.26467485588;7815.843683126419;2214.30249471416;2231.3752149398197;2233.129079658934;2239.6976408185014;2402.981495543752;922.8938672052666;928.7028150991617;929.8372784762774;951.728757304165;957.1577012229333;16382.098984555725;18253.63492730138;20388;20430;20518.418545570163;845.218173189797;964.3153361113688;965.4649107918443;969.1568335547977;990.5641517732175;8905.643774248958;9912.355696879824;9942.304038717364;9956.253281003954;10372.325798823053;711033;713372;717775;726024;727198;159001.27989760792;159427;161375.19312403598;166881;171629;1180.3449085757486;1180.651707746217;1228.6129869091387;1233.4387853526623;1238.1766125526603;20602.69095963592;20658.483537911674;20755.377338679864;20889.582208355445;20953.580928381652;62566;64916.35083649103;65295.19853000011;65999.34000659932;67451.98822017576;69596.6520167396;93336.59995099943;93386.53306733423;94136.52931735571;94660.69410976529;8381.832363352576;8633.8704919425;8667;8696.739097827076;8816.426932249384;13321.267330296927;13518.79721804154;13611;13845.79231311511;13945.860541394457;11264.887351126385;11290.943545282222;11607.013403860767;11752.58865939688;11781.705457363634;8139.430239883388;10220.897791021995;10443;10455.94772026135;10774.838377424501;11682.182247242612;11894.988925941047;12107.878921210675;12107.98746556292;12302.569410070604;11101.722456938642;13050.15174013686;13056.869431305946;13360.398782054637;13483.932580337036;11889.98935090527;11901.702457438634;11966.162368634112;12211.206271592318;12245.448954796877 16:50:53 INFO - PID 1975 | |3;mootools.html;1256.6481385212144;1361.2377068841447;1418.9716205675886;1428.3001329348622;1438.02214494144;10699.518521666525;10965.780684386307;11143.387113708743;11157.665270041902;11312.321260724342;9095.408798428098;10854;11106.333619982806;11111.83332250017;11231.831522527169;2449.130558651675;2484.6397272395493;2491.8878650460683;2498.750124987505;2500.3999040230306;1916.281394477071;2063.865848719836;2154.493693981921;2412.975870241293;2428.490017096412;2137.7648458669532;2472.863992480413;2478.6653801736807;2478.8140889433275;2487.017628036924;2429.9999999999914;2437.171361737009;2445.596476581362;2472.0359059966627;2474.8638824864624;2356.2813341930705;2360.256519196465;2370.454795397051;2373.41851246444;2373.6202207646693;11065.280756750786;11281.210315277935;11313.547458101666;11332;11336;6247.468965137969;6417.165768450118;6504.024396340539;6504.5121615878525;6511.772087976898;3954.4266081418323;4057.5333836608875;4121.319982202963;4127.153933443637;4145.17096580682;1005.9144972677332;1165.4347641393917;1190.4404929683035;1208.2991864718442;1218.3238302741975;15302.617434564114;15306.770398444032;15441.459548915735;15448.459303924308;15461.458848940345;5310.4424035476495;5426.538744206708;5443.618946673695;5501.284832971688;5507.614466987353;11323.263987840764;11424.885751142549;11504.76990460186;11507.827382589268;11519.769604607862;6260.812175634738;6294.087357333161;6303.275123360872;6308.905366419507;6343.651099189589;5632.746526406321;5862.824115276549;5876.206712093813;5920.911186332122;5936.436038576328;4164.66682665386;4308.267594508941;4322.978385108055;4382.145481631052;4402.119576084796;1059.8251288537458;1141.235372300544;1178.8997935175435;1286.5625687266374;1393.9651508712363;666.6400143922325;734.0933946575974;754.4605606990918;774.2141726147964;799.188823344306;6416.262129855033;6534.8366290843105;6742.9662851685425;6759.324067593299;6784.185897692209;883.7348795361365;886.8625363068735;900.9639614415481;903.7605034665819;928.0858354520792;4101.241270364992;4177.081042170717;4213.936790948138;4225.57744225575;4279.079997800425;13561.932190338985;14068;14096.013279070366;14133.929330353283;14134.010619256553;13963.930180349033;13991.370388332549;14005.579832605039;14024.08843425174;14055;9853.556589953469;10186.388816671024;10238.232132590085;10242.078212960869;10256.333338332985;5332.280142180911;5980.222571065734;6538.771143009972;6827.283135270725;6844.2129155147795;9306.860397094184;12586.811197831857;12645.178063425848;12657.81013284783;12695.42870570808;12382;12610.621681349574;12699.301538415475;12734.172278801849;12749.17130386522;9058.547072646474;9114.179723824887;9228.538573071186;9266;9305.488198149167;10066.748331291776;10155.54300056514;10227.386356818615;10266.538005789607;10335;8661;8749.300055995633;8759.518226497605;8843.55782210874;8865.113488651083;8540.188682075193;8588.141185881363;8600.09698981623;8634.740957771277;8638.17937295956;6070.362611925684;7502.099748030271;7505.286997735207;7507.361874240751;7610.9238907610215;5004.749762511933;5729.885402292014;5832.300123985149;5920.437558431942;5949.315828679584;5459.590530710292;5889.882202356014;6062.18160548338;6106.786262480792;6211.627302361872 16:50:53 INFO - PID 1975 | |4;prototype.html;360.2345016839217;401.909570346672;402.4385981555732;406.7417190084296;406.99389509157356;6146.784862529801;6330.715117819697;6339.5562310638215;6370.426661600461;6437.452816510597;5837.774067445842;6104.298005729343;6236.565589914322;6307.653079080649;6366.458850997659;4608.5621865922685;4912.582430493395;4919.2621106834085;4921.876953076166;4942.827001054964;4134.317837556815;4391.890202744925;4624.09830083134;4639.721616702992;4656.3481112644085;3532.735044871632;4593.655475839309;4602.102589994953;4620.861374158781;4641.002184530325;4636.791344389494;4644.814207431698;4653.627179981913;4668.506078055024;4679.9938013326955;3856.479375284329;3867.2652196082654;3875.081834491916;3936.9409458857854;3965.543962444327;5241.764120614582;6104.603200791978;6105.175801266817;6158.949661277754;6193.442590166862;5378.435264297271;5388.595855310868;5407.594430417735;5414.566834653218;5441.170591292505;6523.151990241286;6655.43428808547;6714.83212919676;6727.697253623599;6777.288384719632;3252.642209356969;3666.046827824785;4150.692531852487;4338.544452832471;4375.824967001348;6706.731730730765;6916.619585922772;7127.7148914042855;7168.247334029904;7221.4583906207245;4507.954920450819;4533.478649955231;4540.477845047796;4541.5231400703115;4569.725816451024;4583.541645835457;4608.28571571407;4616.615015495338;4619.2609182531305;4621.260598304322;4504.6396288296855;5196.636235463481;5287.365516138088;5414.35027796667;5480.616356855062;6151.323354431099;6220.595661282002;6395.872082558416;6603.339666033455;6825.863482730417;1502.8422015688413;1661.8421249981232;1710.0936503653083;1724.905130217825;1732.5841797968396;1134.9373652201368;1180.3744015671707;1190.1014733875868;1196.2941864300105;1204.3195594489086;5628.802991895346;6077.817665470043;6175.96912015437;6424.453921416639;6499.8700025999215;6033.7630785688825;6075.513958883279;6281.685915704196;6678.33216678338;6790.151231096113;5644.32268127819;6176.505879529716;6386.1480170749965;6419.51853610981;6529.934700652932;3401.098708842159;3474.2356681530023;3595.748297619194;3642.0894776305922;3663.908402289964;6255.437010669061;6656.267810540837;6853.155940284726;7112.715491380391;7299.562026278334;6802.455803535804;7087.539309944837;7136.607486588184;7136.857262854714;7176.7846964591145;6891.75878844238;7223.349898509159;7225.121468418144;7229.56622602635;7246.717284055506;6439.613623182624;7005.299470052954;7048.718051278097;7051.576905385693;7058.294170582901;4948.703077815343;6892.760402610765;6959.582425054513;6985;7024.332688394595;7012.088428504466;7251.746188883364;7299.9270007301375;7301.634918254173;7311.4516411268305;3717.8326975285636;4397.845055922511;4507.459104907432;4529.954700452953;4533.909321813611;6722.361375669304;6872.759453419107;7000.754973576104;7006.9649651751415;7021.368076872902;6371;6591.439727623206;6733.85524460833;6782.69477873487;6797.354251346114;6547.247066587403;6722.294159113223;6745.156855393076;6775.356341147583;6782.898256526255;4979.804846836805;5389.01516097616;5421.484958928895;5739.598228124159;6533.542652014314;6733.326667333424;6747.156605424322;6756.864862702817;6768;6769.424598909148;5159.993801208729;6316.210473690789;6514.51141164424;6552.6723663815665;6589.901151482636 16:50:53 INFO - PID 1975 | |5;yui.html;664.6311297230037;716.437596486758;722.1190148019415;741.388354607449;757.2351924556197;15262.084274943498;15628.21858907053;16017.75973360399;16074.196290185502;16539.58651033725;12639.431225594848;15453.22733863308;15465.381384744596;15499.457518986837;16588;850.9829803403928;949.776802451425;977.1596427072711;983.9852402213972;985.5417230987589;771.6836097200149;993.2352088891549;1004.2367800471658;1006.6577363696342;1009.4246279620619;835.340081335744;1046.29897968361;1057.328596341325;1070.9410982395966;1076.6447072466067;1048.1667074675652;1056.646023582101;1058.0583280880023;1059.2373491086405;1059.2585190366735;778.7702627724835;778.8636988526985;791.3708601661692;792.9801754956114;793.8769490728946;16299.91850040754;16443.67112657752;16578.83421165785;17286.56783580408;17519.211635476433;14738.778918316233;14748.631284217872;14798.186099764509;14845.554633361016;14981.475648352365;11843.940780296129;12128.45421956014;12140.210886292363;12214.206076604993;12219.617174650946;3009.428208640373;3047.3752880659413;3076.8307743074124;3170.397624451347;3175.666555011737;116531;117049;118108.40945795302;119022;122430.99999999911;11028.724281893017;11407;11474.827877581843;11510.48202830858;11611.6516504505;24904.5019099617;25161.119360822282;25266.747332526807;25271.87364063168;25312.367190820376;7902.367810575255;7924.128345881948;7929.246721561442;7991.360691144694;8020.518768873886;4196.832126714959;4220.852270170591;4230.915381692411;4282.935755963663;4340.8697739067875;4015.718899677053;4055.2092341993616;4127.855525056609;4133.937990930139;4134.751914885116;680.8161796314968;778.8208711996273;798.7044793426469;802.6548584108773;812.8983877015373;487.74149700658705;523.7486006716797;547.225675668932;569.2770181869004;578.3291381996947;9797.951010244904;9885.752856178653;10078.496075196212;10277.691669249934;10454.215933804995;514.2132537218063;527.5515811560219;538.973051347431;567.9858003549945;592.6532978207711;4343.143219012115;4457.420535330386;4711.83508577205;4792.564360278242;4873.220284754422;84742.57628711817;86447;87152;95144;103892.48053759834;76556.46887062228;76780.23219767731;77473.6126319376;77543.61228193823;78945;69301.61396772137;70853.2914670857;70949.64525177548;71075.6446217776;71197.28802711907;10195;10390.636327728493;10625.990530899553;10672.73318167021;10768.784624307627;23073.769262307163;34707.652923471454;36098.27803443864;36413.817930910176;36517.72187973408;48399.79000525015;48441;48882.5111748878;49005.75497122492;49372.25941610807;13396.26320552379;13455.932720336335;13570.457181713016;13897.513587024405;14061.578152655436;14054.437822487394;14226.786598201243;14521.419143234152;14630;14880.107193568421;12987.675308117372;13189.604311870658;13290.734185316047;13772.724545509234;13991.090579112732;12282.385880706108;13258.602241932758;13375.665608359868;13477.663058423617;13732.244726540137;12727.872721272668;12762.17045892014;12864.935675321563;13023.413946372246;13430;8918.687845925362;9053.728388148365;9268.95365523168;9439.952800235955;9892.15916647093;8468.915310846813;9418.387804792666;9922.454265015493;9980.821479942511;10102.661316132786 16:50:53 INFO - PID 1975 | __end_tp_report 16:50:53 INFO - PID 1975 | __start_cc_report 16:50:53 INFO - PID 1975 | _x_x_mozilla_cycle_collect,421 16:50:53 INFO - PID 1975 | __end_cc_report 16:50:53 INFO - PID 1975 | __startTimestamp1490831453758__endTimestamp 16:50:53 INFO - PID 1975 | 16:50:53 INFO - PID 1975 | ------- Summary: start ------- 16:50:53 INFO - PID 1975 | Number of tests: 6 16:50:53 INFO - PID 1975 | 16:50:53 INFO - PID 1975 | [#0] dojo.html Cycles:165 Average:6224.89 Median:6429.23 stddev:738.00 (11.5%) stddev-sans-first:711.11 16:50:53 INFO - PID 1975 | Values: 3606.9 3612.9 3649.9 3666.5 3676.6 6112.6 6204.1 6224.6 6279.7 6289.4 6235.6 6239.2 6247.9 6287.1 6307.7 5639.3 5695.7 5777.7 5797.9 5825.8 5558.3 5684.2 5809.3 5833.0 5855.8 4641.9 5496.2 5643.2 5710.7 5748.7 5567.2 5569.3 5585.0 5601.9 5606.2 5178.8 5214.0 5650.7 5658.8 5677.3 6091.7 6214.1 6297.4 6311.5 6325.7 6367.9 6546.1 6698.6 6765.0 6833.0 5777.5 6777.7 6828.8 6867.8 6894.9 5236.2 6298.5 6310.5 6355.5 6357.7 6706.8 7029.6 7046.1 7058.8 7059.6 6441.4 6571.3 6614.8 6642.0 6678.2 6836.0 6841.8 6848.3 6867.0 6868.9 5642.0 6684.5 6693.8 6772.9 6775.6 6469.1 6561.7 6656.8 6718.9 6721.5 4407.9 5427.9 5432.1 5440.1 5650.7 4797.4 4818.8 4819.0 4827.6 4831.5 6282.7 6339.2 6343.4 6371.0 6388.2 6841.7 6845.9 6873.9 6877.2 6879.9 6403.6 6429.2 6431.0 6438.9 6451.9 5844.0 5956.7 5975.4 6113.4 6130.5 6875.1 7007.9 7077.8 7104.5 7125.0 6538.6 6794.6 6835.7 6853.1 6864.7 6772.6 6905.8 6982.0 7025.4 7064.6 5067.4 6698.5 6733.9 6741.9 6750.2 6518.7 6610.9 6755.8 6756.2 6839.5 5309.2 6608.1 6655.4 6665.0 6696.0 6600.5 6621.3 6634.1 6645.0 6667.1 6277.9 6343.7 6346.7 6415.7 6428.5 6612.0 6654.2 6654.6 6669.3 6669.7 6355.8 6481.6 6496.2 6496.9 6499.3 16:50:53 INFO - PID 1975 | 16:50:53 INFO - PID 1975 | [#1] ext.html Cycles:180 Average:49482.87 Median:33288.92 stddev:94647.01 (284.3%) stddev-sans-first:94841.79 16:50:53 INFO - PID 1975 | Values: 751.4 810.3 833.6 1274.7 1282.1 38573.0 39036.0 39064.6 39076.2 39184.0 37831.1 38137.0 38185.6 38277.2 38433.8 7422.5 8004.4 8154.2 8452.7 8677.9 8531.6 8599.8 8634.3 8657.9 8663.5 8616.9 8685.4 8697.9 8702.6 8732.2 8601.0 8623.6 8629.0 8633.0 8776.4 8499.4 8560.6 8578.6 8585.4 8601.1 30917.4 37592.2 37644.7 37715.8 37784.0 2598.9 3069.2 3123.8 3126.8 3134.9 27210.5 27765.6 28392.7 28393.9 28591.6 2808.1 2823.1 2829.3 2835.0 2925.7 270046.0 317920.0 318584.0 319586.0 321147.0 49179.8 49211.8 49287.3 49396.0 49463.0 50526.5 50601.0 50964.0 51026.0 51062.7 14315.7 14390.7 14541.7 14609.6 14661.7 10039.3 11912.4 11922.9 11936.7 11944.0 13226.4 13315.4 13435.8 13443.7 13591.5 5276.9 5705.0 5937.9 5940.3 5983.4 830.6 876.4 950.0 973.5 1010.4 23920.5 24006.0 24243.3 24263.9 24311.0 1082.5 1098.7 1105.3 1126.2 1129.9 11737.5 12307.7 12354.9 12371.4 12373.3 428730.0 429507.0 433022.0 433085.0 435072.0 52904.1 53149.7 53266.9 53413.7 53521.7 1470.2 1496.8 1497.1 1512.5 1514.4 26031.6 28293.0 28396.0 29046.9 29098.6 37126.9 37246.9 37294.7 37502.0 37626.8 317909.0 318069.0 319023.0 319727.0 320954.2 31458.7 32787.8 33790.0 33928.0 33982.7 39862.8 42731.8 43092.0 43442.3 44255.0 28353.4 29813.6 30038.2 30352.1 30400.5 29762.0 30320.5 30438.0 30522.8 30569.4 35711.8 36296.3 36439.1 36598.1 36727.6 29225.4 29727.7 29848.6 30044.8 30231.0 21760.8 23589.8 23977.3 24106.5 24661.5 16:50:53 INFO - PID 1975 | 16:50:53 INFO - PID 1975 | [#2] jquery.html Cycles:180 Average:41740.06 Median:22320.94 stddev:120894.12 (541.6%) stddev-sans-first:121193.35 16:50:53 INFO - PID 1975 | Values: 369.3 387.5 418.2 419.6 424.6 17916.5 21231.6 21489.6 21730.9 22329.0 21565.9 22061.7 22141.2 22205.6 22312.9 5823.5 6129.2 6151.3 6166.3 6168.9 5407.6 5862.3 5930.2 5955.3 6034.1 5848.7 5891.0 5900.9 5904.9 5906.2 5391.2 5868.0 5885.6 5943.5 5948.9 5268.1 5270.1 5272.6 5272.7 5274.0 25407.0 26124.0 26372.6 26427.6 26524.0 1992.9 1993.5 2004.7 2010.8 2081.4 15859.0 15895.4 16178.7 16276.1 16478.3 1358.9 1540.0 1582.2 1590.7 1631.6 137993.3 140965.0 167327.0 171369.0 172648.0 12299.8 12410.2 12456.4 12479.4 12563.8 15911.6 15916.9 15948.3 15965.4 15976.2 8804.1 9004.9 9025.5 9035.3 9040.2 8621.7 8646.0 8672.7 8829.9 8843.6 6374.9 7658.5 7735.8 7740.3 7815.8 2214.3 2231.4 2233.1 2239.7 2403.0 922.9 928.7 929.8 951.7 957.2 16382.1 18253.6 20388.0 20430.0 20518.4 845.2 964.3 965.5 969.2 990.6 8905.6 9912.4 9942.3 9956.3 10372.3 711033.0 713372.0 717775.0 726024.0 727198.0 159001.3 159427.0 161375.2 166881.0 171629.0 1180.3 1180.7 1228.6 1233.4 1238.2 20602.7 20658.5 20755.4 20889.6 20953.6 62566.0 64916.4 65295.2 65999.3 67452.0 69596.7 93336.6 93386.5 94136.5 94660.7 8381.8 8633.9 8667.0 8696.7 8816.4 13321.3 13518.8 13611.0 13845.8 13945.9 11264.9 11290.9 11607.0 11752.6 11781.7 8139.4 10220.9 10443.0 10455.9 10774.8 11682.2 11895.0 12107.9 12108.0 12302.6 11101.7 13050.2 13056.9 13360.4 13483.9 11890.0 11901.7 11966.2 12211.2 12245.4 16:50:53 INFO - PID 1975 | 16:50:53 INFO - PID 1975 | [#3] mootools.html Cycles:180 Average:6809.91 Median:2499.58 stddev:4228.38 (169.2%) stddev-sans-first:4219.64 16:50:53 INFO - PID 1975 | Values: 1256.6 1361.2 1419.0 1428.3 1438.0 10699.5 10965.8 11143.4 11157.7 11312.3 9095.4 10854.0 11106.3 11111.8 11231.8 2449.1 2484.6 2491.9 2498.8 2500.4 1916.3 2063.9 2154.5 2413.0 2428.5 2137.8 2472.9 2478.7 2478.8 2487.0 2430.0 2437.2 2445.6 2472.0 2474.9 2356.3 2360.3 2370.5 2373.4 2373.6 11065.3 11281.2 11313.5 11332.0 11336.0 6247.5 6417.2 6504.0 6504.5 6511.8 3954.4 4057.5 4121.3 4127.2 4145.2 1005.9 1165.4 1190.4 1208.3 1218.3 15302.6 15306.8 15441.5 15448.5 15461.5 5310.4 5426.5 5443.6 5501.3 5507.6 11323.3 11424.9 11504.8 11507.8 11519.8 6260.8 6294.1 6303.3 6308.9 6343.7 5632.7 5862.8 5876.2 5920.9 5936.4 4164.7 4308.3 4323.0 4382.1 4402.1 1059.8 1141.2 1178.9 1286.6 1394.0 666.6 734.1 754.5 774.2 799.2 6416.3 6534.8 6743.0 6759.3 6784.2 883.7 886.9 901.0 903.8 928.1 4101.2 4177.1 4213.9 4225.6 4279.1 13561.9 14068.0 14096.0 14133.9 14134.0 13963.9 13991.4 14005.6 14024.1 14055.0 9853.6 10186.4 10238.2 10242.1 10256.3 5332.3 5980.2 6538.8 6827.3 6844.2 9306.9 12586.8 12645.2 12657.8 12695.4 12382.0 12610.6 12699.3 12734.2 12749.2 9058.5 9114.2 9228.5 9266.0 9305.5 10066.7 10155.5 10227.4 10266.5 10335.0 8661.0 8749.3 8759.5 8843.6 8865.1 8540.2 8588.1 8600.1 8634.7 8638.2 6070.4 7502.1 7505.3 7507.4 7610.9 5004.7 5729.9 5832.3 5920.4 5949.3 5459.6 5889.9 6062.2 6106.8 6211.6 16:50:53 INFO - PID 1975 | 16:50:53 INFO - PID 1975 | [#4] prototype.html Cycles:180 Average:5421.43 Median:6125.98 stddev:1712.54 (28.0%) stddev-sans-first:1674.68 16:50:53 INFO - PID 1975 | Values: 360.2 401.9 402.4 406.7 407.0 6146.8 6330.7 6339.6 6370.4 6437.5 5837.8 6104.3 6236.6 6307.7 6366.5 4608.6 4912.6 4919.3 4921.9 4942.8 4134.3 4391.9 4624.1 4639.7 4656.3 3532.7 4593.7 4602.1 4620.9 4641.0 4636.8 4644.8 4653.6 4668.5 4680.0 3856.5 3867.3 3875.1 3936.9 3965.5 5241.8 6104.6 6105.2 6158.9 6193.4 5378.4 5388.6 5407.6 5414.6 5441.2 6523.2 6655.4 6714.8 6727.7 6777.3 3252.6 3666.0 4150.7 4338.5 4375.8 6706.7 6916.6 7127.7 7168.2 7221.5 4508.0 4533.5 4540.5 4541.5 4569.7 4583.5 4608.3 4616.6 4619.3 4621.3 4504.6 5196.6 5287.4 5414.4 5480.6 6151.3 6220.6 6395.9 6603.3 6825.9 1502.8 1661.8 1710.1 1724.9 1732.6 1134.9 1180.4 1190.1 1196.3 1204.3 5628.8 6077.8 6176.0 6424.5 6499.9 6033.8 6075.5 6281.7 6678.3 6790.2 5644.3 6176.5 6386.1 6419.5 6529.9 3401.1 3474.2 3595.7 3642.1 3663.9 6255.4 6656.3 6853.2 7112.7 7299.6 6802.5 7087.5 7136.6 7136.9 7176.8 6891.8 7223.3 7225.1 7229.6 7246.7 6439.6 7005.3 7048.7 7051.6 7058.3 4948.7 6892.8 6959.6 6985.0 7024.3 7012.1 7251.7 7299.9 7301.6 7311.5 3717.8 4397.8 4507.5 4530.0 4533.9 6722.4 6872.8 7000.8 7007.0 7021.4 6371.0 6591.4 6733.9 6782.7 6797.4 6547.2 6722.3 6745.2 6775.4 6782.9 4979.8 5389.0 5421.5 5739.6 6533.5 6733.3 6747.2 6756.9 6768.0 6769.4 5160.0 6316.2 6514.5 6552.7 6589.9 16:50:53 INFO - PID 1975 | 16:50:53 INFO - PID 1975 | [#5] yui.html Cycles:180 Average:19180.07 Median:3123.61 stddev:27522.62 (881.1%) stddev-sans-first:27564.72 16:50:53 INFO - PID 1975 | Values: 664.6 716.4 722.1 741.4 757.2 15262.1 15628.2 16017.8 16074.2 16539.6 12639.4 15453.2 15465.4 15499.5 16588.0 851.0 949.8 977.2 984.0 985.5 771.7 993.2 1004.2 1006.7 1009.4 835.3 1046.3 1057.3 1070.9 1076.6 1048.2 1056.6 1058.1 1059.2 1059.3 778.8 778.9 791.4 793.0 793.9 16299.9 16443.7 16578.8 17286.6 17519.2 14738.8 14748.6 14798.2 14845.6 14981.5 11843.9 12128.5 12140.2 12214.2 12219.6 3009.4 3047.4 3076.8 3170.4 3175.7 116531.0 117049.0 118108.4 119022.0 122431.0 11028.7 11407.0 11474.8 11510.5 11611.7 24904.5 25161.1 25266.7 25271.9 25312.4 7902.4 7924.1 7929.2 7991.4 8020.5 4196.8 4220.9 4230.9 4282.9 4340.9 4015.7 4055.2 4127.9 4133.9 4134.8 680.8 778.8 798.7 802.7 812.9 487.7 523.7 547.2 569.3 578.3 9798.0 9885.8 10078.5 10277.7 10454.2 514.2 527.6 539.0 568.0 592.7 4343.1 4457.4 4711.8 4792.6 4873.2 84742.6 86447.0 87152.0 95144.0 103892.5 76556.5 76780.2 77473.6 77543.6 78945.0 69301.6 70853.3 70949.6 71075.6 71197.3 10195.0 10390.6 10626.0 10672.7 10768.8 23073.8 34707.7 36098.3 36413.8 36517.7 48399.8 48441.0 48882.5 49005.8 49372.3 13396.3 13455.9 13570.5 13897.5 14061.6 14054.4 14226.8 14521.4 14630.0 14880.1 12987.7 13189.6 13290.7 13772.7 13991.1 12282.4 13258.6 13375.7 13477.7 13732.2 12727.9 12762.2 12864.9 13023.4 13430.0 8918.7 9053.7 9269.0 9440.0 9892.2 8468.9 9418.4 9922.5 9980.8 10102.7 16:50:53 INFO - PID 1975 | -------- Summary: end -------- 16:50:53 INFO - PID 1975 | 16:50:54 INFO - TEST-INFO | 1975: exit 0 16:50:54 INFO - TEST-OK | dromaeo_css | took 1117730ms 16:50:54 INFO - TEST-START | kraken 16:50:54 INFO - operating with platform_type : mac_ 16:50:54 INFO - Initialising browser for kraken test... 16:50:54 INFO - TEST-INFO | started process 1982 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpVWsWZN/profile http://localhost:49394/getInfo.html) 16:50:57 INFO - PID 1982 | 2017-03-29 16:50:57.362 plugin-container[1983:13294] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.tsm.portname' 16:50:57 INFO - PID 1982 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:50:57 INFO - PID 1982 | 2017-03-29 16:50:57.365 plugin-container[1983:13294] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.CFPasteboardClient' 16:50:57 INFO - PID 1982 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:50:57 INFO - PID 1982 | 2017-03-29 16:50:57.365 plugin-container[1983:13294] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:50:57 INFO - PID 1982 | __metrics Screen width/height:1600/1200 16:50:57 INFO - PID 1982 | colorDepth:24 16:50:57 INFO - PID 1982 | Browser inner width/height: 1024/689 16:50:57 INFO - PID 1982 | __metrics 16:50:57 ERROR - PID 1982 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 16:50:57 ERROR - PID 1982 | 1490831457585 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpVWsWZN/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpVWsWZN/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpVWsWZN/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpVWsWZN/profile/extensions/talos-powers@TalosPowersService.js:55:9 16:50:58 INFO - TEST-INFO | 1982: exit 0 16:50:58 INFO - Browser initialized. 16:50:58 INFO - Running cycle 1/1 for kraken test... 16:50:58 INFO - TEST-INFO | started process 1984 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/T/tmpVWsWZN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:51:00 INFO - PID 1984 | 2017-03-29 16:51:00.826 plugin-container[1985:13417] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443b, name = 'com.apple.tsm.portname' 16:51:00 INFO - PID 1984 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:51:00 INFO - PID 1984 | 2017-03-29 16:51:00.828 plugin-container[1985:13417] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 16:51:00 INFO - PID 1984 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:51:00 INFO - PID 1984 | 2017-03-29 16:51:00.828 plugin-container[1985:13417] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:51:10 INFO - PID 1984 | RSS: Main: 230285312 16:51:10 INFO - PID 1984 | 16:51:13 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49394/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49394/tests/kraken/driver.html?testName=audio-beat-detection) 16:51:14 INFO - PID 1984 | RSS: Main: 245366784 16:51:14 INFO - PID 1984 | 16:51:17 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49394/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49394/tests/kraken/driver.html?testName=audio-dft) 16:51:17 INFO - PID 1984 | RSS: Main: 253906944 16:51:17 INFO - PID 1984 | 16:51:21 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49394/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49394/tests/kraken/driver.html?testName=audio-fft) 16:51:22 INFO - PID 1984 | RSS: Main: 255217664 16:51:22 INFO - PID 1984 | 16:51:24 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49394/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49394/tests/kraken/driver.html?testName=audio-oscillator) 16:51:25 INFO - PID 1984 | RSS: Main: 256090112 16:51:25 INFO - PID 1984 | 16:51:27 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49394/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49394/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:51:27 INFO - PID 1984 | RSS: Main: 257880064 16:51:27 INFO - PID 1984 | 16:51:31 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49394/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49394/tests/kraken/driver.html?testName=imaging-darkroom) 16:51:31 INFO - PID 1984 | RSS: Main: 262922240 16:51:31 INFO - PID 1984 | 16:51:34 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49394/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49394/tests/kraken/driver.html?testName=imaging-desaturate) 16:51:35 INFO - PID 1984 | RSS: Main: 263757824 16:51:35 INFO - PID 1984 | 16:51:38 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49394/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49394/tests/kraken/driver.html?testName=json-parse-financial) 16:51:39 INFO - PID 1984 | RSS: Main: 263667712 16:51:39 INFO - PID 1984 | 16:51:41 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49394/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49394/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:51:41 INFO - PID 1984 | RSS: Main: 263741440 16:51:41 INFO - PID 1984 | 16:51:43 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49394/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49394/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:51:44 INFO - PID 1984 | RSS: Main: 263712768 16:51:44 INFO - PID 1984 | 16:51:46 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49394/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49394/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:51:47 INFO - PID 1984 | RSS: Main: 263737344 16:51:47 INFO - PID 1984 | 16:51:50 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49394/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49394/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:51:51 INFO - PID 1984 | RSS: Main: 264441856 16:51:51 INFO - PID 1984 | 16:51:54 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49394/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49394/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:51:55 INFO - PID 1984 | RSS: Main: 261251072 16:51:55 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | Cycle 1(1): loaded http://localhost:49394/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49394/tests/kraken/driver.html?testName=ai-astar) 16:51:57 INFO - PID 1984 | RSS: Main: 264470528 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | __start_tp_report 16:51:57 INFO - PID 1984 | _x_x_mozilla_page_load 16:51:57 INFO - PID 1984 | _x_x_mozilla_page_load_details 16:51:57 INFO - PID 1984 | |i|pagename|runs| 16:51:57 INFO - PID 1984 | |0;ai-astar;151;184;138;124;124;121;121;122;120;122 16:51:57 INFO - PID 1984 | |1;audio-beat-detection;157;137;153;204;154;119;121;121;182;155 16:51:57 INFO - PID 1984 | |2;audio-dft;289;232;237;220;218;232;213;218;245;247 16:51:57 INFO - PID 1984 | |3;audio-fft;89;68;90;119;63;65;71;86;103;91 16:51:57 INFO - PID 1984 | |4;audio-oscillator;84;70;90;76;73;74;86;73;73;91 16:51:57 INFO - PID 1984 | |5;imaging-gaussian-blur;98;91;91;92;91;91;92;137;128;130 16:51:57 INFO - PID 1984 | |6;imaging-darkroom;100;98;98;100;99;107;100;98;100;100 16:51:57 INFO - PID 1984 | |7;imaging-desaturate;84;84;126;119;115;163;149;114;151;102 16:51:57 INFO - PID 1984 | |8;json-parse-financial;70;66;67;68;68;69;68;67;85;67 16:51:57 INFO - PID 1984 | |9;json-stringify-tinderbox;59;59;64;63;77;66;61;61;61;77 16:51:57 INFO - PID 1984 | |10;stanford-crypto-aes;104;80;107;117;93;90;87;88;87;92 16:51:57 INFO - PID 1984 | |11;stanford-crypto-ccm;165;163;160;154;206;166;159;139;231;186 16:51:57 INFO - PID 1984 | |12;stanford-crypto-pbkdf2;199;196;194;259;254;201;202;198;196;199 16:51:57 INFO - PID 1984 | |13;stanford-crypto-sha256-iterative;71;70;67;72;72;60;88;102;102;62 16:51:57 INFO - PID 1984 | __end_tp_report 16:51:57 INFO - PID 1984 | __start_cc_report 16:51:57 INFO - PID 1984 | _x_x_mozilla_cycle_collect,1043 16:51:57 INFO - PID 1984 | __end_cc_report 16:51:57 INFO - PID 1984 | __startTimestamp1490831517615__endTimestamp 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | ------- Summary: start ------- 16:51:57 INFO - PID 1984 | Number of tests: 14 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | [#0] ai-astar Cycles:10 Average:132.70 Median:124.00 stddev:20.58 (16.6%) stddev-sans-first:20.73 16:51:57 INFO - PID 1984 | Values: 151.0 184.0 138.0 124.0 124.0 121.0 121.0 122.0 120.0 122.0 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | [#1] audio-beat-detection Cycles:10 Average:150.30 Median:154.50 stddev:27.59 (17.9%) stddev-sans-first:29.16 16:51:57 INFO - PID 1984 | Values: 157.0 137.0 153.0 204.0 154.0 119.0 121.0 121.0 182.0 155.0 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | [#2] audio-dft Cycles:10 Average:235.10 Median:234.50 stddev:22.28 (9.5%) stddev-sans-first:12.45 16:51:57 INFO - PID 1984 | Values: 289.0 232.0 237.0 220.0 218.0 232.0 213.0 218.0 245.0 247.0 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | [#3] audio-fft Cycles:10 Average:84.50 Median:78.50 stddev:18.03 (23.0%) stddev-sans-first:19.05 16:51:57 INFO - PID 1984 | Values: 89.0 68.0 90.0 119.0 63.0 65.0 71.0 86.0 103.0 91.0 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:80.00 stddev:7.90 (9.9%) stddev-sans-first:8.17 16:51:57 INFO - PID 1984 | Values: 84.0 70.0 90.0 76.0 73.0 74.0 86.0 73.0 73.0 91.0 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | [#5] imaging-gaussian-blur Cycles:10 Average:104.10 Median:91.00 stddev:19.27 (21.2%) stddev-sans-first:20.31 16:51:57 INFO - PID 1984 | Values: 98.0 91.0 91.0 92.0 91.0 91.0 92.0 137.0 128.0 130.0 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | [#6] imaging-darkroom Cycles:10 Average:100.00 Median:102.50 stddev:2.62 (2.6%) stddev-sans-first:2.78 16:51:57 INFO - PID 1984 | Values: 100.0 98.0 98.0 100.0 99.0 107.0 100.0 98.0 100.0 100.0 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | [#7] imaging-desaturate Cycles:10 Average:120.70 Median:150.00 stddev:27.20 (18.1%) stddev-sans-first:25.41 16:51:57 INFO - PID 1984 | Values: 84.0 84.0 126.0 119.0 115.0 163.0 149.0 114.0 151.0 102.0 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | [#8] json-parse-financial Cycles:10 Average:69.50 Median:68.00 stddev:5.56 (8.2%) stddev-sans-first:5.90 16:51:57 INFO - PID 1984 | Values: 70.0 66.0 67.0 68.0 68.0 69.0 68.0 67.0 85.0 67.0 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:63.50 stddev:6.78 (10.7%) stddev-sans-first:6.86 16:51:57 INFO - PID 1984 | Values: 59.0 59.0 64.0 63.0 77.0 66.0 61.0 61.0 61.0 77.0 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | [#10] stanford-crypto-aes Cycles:10 Average:94.50 Median:87.50 stddev:11.29 (12.9%) stddev-sans-first:11.44 16:51:57 INFO - PID 1984 | Values: 104.0 80.0 107.0 117.0 93.0 90.0 87.0 88.0 87.0 92.0 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | [#11] stanford-crypto-ccm Cycles:10 Average:172.90 Median:165.50 stddev:27.32 (16.5%) stddev-sans-first:28.83 16:51:57 INFO - PID 1984 | Values: 165.0 163.0 160.0 154.0 206.0 166.0 159.0 139.0 231.0 186.0 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:209.80 Median:200.00 stddev:24.76 (12.4%) stddev-sans-first:25.95 16:51:57 INFO - PID 1984 | Values: 199.0 196.0 194.0 259.0 254.0 201.0 202.0 198.0 196.0 199.0 16:51:57 INFO - PID 1984 | 16:51:57 INFO - PID 1984 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.60 Median:70.50 stddev:15.34 (21.8%) stddev-sans-first:16.14 16:51:57 INFO - PID 1984 | Values: 71.0 70.0 67.0 72.0 72.0 60.0 88.0 102.0 102.0 62.0 16:51:57 INFO - PID 1984 | -------- Summary: end -------- 16:51:57 INFO - PID 1984 | 16:51:58 INFO - TEST-INFO | 1984: exit 0 16:51:58 INFO - TEST-OK | kraken | took 64314ms 16:51:58 INFO - SUITE-END | took 1182s 16:51:58 INFO - Completed test suite (00:19:42) 16:51:58 INFO - javascript benchmark 16:51:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7731.156828711475, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3606.9458958115624, 3612.9458058129117, 3649.9270014599724, 3666.5416822897128, 3676.5771936227325, 6112.564273424373, 6204.100405441209, 6224.595401298912, 6279.686015699214, 6289.37106289372, 6235.625862448245, 6239.157713708648, 6247.875042499159, 6287.0569414587835, 6307.747690092391, 5639.323281206261, 5695.686737229472, 5777.682227477487, 5797.89839930414, 5825.825225243249, 5558.333000040001, 5684.2326285951285, 5809.302883653946, 5832.970835145839, 5855.79504717335, 4641.930371044437, 5496.230527726121, 5643.153526970947, 5710.685912274823, 5748.712564371806, 5567.164925261202, 5569.331680198362, 5584.96678114546, 5601.935632229864, 5606.243157173811, 5178.766955487012, 5213.973930130325, 5650.6892120933335, 5658.773649054033, 5677.318721753375, 6091.664958427285, 6214.098955651405, 6297.425643589103, 6311.526635502356, 6325.652089135096, 6367.936320636781, 6546.149000629887, 6698.59808411492, 6764.966175169142, 6833.0, 5777.480026797609, 6777.661116944133, 6828.829279268009, 6867.759628413031, 6894.896576551356, 5236.24074509194, 6298.527610429144, 6310.463610593151, 6355.491560675135, 6357.65032923189, 6706.765263215764, 7029.61337126463, 7046.1192350956135, 7058.788236352918, 7059.6117213552725, 6441.420272175526, 6571.2771595124495, 6614.8346291343105, 6641.966790166019, 6678.165229346332, 6836.0, 6841.828954276083, 6848.3494068063455, 6867.038614593961, 6868.862622747617, 5642.0, 6684.532082754161, 6693.799186024427, 6772.864542709118, 6775.627340496221, 6469.061986012096, 6561.671916404161, 6656.800295991128, 6718.932810671929, 6721.4958878084335, 4407.911841763211, 5427.9185812212845, 5432.076546986943, 5440.102383106743, 5650.672092058363, 4797.4482934462285, 4818.831340903052, 4819.012102518976, 4827.565519103297, 4831.4685384607665, 6282.654454005074, 6339.2392912850755, 6343.358095694519, 6371.044343348442, 6388.201474815648, 6841.692123854339, 6845.8973115403305, 6873.931260687429, 6877.243503214642, 6879.931200688029, 6403.583767055126, 6429.228492580826, 6431.0, 6438.903416448757, 6451.903221451682, 5844.035734103911, 5956.6723830188885, 5975.372585878507, 6113.449789518964, 6130.509559235251, 6875.07186529812, 7007.894881576678, 7077.787666370017, 7104.502684812013, 7125.038119853961, 6538.574992625464, 6794.558353706993, 6835.69239384219, 6853.1433570801655, 6864.725410983505, 6772.55978361405, 6905.827354316182, 6982.022516847747, 7025.437964962688, 7064.646767661494, 5067.41724701664, 6698.464122870256, 6733.865322693617, 6741.865162696817, 6750.189977202767, 6518.73925042993, 6610.933890661224, 6755.831104222434, 6756.189257289157, 6839.521233513607, 5309.230161626465, 6608.140941677551, 6655.401013908771, 6665.0, 6696.0, 6600.471962242913, 6621.3047629998155, 6634.104395906684, 6644.966775166094, 6667.133272674715, 6277.937220627735, 6343.682815859281, 6346.7461301549265, 6415.711292991733, 6428.453581445629, 6611.966940165461, 6654.234763002123, 6654.600723956578, 6669.333066693486, 6669.666516674244, 6355.841103972438, 6481.5786973848435, 6496.155499784998, 6496.902546461902, 6499.3175716549085], "value": 6178.662349177585, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.4101430377156, 810.339573247803, 833.5998720614107, 1274.674957885739, 1282.1025282302392, 38573.03567410816, 39036.0, 39064.60935390645, 39076.21847563047, 39184.0, 37831.05422364442, 38137.0, 38185.61814381855, 38277.23445531094, 38433.807830960875, 7422.517536360147, 8004.439689221742, 8154.184581541828, 8452.74641760748, 8677.913220867773, 8531.616077276507, 8599.785005374853, 8634.26608738258, 8657.913420865774, 8663.480191188517, 8616.913830861673, 8685.392022558424, 8697.869531957025, 8702.608382622766, 8732.17044380786, 8601.010883748355, 8623.568821558896, 8628.956855215714, 8632.956835215846, 8776.385653004292, 8499.447535910145, 8560.571971401405, 8578.613962371708, 8585.35609829259, 8601.096884827128, 30917.38165236705, 37592.24815503702, 37644.682436114876, 37715.81142094299, 37784.0, 2598.8570628615416, 3069.2020074780494, 3123.7500999919953, 3126.7811253212285, 3134.8589313480948, 27210.455790884072, 27765.583516247272, 28392.716072839215, 28393.858030709922, 28591.57112643312, 2808.057992069365, 2823.0683874321426, 2829.2926768307925, 2834.9716502834913, 2925.6781754007043, 270046.0, 317920.0, 318584.0, 319586.0, 321147.0, 49179.754101229264, 49211.75394123006, 49287.260691089665, 49396.0, 49463.010739785, 50526.494735052176, 50601.0, 50964.0, 51026.0, 51062.74468627633, 14315.713685726227, 14390.712185756227, 14541.709165816625, 14609.561713148623, 14661.706765864623, 10039.347442416365, 11912.404379780975, 11922.940385298192, 11936.701582460508, 11944.0, 13226.4048117833, 13315.40080696371, 13435.798463023062, 13443.663908402368, 13591.52429664957, 5276.947230527722, 5704.971475142681, 5937.892048322869, 5940.34656187819, 5983.371745966698, 830.5930094253772, 876.4303202918027, 949.9762505937268, 973.4500007495742, 1010.4139599032543, 23920.52158956811, 24006.039758409468, 24243.272701818973, 24263.878680606485, 24311.0275588978, 1082.4966390628394, 1098.747288123736, 1105.2870898270571, 1126.1778901401858, 1129.870064942542, 11737.471813768401, 12307.6923076922, 12354.876451235552, 12371.443285052193, 12373.319467429377, 428730.0, 429507.0, 433022.0, 433085.0, 435072.0, 52904.14835480817, 53149.7342513285, 53266.93466130656, 53413.73293133587, 53521.66195845057, 1470.1767010474168, 1496.8428315026983, 1497.0792962328308, 1512.5008747113427, 1514.379104567122, 26031.609525856747, 28293.0, 28396.0, 29046.854765726035, 29098.56352154677, 37126.88619341424, 37246.88259352224, 37294.694685686954, 37502.0, 37626.81186594049, 317909.0, 318069.0, 319023.0, 319727.0, 320954.18568721955, 31458.685413145577, 32787.83606081954, 33790.0, 33928.0, 33982.66017339795, 39862.800685996386, 42731.83395322158, 43092.0, 43442.34836477392, 44255.0, 28353.43293134167, 29813.5527967085, 30038.24904377408, 30352.089437316918, 30400.5439918397, 29762.0, 30320.54519182258, 30438.0, 30522.84738576293, 30569.388612227187, 35711.82144089263, 36296.27407451889, 36439.08902277358, 36598.08504787402, 36727.632723672425, 29225.415491690474, 29727.70272297249, 29848.552271715507, 30044.798208071432, 30231.0, 21760.782392175875, 23589.764102358757, 23977.28068157958, 24106.51786964286, 24661.506769864143], "value": 17590.164768841365, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.28358983571866, 387.45369029668154, 418.20332267031296, 419.5929947950487, 424.55209753709835, 17916.462506124804, 21231.57536849262, 21489.570208595836, 21730.9134543273, 22328.995195216234, 21565.892170539126, 22061.669074963847, 22141.225057123003, 22205.555888882212, 22312.8884355578, 5823.5341172706185, 6129.172561704179, 6151.323354431009, 6166.290876549209, 6168.907466387986, 5407.567394608442, 5862.267216597926, 5930.199423077873, 5955.255593050869, 6034.0948857671265, 5848.707564621774, 5891.0, 5900.940990590104, 5904.911426328608, 5906.232189815318, 5391.218273350361, 5868.0, 5885.558583106263, 5943.524518038546, 5948.940510594882, 5268.0517506848755, 5270.051390749663, 5272.604554658416, 5272.6836389816735, 5273.997940391313, 25407.0, 26124.0, 26372.60441093385, 26427.603585946224, 26524.0, 1992.9402117936486, 1993.5414854583528, 2004.6692295771181, 2010.8491863110326, 2081.42760740796, 15859.048457092496, 15895.443659471863, 16178.676426471522, 16276.104814235212, 16478.34086636533, 1358.8844948179417, 1539.9692006159814, 1582.1614544291544, 1590.747631514887, 1631.6410389714363, 137993.3100334502, 140965.0, 167327.0, 171369.0, 172647.99999999875, 12299.754004919852, 12410.193337433038, 12456.377181140906, 12479.438425270884, 12563.811542826865, 15911.602209944844, 15916.920415397848, 15948.2823272953, 15965.361385544447, 15976.201189940457, 8804.119588041145, 9004.8649270261, 9025.50359730208, 9035.277177825757, 9040.231580315616, 8621.69824056155, 8645.95677021611, 8672.739817805475, 8829.867551986725, 8843.557822108869, 6374.872502550016, 7658.463907526531, 7735.7679269622, 7740.26467485588, 7815.843683126419, 2214.30249471416, 2231.3752149398197, 2233.129079658934, 2239.6976408185014, 2402.981495543752, 922.8938672052666, 928.7028150991617, 929.8372784762774, 951.728757304165, 957.1577012229333, 16382.098984555725, 18253.63492730138, 20388.0, 20430.0, 20518.418545570163, 845.218173189797, 964.3153361113688, 965.4649107918443, 969.1568335547977, 990.5641517732175, 8905.643774248958, 9912.355696879824, 9942.304038717364, 9956.253281003954, 10372.325798823053, 711033.0, 713372.0, 717775.0, 726024.0, 727198.0, 159001.27989760792, 159427.0, 161375.19312403598, 166881.0, 171629.0, 1180.3449085757486, 1180.651707746217, 1228.6129869091387, 1233.4387853526623, 1238.1766125526603, 20602.69095963592, 20658.483537911674, 20755.377338679864, 20889.582208355445, 20953.580928381652, 62566.0, 64916.35083649103, 65295.19853000011, 65999.34000659932, 67451.98822017576, 69596.6520167396, 93336.59995099943, 93386.53306733423, 94136.52931735571, 94660.69410976529, 8381.832363352576, 8633.8704919425, 8667.0, 8696.739097827076, 8816.426932249384, 13321.267330296927, 13518.79721804154, 13611.0, 13845.79231311511, 13945.860541394457, 11264.887351126385, 11290.943545282222, 11607.013403860767, 11752.58865939688, 11781.705457363634, 8139.430239883388, 10220.897791021995, 10443.0, 10455.94772026135, 10774.838377424501, 11682.182247242612, 11894.988925941047, 12107.878921210675, 12107.98746556292, 12302.569410070604, 11101.722456938642, 13050.15174013686, 13056.869431305946, 13360.398782054637, 13483.932580337036, 11889.98935090527, 11901.702457438634, 11966.162368634112, 12211.206271592318, 12245.448954796877], "value": 10443.32244602874, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1256.6481385212144, 1361.2377068841447, 1418.9716205675886, 1428.3001329348622, 1438.02214494144, 10699.518521666525, 10965.780684386307, 11143.387113708743, 11157.665270041902, 11312.321260724342, 9095.408798428098, 10854.0, 11106.333619982806, 11111.83332250017, 11231.831522527169, 2449.130558651675, 2484.6397272395493, 2491.8878650460683, 2498.750124987505, 2500.3999040230306, 1916.281394477071, 2063.865848719836, 2154.493693981921, 2412.975870241293, 2428.490017096412, 2137.7648458669532, 2472.863992480413, 2478.6653801736807, 2478.8140889433275, 2487.017628036924, 2429.9999999999914, 2437.171361737009, 2445.596476581362, 2472.0359059966627, 2474.8638824864624, 2356.2813341930705, 2360.256519196465, 2370.454795397051, 2373.41851246444, 2373.6202207646693, 11065.280756750786, 11281.210315277935, 11313.547458101666, 11332.0, 11336.0, 6247.468965137969, 6417.165768450118, 6504.024396340539, 6504.5121615878525, 6511.772087976898, 3954.4266081418323, 4057.5333836608875, 4121.319982202963, 4127.153933443637, 4145.17096580682, 1005.9144972677332, 1165.4347641393917, 1190.4404929683035, 1208.2991864718442, 1218.3238302741975, 15302.617434564114, 15306.770398444032, 15441.459548915735, 15448.459303924308, 15461.458848940345, 5310.4424035476495, 5426.538744206708, 5443.618946673695, 5501.284832971688, 5507.614466987353, 11323.263987840764, 11424.885751142549, 11504.76990460186, 11507.827382589268, 11519.769604607862, 6260.812175634738, 6294.087357333161, 6303.275123360872, 6308.905366419507, 6343.651099189589, 5632.746526406321, 5862.824115276549, 5876.206712093813, 5920.911186332122, 5936.436038576328, 4164.66682665386, 4308.267594508941, 4322.978385108055, 4382.145481631052, 4402.119576084796, 1059.8251288537458, 1141.235372300544, 1178.8997935175435, 1286.5625687266374, 1393.9651508712363, 666.6400143922325, 734.0933946575974, 754.4605606990918, 774.2141726147964, 799.188823344306, 6416.262129855033, 6534.8366290843105, 6742.9662851685425, 6759.324067593299, 6784.185897692209, 883.7348795361365, 886.8625363068735, 900.9639614415481, 903.7605034665819, 928.0858354520792, 4101.241270364992, 4177.081042170717, 4213.936790948138, 4225.57744225575, 4279.079997800425, 13561.932190338985, 14068.0, 14096.013279070366, 14133.929330353283, 14134.010619256553, 13963.930180349033, 13991.370388332549, 14005.579832605039, 14024.08843425174, 14055.0, 9853.556589953469, 10186.388816671024, 10238.232132590085, 10242.078212960869, 10256.333338332985, 5332.280142180911, 5980.222571065734, 6538.771143009972, 6827.283135270725, 6844.2129155147795, 9306.860397094184, 12586.811197831857, 12645.178063425848, 12657.81013284783, 12695.42870570808, 12382.0, 12610.621681349574, 12699.301538415475, 12734.172278801849, 12749.17130386522, 9058.547072646474, 9114.179723824887, 9228.538573071186, 9266.0, 9305.488198149167, 10066.748331291776, 10155.54300056514, 10227.386356818615, 10266.538005789607, 10335.0, 8661.0, 8749.300055995633, 8759.518226497605, 8843.55782210874, 8865.113488651083, 8540.188682075193, 8588.141185881363, 8600.09698981623, 8634.740957771277, 8638.17937295956, 6070.362611925684, 7502.099748030271, 7505.286997735207, 7507.361874240751, 7610.9238907610215, 5004.749762511933, 5729.885402292014, 5832.300123985149, 5920.437558431942, 5949.315828679584, 5459.590530710292, 5889.882202356014, 6062.18160548338, 6106.786262480792, 6211.627302361872], "value": 5171.071348119334, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.2345016839217, 401.909570346672, 402.4385981555732, 406.7417190084296, 406.99389509157356, 6146.784862529801, 6330.715117819697, 6339.5562310638215, 6370.426661600461, 6437.452816510597, 5837.774067445842, 6104.298005729343, 6236.565589914322, 6307.653079080649, 6366.458850997659, 4608.5621865922685, 4912.582430493395, 4919.2621106834085, 4921.876953076166, 4942.827001054964, 4134.317837556815, 4391.890202744925, 4624.09830083134, 4639.721616702992, 4656.3481112644085, 3532.735044871632, 4593.655475839309, 4602.102589994953, 4620.861374158781, 4641.002184530325, 4636.791344389494, 4644.814207431698, 4653.627179981913, 4668.506078055024, 4679.9938013326955, 3856.479375284329, 3867.2652196082654, 3875.081834491916, 3936.9409458857854, 3965.543962444327, 5241.764120614582, 6104.603200791978, 6105.175801266817, 6158.949661277754, 6193.442590166862, 5378.435264297271, 5388.595855310868, 5407.594430417735, 5414.566834653218, 5441.170591292505, 6523.151990241286, 6655.43428808547, 6714.83212919676, 6727.697253623599, 6777.288384719632, 3252.642209356969, 3666.046827824785, 4150.692531852487, 4338.544452832471, 4375.824967001348, 6706.731730730765, 6916.619585922772, 7127.7148914042855, 7168.247334029904, 7221.4583906207245, 4507.954920450819, 4533.478649955231, 4540.477845047796, 4541.5231400703115, 4569.725816451024, 4583.541645835457, 4608.28571571407, 4616.615015495338, 4619.2609182531305, 4621.260598304322, 4504.6396288296855, 5196.636235463481, 5287.365516138088, 5414.35027796667, 5480.616356855062, 6151.323354431099, 6220.595661282002, 6395.872082558416, 6603.339666033455, 6825.863482730417, 1502.8422015688413, 1661.8421249981232, 1710.0936503653083, 1724.905130217825, 1732.5841797968396, 1134.9373652201368, 1180.3744015671707, 1190.1014733875868, 1196.2941864300105, 1204.3195594489086, 5628.802991895346, 6077.817665470043, 6175.96912015437, 6424.453921416639, 6499.8700025999215, 6033.7630785688825, 6075.513958883279, 6281.685915704196, 6678.33216678338, 6790.151231096113, 5644.32268127819, 6176.505879529716, 6386.1480170749965, 6419.51853610981, 6529.934700652932, 3401.098708842159, 3474.2356681530023, 3595.748297619194, 3642.0894776305922, 3663.908402289964, 6255.437010669061, 6656.267810540837, 6853.155940284726, 7112.715491380391, 7299.562026278334, 6802.455803535804, 7087.539309944837, 7136.607486588184, 7136.857262854714, 7176.7846964591145, 6891.75878844238, 7223.349898509159, 7225.121468418144, 7229.56622602635, 7246.717284055506, 6439.613623182624, 7005.299470052954, 7048.718051278097, 7051.576905385693, 7058.294170582901, 4948.703077815343, 6892.760402610765, 6959.582425054513, 6985.0, 7024.332688394595, 7012.088428504466, 7251.746188883364, 7299.9270007301375, 7301.634918254173, 7311.4516411268305, 3717.8326975285636, 4397.845055922511, 4507.459104907432, 4529.954700452953, 4533.909321813611, 6722.361375669304, 6872.759453419107, 7000.754973576104, 7006.9649651751415, 7021.368076872902, 6371.0, 6591.439727623206, 6733.85524460833, 6782.69477873487, 6797.354251346114, 6547.247066587403, 6722.294159113223, 6745.156855393076, 6775.356341147583, 6782.898256526255, 4979.804846836805, 5389.01516097616, 5421.484958928895, 5739.598228124159, 6533.542652014314, 6733.326667333424, 6747.156605424322, 6756.864862702817, 6768.0, 6769.424598909148, 5159.993801208729, 6316.210473690789, 6514.51141164424, 6552.6723663815665, 6589.901151482636], "value": 4898.420335600389, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.6311297230037, 716.437596486758, 722.1190148019415, 741.388354607449, 757.2351924556197, 15262.084274943498, 15628.21858907053, 16017.75973360399, 16074.196290185502, 16539.58651033725, 12639.431225594848, 15453.22733863308, 15465.381384744596, 15499.457518986837, 16588.0, 850.9829803403928, 949.776802451425, 977.1596427072711, 983.9852402213972, 985.5417230987589, 771.6836097200149, 993.2352088891549, 1004.2367800471658, 1006.6577363696342, 1009.4246279620619, 835.340081335744, 1046.29897968361, 1057.328596341325, 1070.9410982395966, 1076.6447072466067, 1048.1667074675652, 1056.646023582101, 1058.0583280880023, 1059.2373491086405, 1059.2585190366735, 778.7702627724835, 778.8636988526985, 791.3708601661692, 792.9801754956114, 793.8769490728946, 16299.91850040754, 16443.67112657752, 16578.83421165785, 17286.56783580408, 17519.211635476433, 14738.778918316233, 14748.631284217872, 14798.186099764509, 14845.554633361016, 14981.475648352365, 11843.940780296129, 12128.45421956014, 12140.210886292363, 12214.206076604993, 12219.617174650946, 3009.428208640373, 3047.3752880659413, 3076.8307743074124, 3170.397624451347, 3175.666555011737, 116531.0, 117049.0, 118108.40945795302, 119022.0, 122430.99999999911, 11028.724281893017, 11407.0, 11474.827877581843, 11510.48202830858, 11611.6516504505, 24904.5019099617, 25161.119360822282, 25266.747332526807, 25271.87364063168, 25312.367190820376, 7902.367810575255, 7924.128345881948, 7929.246721561442, 7991.360691144694, 8020.518768873886, 4196.832126714959, 4220.852270170591, 4230.915381692411, 4282.935755963663, 4340.8697739067875, 4015.718899677053, 4055.2092341993616, 4127.855525056609, 4133.937990930139, 4134.751914885116, 680.8161796314968, 778.8208711996273, 798.7044793426469, 802.6548584108773, 812.8983877015373, 487.74149700658705, 523.7486006716797, 547.225675668932, 569.2770181869004, 578.3291381996947, 9797.951010244904, 9885.752856178653, 10078.496075196212, 10277.691669249934, 10454.215933804995, 514.2132537218063, 527.5515811560219, 538.973051347431, 567.9858003549945, 592.6532978207711, 4343.143219012115, 4457.420535330386, 4711.83508577205, 4792.564360278242, 4873.220284754422, 84742.57628711817, 86447.0, 87152.0, 95144.0, 103892.48053759834, 76556.46887062228, 76780.23219767731, 77473.6126319376, 77543.61228193823, 78945.0, 69301.61396772137, 70853.2914670857, 70949.64525177548, 71075.6446217776, 71197.28802711907, 10195.0, 10390.636327728493, 10625.990530899553, 10672.73318167021, 10768.784624307627, 23073.769262307163, 34707.652923471454, 36098.27803443864, 36413.817930910176, 36517.72187973408, 48399.79000525015, 48441.0, 48882.5111748878, 49005.75497122492, 49372.25941610807, 13396.26320552379, 13455.932720336335, 13570.457181713016, 13897.513587024405, 14061.578152655436, 14054.437822487394, 14226.786598201243, 14521.419143234152, 14630.0, 14880.107193568421, 12987.675308117372, 13189.604311870658, 13290.734185316047, 13772.724545509234, 13991.090579112732, 12282.385880706108, 13258.602241932758, 13375.665608359868, 13477.663058423617, 13732.244726540137, 12727.872721272668, 12762.17045892014, 12864.935675321563, 13023.413946372246, 13430.0, 8918.687845925362, 9053.728388148365, 9268.95365523168, 9439.952800235955, 9892.15916647093, 8468.915310846813, 9418.387804792666, 9922.454265015493, 9980.821479942511, 10102.661316132786], "value": 7426.712796859233, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1694.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 184.0, 138.0, 124.0, 124.0, 121.0, 121.0, 122.0, 120.0, 122.0], "value": 132.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 137.0, 153.0, 204.0, 154.0, 119.0, 121.0, 121.0, 182.0, 155.0], "value": 150.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [289.0, 232.0, 237.0, 220.0, 218.0, 232.0, 213.0, 218.0, 245.0, 247.0], "value": 235.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 68.0, 90.0, 119.0, 63.0, 65.0, 71.0, 86.0, 103.0, 91.0], "value": 84.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 90.0, 76.0, 73.0, 74.0, 86.0, 73.0, 73.0, 91.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 91.0, 91.0, 92.0, 91.0, 91.0, 92.0, 137.0, 128.0, 130.0], "value": 104.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 98.0, 98.0, 100.0, 99.0, 107.0, 100.0, 98.0, 100.0, 100.0], "value": 100.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 126.0, 119.0, 115.0, 163.0, 149.0, 114.0, 151.0, 102.0], "value": 120.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 67.0, 68.0, 68.0, 69.0, 68.0, 67.0, 85.0, 67.0], "value": 69.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 64.0, 63.0, 77.0, 66.0, 61.0, 61.0, 61.0, 77.0], "value": 64.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 80.0, 107.0, 117.0, 93.0, 90.0, 87.0, 88.0, 87.0, 92.0], "value": 94.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 160.0, 154.0, 206.0, 166.0, 159.0, 139.0, 231.0, 186.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 196.0, 194.0, 259.0, 254.0, 201.0, 202.0, 198.0, 196.0, 199.0], "value": 209.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 67.0, 72.0, 72.0, 60.0, 88.0, 102.0, 102.0, 62.0], "value": 76.6, "unit": "score"}]}]} 16:51:58 INFO - Return code: 0 16:51:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:51:58 ERROR - # TBPL SUCCESS # 16:51:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:51:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:51:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:51:58 INFO - 2017-03-29 16:51:58.954 screenresolution[1989:13814] starting screenresolution argv=screenresolution get 16:51:58 INFO - 2017-03-29 16:51:58.956 screenresolution[1989:13814] Display 0: 1600x1200x32@60 16:51:58 INFO - 2017-03-29 16:51:58.964 screenresolution[1990:13817] starting screenresolution argv=screenresolution list 16:51:58 INFO - Available Modes on Display 0 16:51:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:51:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:51:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:51:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:51:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:51:59 INFO - Intel Iris: 16:51:59 INFO - Chipset Model: Intel Iris 16:51:59 INFO - Type: GPU 16:51:59 INFO - Bus: Built-In 16:51:59 INFO - VRAM (Dynamic, Max): 1536 MB 16:51:59 INFO - Vendor: Intel (0x8086) 16:51:59 INFO - Device ID: 0x0a2e 16:51:59 INFO - Revision ID: 0x0009 16:51:59 INFO - Displays: 16:51:59 INFO - Display: 16:51:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:51:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:51:59 INFO - Display Serial Number: 1600x1200 60 16:51:59 INFO - Main Display: Yes 16:51:59 INFO - Mirror: Off 16:51:59 INFO - Online: Yes 16:51:59 INFO - Rotation: Supported 16:51:59 INFO - Return code: 0 16:51:59 INFO - Running post-action listener: _resource_record_post_action 16:51:59 INFO - [mozharness: 2017-03-29 23:51:59.212162Z] Finished run-tests step (success) 16:51:59 INFO - Running post-run listener: _resource_record_post_run 16:51:59 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 458752; Write bytes: 518251520; Read time: 8; Write time: 1742 16:51:59 INFO - TinderboxPrint: CPU usage
54.2% 16:51:59 INFO - TinderboxPrint: I/O read bytes / time
458,752 / 8 16:51:59 INFO - TinderboxPrint: I/O write bytes / time
518,251,520 / 1,742 16:51:59 INFO - TinderboxPrint: CPU idle
2,204.3 (45.8%) 16:51:59 INFO - TinderboxPrint: CPU system
966.5 (20.1%) 16:51:59 INFO - TinderboxPrint: CPU user
1,643.0 (34.1%) 16:51:59 INFO - TinderboxPrint: Swap in / out
212,434,944 / 0 16:51:59 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 206965248; Write bytes: 204961792; Read time: 15791; Write time: 458 16:51:59 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 458752; Write bytes: 302451712; Read time: 8; Write time: 1253 16:51:59 INFO - Running post-run listener: _upload_blobber_files 16:51:59 INFO - Blob upload gear active. 16:51:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:51:59 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:51:59 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:51:59 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:52:00 INFO - (blobuploader) - INFO - Open directory for files ... 16:52:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:52:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:52:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:01 INFO - (blobuploader) - INFO - Done attempting. 16:52:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:52:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:52:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:02 INFO - (blobuploader) - INFO - Done attempting. 16:52:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:52:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:52:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:03 INFO - (blobuploader) - INFO - Done attempting. 16:52:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:52:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:52:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:05 INFO - (blobuploader) - INFO - Done attempting. 16:52:05 INFO - (blobuploader) - INFO - Iteration through files over. 16:52:05 INFO - Return code: 0 16:52:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:52:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:52:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f05def63c13331620bfe3b4ce3fa8b0f3d48416b3acf457f981107ff74435de68f39d6d7c505217f0020c1853b434e4b2eea96339a1e5c294d88d8882e255ca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e6403afc5509bcc35143b9755d2041540ca52c1b5fe644226f7eee80c922a7fd0d6e9441a0c32a8e61477ec1c91d73becab1d4806a0c9f39c58aef86f481aba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1888376dc352eef489ed56546623eefddfde9bf232890f9d5ecd2049dbb6d185184fb9667e71875bcd728955a994031e5fd0f8520b00287057282fba305229cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c655a8ccb2c1dcd37f13049fa722974e0ebc40348ab90e5072ce8a0ed452015ede0b8104142b3eed2f33c75ddd4163b2b6a67533d9ad6ad83982f5abb707b3a5"} 16:52:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:52:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:52:05 INFO - Contents: 16:52:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f05def63c13331620bfe3b4ce3fa8b0f3d48416b3acf457f981107ff74435de68f39d6d7c505217f0020c1853b434e4b2eea96339a1e5c294d88d8882e255ca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e6403afc5509bcc35143b9755d2041540ca52c1b5fe644226f7eee80c922a7fd0d6e9441a0c32a8e61477ec1c91d73becab1d4806a0c9f39c58aef86f481aba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1888376dc352eef489ed56546623eefddfde9bf232890f9d5ecd2049dbb6d185184fb9667e71875bcd728955a994031e5fd0f8520b00287057282fba305229cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c655a8ccb2c1dcd37f13049fa722974e0ebc40348ab90e5072ce8a0ed452015ede0b8104142b3eed2f33c75ddd4163b2b6a67533d9ad6ad83982f5abb707b3a5"} 16:52:05 INFO - Running post-run listener: copy_logs_to_upload_dir 16:52:05 INFO - Copying logs to upload dir... 16:52:05 INFO - mkdir: /builds/slave/test/build/upload/logs 16:52:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.239783 ========= master_lag: -0.33 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-03-29 16:52:05.025445) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 16:52:05.028752) ========= 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.WeSBNJ8DkE/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.hGIenzHAJu/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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/Firefox/sha512/7f05def63c13331620bfe3b4ce3fa8b0f3d48416b3acf457f981107ff74435de68f39d6d7c505217f0020c1853b434e4b2eea96339a1e5c294d88d8882e255ca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e6403afc5509bcc35143b9755d2041540ca52c1b5fe644226f7eee80c922a7fd0d6e9441a0c32a8e61477ec1c91d73becab1d4806a0c9f39c58aef86f481aba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1888376dc352eef489ed56546623eefddfde9bf232890f9d5ecd2049dbb6d185184fb9667e71875bcd728955a994031e5fd0f8520b00287057282fba305229cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c655a8ccb2c1dcd37f13049fa722974e0ebc40348ab90e5072ce8a0ed452015ede0b8104142b3eed2f33c75ddd4163b2b6a67533d9ad6ad83982f5abb707b3a5"} build_url:https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011163 build_url: 'https://queue.taskcluster.net/v1/task/bL9sWD9WT82rGUWhS7axcg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7f05def63c13331620bfe3b4ce3fa8b0f3d48416b3acf457f981107ff74435de68f39d6d7c505217f0020c1853b434e4b2eea96339a1e5c294d88d8882e255ca", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e6403afc5509bcc35143b9755d2041540ca52c1b5fe644226f7eee80c922a7fd0d6e9441a0c32a8e61477ec1c91d73becab1d4806a0c9f39c58aef86f481aba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1888376dc352eef489ed56546623eefddfde9bf232890f9d5ecd2049dbb6d185184fb9667e71875bcd728955a994031e5fd0f8520b00287057282fba305229cf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c655a8ccb2c1dcd37f13049fa722974e0ebc40348ab90e5072ce8a0ed452015ede0b8104142b3eed2f33c75ddd4163b2b6a67533d9ad6ad83982f5abb707b3a5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 16:52:05.063651) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:52:05.063988) ========= 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.WeSBNJ8DkE/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.hGIenzHAJu/Listeners TMPDIR=/var/folders/g3/g2lxvbfd1c33ggtssv6lgc3r00000w/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.005271 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 16:52:05.113304) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 16:52:05.113649) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 16:52:05.113992) ========= ========= Total master_lag: 0.01 =========