builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0321 starttime: 1494503742.88 results: success (0) revision: 86754a7acc0e12cf3c575e9867958c2bacc8f465 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 04:55:42.879450) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 04:55:42.881326) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 04:55:42.881661) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 04:55:42.907562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 04:55:42.907948) ========= 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.MxiFK01Aau/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.llfId1VLD3/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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-05-11 04:55:42-- 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% 155M=0s 2017-05-11 04:55:43 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.167383 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 04:55:43.098361) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 04:55:43.098669) ========= 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.MxiFK01Aau/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.llfId1VLD3/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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.032077 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 04:55:43.152116) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-11 04:55:43.152446) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 86754a7acc0e12cf3c575e9867958c2bacc8f465 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 86754a7acc0e12cf3c575e9867958c2bacc8f465 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MxiFK01Aau/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.llfId1VLD3/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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-05-11 04:55:43,256 truncating revision to first 12 chars 2017-05-11 04:55:43,256 Setting DEBUG logging. 2017-05-11 04:55:43,256 attempt 1/10 2017-05-11 04:55:43,256 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/86754a7acc0e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 04:55:44,956 unpacking tar archive at: mozilla-central-86754a7acc0e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.982378 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-11 04:55:45.156940) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 04:55:45.157260) ========= script_repo_revision: 86754a7acc0e12cf3c575e9867958c2bacc8f465 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 04:55:45.157643) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 04:55:45.157962) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 04:55:45.177106) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-11 04:55:45.177480) ========= /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.MxiFK01Aau/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.llfId1VLD3/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:55:45 INFO - MultiFileLogger online at 20170511 04:55:45 in /builds/slave/test 04:55:45 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 04:55:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:55:45 INFO - {'append_to_log': False, 04:55:45 INFO - 'base_work_dir': '/builds/slave/test', 04:55:45 INFO - 'blob_upload_branch': 'Firefox', 04:55:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:55:45 INFO - 'branch': 'Firefox', 04:55:45 INFO - 'buildbot_json_path': 'buildprops.json', 04:55:45 INFO - 'config_files': ('talos/mac_config.py',), 04:55:45 INFO - 'default_actions': ('clobber', 04:55:45 INFO - 'read-buildbot-config', 04:55:45 INFO - 'download-and-extract', 04:55:45 INFO - 'populate-webroot', 04:55:45 INFO - 'create-virtualenv', 04:55:45 INFO - 'install', 04:55:45 INFO - 'run-tests'), 04:55:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:55:45 INFO - 'download_minidump_stackwalk': True, 04:55:45 INFO - 'download_symbols': 'ondemand', 04:55:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:55:45 INFO - 'tooltool.py': '/tools/tooltool.py', 04:55:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:55:45 INFO - '/tools/misc-python/virtualenv.py')}, 04:55:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:55:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:55:45 INFO - 'gecko_profile': False, 04:55:45 INFO - 'gecko_profile_interval': 0, 04:55:45 INFO - 'installer_path': 'installer.exe', 04:55:45 INFO - 'log_level': 'info', 04:55:45 INFO - 'log_name': 'talos', 04:55:45 INFO - 'log_to_console': True, 04:55:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:55:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:55:45 INFO - 'opt_config_files': (), 04:55:45 INFO - 'pip_index': False, 04:55:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:55:45 INFO - 'cmd': ('bash', 04:55:45 INFO - '-c', 04:55:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:55:45 INFO - 'enabled': True, 04:55:45 INFO - 'halt_on_failure': False, 04:55:45 INFO - 'name': 'check_screen_resolution'},), 04:55:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:55:45 INFO - 'cmd': ('bash', 04:55:45 INFO - '-c', 04:55:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:55:45 INFO - 'enabled': True, 04:55:45 INFO - 'halt_on_failure': False, 04:55:45 INFO - 'name': 'check_screen_resolution'},), 04:55:45 INFO - 'run_cmd_checks_enabled': True, 04:55:45 INFO - 'suite': 'dromaeojs-e10s', 04:55:45 INFO - 'system_bits': '32', 04:55:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:55:45 INFO - 'title': 't-yosemite-r7-0321', 04:55:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:55:45 INFO - 'use_talos_json': True, 04:55:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:55:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:55:45 INFO - 'work_dir': 'build'} 04:55:45 INFO - [mozharness: 2017-05-11 11:55:45.392156Z] Running clobber step. 04:55:45 INFO - Running pre-action listener: _resource_record_pre_action 04:55:45 INFO - Running main action method: clobber 04:55:45 INFO - rmtree: /builds/slave/test/build 04:55:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:55:47 INFO - Running post-action listener: _resource_record_post_action 04:55:47 INFO - [mozharness: 2017-05-11 11:55:47.740486Z] Finished clobber step (success) 04:55:47 INFO - [mozharness: 2017-05-11 11:55:47.740615Z] Running read-buildbot-config step. 04:55:47 INFO - Running pre-action listener: _resource_record_pre_action 04:55:47 INFO - Running main action method: read_buildbot_config 04:55:47 INFO - Using buildbot properties: 04:55:47 INFO - { 04:55:47 INFO - "project": "", 04:55:47 INFO - "product": "firefox", 04:55:47 INFO - "who": "cbook@mozilla.com", 04:55:47 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 04:55:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:55:47 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 04:55:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:55:47 INFO - "stage_platform": "macosx64", 04:55:47 INFO - "basedir": "/builds/slave/test", 04:55:47 INFO - "buildnumber": 23, 04:55:47 INFO - "platform": "yosemite_r7", 04:55:47 INFO - "parent_task_id": "AyamvUt_QAavSWG7kabMzw", 04:55:47 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 04:55:47 INFO - "slavebuilddir": "test", 04:55:47 INFO - "taskId": "Q_n0U9vfS6Oa3Q0Bpgea6Q", 04:55:47 INFO - "branch": "mozilla-central", 04:55:47 INFO - "script_repo_revision": "production", 04:55:47 INFO - "revision": "86754a7acc0e12cf3c575e9867958c2bacc8f465", 04:55:47 INFO - "slavename": "t-yosemite-r7-0321", 04:55:47 INFO - "repo_path": "mozilla-central" 04:55:47 INFO - } 04:55:47 INFO - Finding installer, test and symbols from parent task. 04:55:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q_n0U9vfS6Oa3Q0Bpgea6Q'}, attempt #1 04:55:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q_n0U9vfS6Oa3Q0Bpgea6Q'}, attempt #1 04:55:49 INFO - Task dependencies: bohaCyX2RkKRAs8dZb63-A 04:55:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw'}, attempt #1 04:55:49 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:55:49 INFO - Set test_url: https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/test_packages.json 04:55:49 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/target.crashreporter-symbols.zip 04:55:49 INFO - Running post-action listener: _resource_record_post_action 04:55:49 INFO - [mozharness: 2017-05-11 11:55:49.499731Z] Finished read-buildbot-config step (success) 04:55:49 INFO - [mozharness: 2017-05-11 11:55:49.499869Z] Running download-and-extract step. 04:55:49 INFO - Running pre-action listener: _resource_record_pre_action 04:55:49 INFO - Running main action method: download_and_extract 04:55:49 INFO - mkdir: /builds/slave/test/build/tests 04:55: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')]} 04:55:49 INFO - trying https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:55:49 INFO - Downloading https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/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 04:55:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/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 04:55:50 INFO - Downloaded 1325 bytes. 04:55:50 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:55:50 INFO - Using the following test package requirements: 04:55:50 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:55:50 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:55:50 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:55:50 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:55:50 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:55:50 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:55:50 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:55:50 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:55:50 INFO - u'jsshell-mac.zip'], 04:55:50 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:55:50 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:55:50 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:55:50 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:55:50 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:55:50 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:55:50 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:55:50 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:55:50 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:55:50 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:55:50 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:55:50 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:55:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:55:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:55:50 INFO - Fetch https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:55:52 INFO - Content-Length response header: 52707361 04:55:52 INFO - Bytes received: 52707361 04:56:08 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 04:56:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:56:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:56:08 INFO - Fetch https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:56:10 INFO - Content-Length response header: 52707361 04:56:10 INFO - Bytes received: 52707361 04:56:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:56:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:56:26 INFO - Fetch https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:56:27 INFO - Content-Length response header: 14409352 04:56:27 INFO - Bytes received: 14409352 04:56:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:28 INFO - trying https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:56:28 INFO - Downloading https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:56:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/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 04:56:30 INFO - Downloaded 75536451 bytes. 04:56:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:56:30 INFO - mkdir: /builds/slave/test/properties 04:56:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:56:30 INFO - Writing to file /builds/slave/test/properties/build_url 04:56:30 INFO - Contents: 04:56:30 INFO - build_url:https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:56:30 INFO - Running post-action listener: _resource_record_post_action 04:56:30 INFO - Running post-action listener: set_extra_try_arguments 04:56:30 INFO - [mozharness: 2017-05-11 11:56:30.224954Z] Finished download-and-extract step (success) 04:56:30 INFO - [mozharness: 2017-05-11 11:56:30.225081Z] Running populate-webroot step. 04:56:30 INFO - Running pre-action listener: _resource_record_pre_action 04:56:30 INFO - Running main action method: populate_webroot 04:56:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:56:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:56:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:56:30 INFO - '--tppagecycles', 04:56:30 INFO - '7'], 04:56:30 INFO - 'tests': ['tsvgm']}}, 04:56:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:56:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:56:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:56:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:56:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:56:30 INFO - 'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:56:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:56:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:56:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:56:30 INFO - 'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['damp', 'tps']}, 04:56:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:56:30 INFO - 'tests': ['damp', 'tps']}, 04:56:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['dromaeo_dom']}, 04:56:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:56:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:56:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:56:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['a11yr', 04:56:30 INFO - 'ts_paint', 04:56:30 INFO - 'tpaint', 04:56:30 INFO - 'sessionrestore', 04:56:30 INFO - 'sessionrestore_no_auto_restore', 04:56:30 INFO - 'tabpaint']}, 04:56:30 INFO - 'other-e10s': {'tests': ['a11yr', 04:56:30 INFO - 'ts_paint', 04:56:30 INFO - 'tpaint', 04:56:30 INFO - 'sessionrestore', 04:56:30 INFO - 'sessionrestore_no_auto_restore', 04:56:30 INFO - 'tabpaint']}, 04:56:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:56:30 INFO - 'ts_paint', 04:56:30 INFO - 'tpaint', 04:56:30 INFO - 'sessionrestore', 04:56:30 INFO - 'sessionrestore_no_auto_restore', 04:56:30 INFO - 'tabpaint']}, 04:56:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:56:30 INFO - 'ts_paint', 04:56:30 INFO - 'tpaint', 04:56:30 INFO - 'sessionrestore', 04:56:30 INFO - 'sessionrestore_no_auto_restore', 04:56:30 INFO - 'tabpaint']}, 04:56:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['a11yr', 04:56:30 INFO - 'ts_paint', 04:56:30 INFO - 'tpaint', 04:56:30 INFO - 'sessionrestore', 04:56:30 INFO - 'sessionrestore_no_auto_restore', 04:56:30 INFO - 'tabpaint']}, 04:56:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['a11yr', 04:56:30 INFO - 'ts_paint', 04:56:30 INFO - 'tpaint', 04:56:30 INFO - 'sessionrestore', 04:56:30 INFO - 'sessionrestore_no_auto_restore', 04:56:30 INFO - 'tabpaint']}, 04:56:30 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:56:30 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:56:30 INFO - 'bloom_basic_ref']}, 04:56:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['tsvgx', 04:56:30 INFO - 'tsvgr_opacity', 04:56:30 INFO - 'tart', 04:56:30 INFO - 'tscrollx', 04:56:30 INFO - 'cart', 04:56:30 INFO - 'tsvg_static']}, 04:56:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:56:30 INFO - 'tsvgr_opacity', 04:56:30 INFO - 'tart', 04:56:30 INFO - 'tscrollx', 04:56:30 INFO - 'cart', 04:56:30 INFO - 'tsvg_static']}, 04:56:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:56:30 INFO - 'talos_options': ['--disable-e10s'], 04:56:30 INFO - 'tests': ['tp5o']}, 04:56:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:56:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:56:30 INFO - 'talos_options': ['--disable-e10s', 04:56:30 INFO - '--xperf_path', 04:56:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:56:30 INFO - 'tests': ['tp5n']}, 04:56:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:56:30 INFO - 'talos_options': ['--xperf_path', 04:56:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:56:30 INFO - 'tests': ['tp5n']}}, 04:56:30 INFO - 'talos.zip': {'path': '', 04:56:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:56:30 INFO - Running post-action listener: _resource_record_post_action 04:56:30 INFO - [mozharness: 2017-05-11 11:56:30.246281Z] Finished populate-webroot step (success) 04:56:30 INFO - [mozharness: 2017-05-11 11:56:30.246404Z] Running create-virtualenv step. 04:56:30 INFO - Running pre-action listener: _resource_record_pre_action 04:56:30 INFO - Running main action method: create_virtualenv 04:56:30 INFO - Creating virtualenv /builds/slave/test/build/venv 04:56:30 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 04:56:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:56:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:56:30 INFO - Using real prefix '/tools/python27' 04:56:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:56:31 INFO - Installing distribute.............................................................................................................................................................................................done. 04:56:33 INFO - Installing pip.................done. 04:56:33 INFO - Return code: 0 04:56:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:56:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:56:34 INFO - Reading from file tmpfile_stdout 04:56:34 INFO - Output received: 04:56:34 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) 04:56:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:34 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 0x10e77ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e77d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb93458420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e48bd30>, '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 0x10e43edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e47fbe8>, '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.MxiFK01Aau/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.llfId1VLD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:56:34 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 04:56:34 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 04:56:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MxiFK01Aau/Render', 04:56:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:34 INFO - 'HOME': '/Users/cltbld', 04:56:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:34 INFO - 'LOGNAME': 'cltbld', 04:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:34 INFO - 'MOZ_NO_REMOTE': '1', 04:56:34 INFO - 'NO_EM_RESTART': '1', 04:56:34 INFO - 'PAGER': '/bin/cat', 04:56:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:34 INFO - 'PWD': '/builds/slave/test', 04:56:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:34 INFO - 'SHELL': '/bin/bash', 04:56:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llfId1VLD3/Listeners', 04:56:34 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:56:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:34 INFO - 'USER': 'cltbld', 04:56:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:34 INFO - 'XPC_FLAGS': '0x0', 04:56:34 INFO - 'XPC_SERVICE_NAME': '0', 04:56:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:34 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 04:56:34 INFO - Cleaning up... 04:56:34 INFO - Return code: 0 04:56:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:34 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 0x10e77ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e77d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb93458420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e48bd30>, '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 0x10e43edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e47fbe8>, '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.MxiFK01Aau/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.llfId1VLD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:56:34 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 04:56:34 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 04:56:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MxiFK01Aau/Render', 04:56:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:34 INFO - 'HOME': '/Users/cltbld', 04:56:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:34 INFO - 'LOGNAME': 'cltbld', 04:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:34 INFO - 'MOZ_NO_REMOTE': '1', 04:56:34 INFO - 'NO_EM_RESTART': '1', 04:56:34 INFO - 'PAGER': '/bin/cat', 04:56:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:34 INFO - 'PWD': '/builds/slave/test', 04:56:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:34 INFO - 'SHELL': '/bin/bash', 04:56:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llfId1VLD3/Listeners', 04:56:34 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:56:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:34 INFO - 'USER': 'cltbld', 04:56:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:34 INFO - 'XPC_FLAGS': '0x0', 04:56:34 INFO - 'XPC_SERVICE_NAME': '0', 04:56:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:34 INFO - Downloading/unpacking psutil>=3.1.1 04:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:56:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:56:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:56:37 INFO - Installing collected packages: psutil 04:56:37 INFO - Running setup.py install for psutil 04:56:37 INFO - building 'psutil._psutil_osx' extension 04:56:37 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 04:56:37 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 04:56:37 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 04:56:37 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 04:56:37 INFO - building 'psutil._psutil_posix' extension 04:56:37 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 04:56:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:56:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:56:37 INFO - ^ 04:56:37 INFO - 1 warning generated. 04:56:37 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 04:56:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:56:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:56:38 INFO - Successfully installed psutil 04:56:38 INFO - Cleaning up... 04:56:38 INFO - Return code: 0 04:56:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e77ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e77d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb93458420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e48bd30>, '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 0x10e43edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e47fbe8>, '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.MxiFK01Aau/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.llfId1VLD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:56:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:56:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:56:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MxiFK01Aau/Render', 04:56:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:38 INFO - 'HOME': '/Users/cltbld', 04:56:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:38 INFO - 'LOGNAME': 'cltbld', 04:56:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:38 INFO - 'MOZ_NO_REMOTE': '1', 04:56:38 INFO - 'NO_EM_RESTART': '1', 04:56:38 INFO - 'PAGER': '/bin/cat', 04:56:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:38 INFO - 'PWD': '/builds/slave/test', 04:56:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:38 INFO - 'SHELL': '/bin/bash', 04:56:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llfId1VLD3/Listeners', 04:56:38 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:56:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:38 INFO - 'USER': 'cltbld', 04:56:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:38 INFO - 'XPC_FLAGS': '0x0', 04:56:38 INFO - 'XPC_SERVICE_NAME': '0', 04:56:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:56: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 04:56: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 04:56: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 04:56: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 04:56: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 04:56: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 04:56:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:56:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:56:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:56:40 INFO - Installing collected packages: mozsystemmonitor 04:56:40 INFO - Running setup.py install for mozsystemmonitor 04:56:40 INFO - Successfully installed mozsystemmonitor 04:56:40 INFO - Cleaning up... 04:56:40 INFO - Return code: 0 04:56:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:41 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 0x10e77ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e77d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb93458420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e48bd30>, '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 0x10e43edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e47fbe8>, '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.MxiFK01Aau/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.llfId1VLD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:56:41 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 04:56:41 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 04:56:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MxiFK01Aau/Render', 04:56:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:41 INFO - 'HOME': '/Users/cltbld', 04:56:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:41 INFO - 'LOGNAME': 'cltbld', 04:56:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:41 INFO - 'MOZ_NO_REMOTE': '1', 04:56:41 INFO - 'NO_EM_RESTART': '1', 04:56:41 INFO - 'PAGER': '/bin/cat', 04:56:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:41 INFO - 'PWD': '/builds/slave/test', 04:56:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:41 INFO - 'SHELL': '/bin/bash', 04:56:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llfId1VLD3/Listeners', 04:56:41 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:56:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:41 INFO - 'USER': 'cltbld', 04:56:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:41 INFO - 'XPC_FLAGS': '0x0', 04:56:41 INFO - 'XPC_SERVICE_NAME': '0', 04:56:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:41 INFO - Downloading/unpacking jsonschema==2.5.1 04:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:56:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:56: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 04:56: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 04:56: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 04:56: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 04:56: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 04:56: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 04:56:43 INFO - Downloading functools32-3.2.3-2.tar.gz 04:56:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:56:43 INFO - warning: no files found matching '*.txt' 04:56:43 INFO - no previously-included directories found matching 'build' 04:56:43 INFO - no previously-included directories found matching 'dist' 04:56:43 INFO - no previously-included directories found matching '.git*' 04:56:43 INFO - Installing collected packages: jsonschema, functools32 04:56:43 INFO - Running setup.py install for functools32 04:56:43 INFO - warning: no files found matching '*.txt' 04:56:43 INFO - no previously-included directories found matching 'build' 04:56:43 INFO - no previously-included directories found matching 'dist' 04:56:43 INFO - no previously-included directories found matching '.git*' 04:56:43 INFO - Successfully installed jsonschema functools32 04:56:43 INFO - Cleaning up... 04:56:43 INFO - Return code: 0 04:56:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:56:43 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')]} 04:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:43 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')]} 04:56:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:43 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 0x10e77ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e77d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb93458420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e48bd30>, '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 0x10e43edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e47fbe8>, '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.MxiFK01Aau/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.llfId1VLD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:56:43 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 04:56:43 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 04:56:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MxiFK01Aau/Render', 04:56:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:43 INFO - 'HOME': '/Users/cltbld', 04:56:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:43 INFO - 'LOGNAME': 'cltbld', 04:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:43 INFO - 'MOZ_NO_REMOTE': '1', 04:56:43 INFO - 'NO_EM_RESTART': '1', 04:56:43 INFO - 'PAGER': '/bin/cat', 04:56:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:43 INFO - 'PWD': '/builds/slave/test', 04:56:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:43 INFO - 'SHELL': '/bin/bash', 04:56:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llfId1VLD3/Listeners', 04:56:43 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:56:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:43 INFO - 'USER': 'cltbld', 04:56:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:43 INFO - 'XPC_FLAGS': '0x0', 04:56:43 INFO - 'XPC_SERVICE_NAME': '0', 04:56:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:56:43 INFO - Cleaning up... 04:56:43 INFO - Return code: 0 04:56:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:56:43 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')]} 04:56:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:43 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')]} 04:56:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:43 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 0x10e77ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e77d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb93458420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e48bd30>, '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 0x10e43edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e47fbe8>, '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.MxiFK01Aau/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.llfId1VLD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:56:43 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 04:56:43 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 04:56:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MxiFK01Aau/Render', 04:56:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:43 INFO - 'HOME': '/Users/cltbld', 04:56:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:43 INFO - 'LOGNAME': 'cltbld', 04:56:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:43 INFO - 'MOZ_NO_REMOTE': '1', 04:56:43 INFO - 'NO_EM_RESTART': '1', 04:56:43 INFO - 'PAGER': '/bin/cat', 04:56:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:43 INFO - 'PWD': '/builds/slave/test', 04:56:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:43 INFO - 'SHELL': '/bin/bash', 04:56:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llfId1VLD3/Listeners', 04:56:43 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:56:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:43 INFO - 'USER': 'cltbld', 04:56:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:43 INFO - 'XPC_FLAGS': '0x0', 04:56:43 INFO - 'XPC_SERVICE_NAME': '0', 04:56:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:44 INFO - Downloading/unpacking blobuploader==1.2.4 04:56:44 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 04:56:44 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 04:56:44 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 04:56:44 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 04:56:44 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 04:56:44 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 04:56:45 INFO - Downloading blobuploader-1.2.4.tar.gz 04:56:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:56:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:56:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:46 INFO - Downloading docopt-0.6.1.tar.gz 04:56:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:56:46 INFO - Installing collected packages: blobuploader, requests, docopt 04:56:46 INFO - Running setup.py install for blobuploader 04:56:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:56:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:56:46 INFO - Running setup.py install for requests 04:56:47 INFO - Running setup.py install for docopt 04:56:47 INFO - Successfully installed blobuploader requests docopt 04:56:47 INFO - Cleaning up... 04:56:47 INFO - Return code: 0 04:56:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e77ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e77d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb93458420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e48bd30>, '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 0x10e43edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e47fbe8>, '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.MxiFK01Aau/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.llfId1VLD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:56:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:56:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:56:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MxiFK01Aau/Render', 04:56:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:47 INFO - 'HOME': '/Users/cltbld', 04:56:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:47 INFO - 'LOGNAME': 'cltbld', 04:56:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:47 INFO - 'MOZ_NO_REMOTE': '1', 04:56:47 INFO - 'NO_EM_RESTART': '1', 04:56:47 INFO - 'PAGER': '/bin/cat', 04:56:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:47 INFO - 'PWD': '/builds/slave/test', 04:56:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:47 INFO - 'SHELL': '/bin/bash', 04:56:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llfId1VLD3/Listeners', 04:56:47 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:56:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:47 INFO - 'USER': 'cltbld', 04:56:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:47 INFO - 'XPC_FLAGS': '0x0', 04:56:47 INFO - 'XPC_SERVICE_NAME': '0', 04:56:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:56:47 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-4toZB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:56:47 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-rLnGxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:56:47 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-VcfMr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:56:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:56:48 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-3QOxjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:56:48 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-8hXhtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:56:48 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-V__v3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:56:48 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-E9RrCz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:56:48 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-8p9MaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:56:48 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Xgr35o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:56:48 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-IPNdhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:56:48 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-tkMpIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:56:48 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-SuXTyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:56:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:56:48 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-DJVlKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:56:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:56:49 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-AgzP8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:56:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:49 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-oNxL2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:56:49 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-3m1nUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:56:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:56:49 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-TOUv03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:56:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:56:49 INFO - Running setup.py install for manifestparser 04:56:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:56:49 INFO - Running setup.py install for mozcrash 04:56:49 INFO - Running setup.py install for mozdebug 04:56:49 INFO - Running setup.py install for mozdevice 04:56:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Running setup.py install for mozfile 04:56:50 INFO - Running setup.py install for mozhttpd 04:56:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Running setup.py install for mozinfo 04:56:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Running setup.py install for mozInstall 04:56:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Running setup.py install for mozleak 04:56:50 INFO - Running setup.py install for mozlog 04:56:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:56:50 INFO - Running setup.py install for moznetwork 04:56:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:56:51 INFO - Running setup.py install for mozprocess 04:56:51 INFO - Running setup.py install for mozprofile 04:56:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:56:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:56:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:56:51 INFO - Running setup.py install for mozrunner 04:56:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:56:51 INFO - Running setup.py install for mozscreenshot 04:56:51 INFO - Running setup.py install for moztest 04:56:51 INFO - Running setup.py install for mozversion 04:56:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:56:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:56:51 INFO - Cleaning up... 04:56:52 INFO - Return code: 0 04:56:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:56:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e77ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e77d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb93458420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e48bd30>, '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 0x10e43edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e47fbe8>, '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.MxiFK01Aau/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.llfId1VLD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:56:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:56:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:56:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MxiFK01Aau/Render', 04:56:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:52 INFO - 'HOME': '/Users/cltbld', 04:56:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:52 INFO - 'LOGNAME': 'cltbld', 04:56:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:52 INFO - 'MOZ_NO_REMOTE': '1', 04:56:52 INFO - 'NO_EM_RESTART': '1', 04:56:52 INFO - 'PAGER': '/bin/cat', 04:56:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:52 INFO - 'PWD': '/builds/slave/test', 04:56:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:52 INFO - 'SHELL': '/bin/bash', 04:56:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llfId1VLD3/Listeners', 04:56:52 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:56:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:52 INFO - 'USER': 'cltbld', 04:56:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:52 INFO - 'XPC_FLAGS': '0x0', 04:56:52 INFO - 'XPC_SERVICE_NAME': '0', 04:56:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:56:52 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Er_WCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:56:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:56:52 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-zgWNyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:56:52 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)) 04:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:56:52 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-QAFw7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:56:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:56:52 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-CaenOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:56:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:56:52 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-wZ7WsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:56:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:56:52 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-0fr1un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:56:52 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)) 04:56:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:56:52 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-jBbiSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:56:53 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-DiM9Ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:56:53 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Im1cJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:56:53 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-_16zP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:56:53 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-9c_iZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:56:53 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-0sxg_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:56:53 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)) 04:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:56:53 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-LrUQmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:56:53 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-3Rt4Cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:56:53 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)) 04:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:53 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-QKS6d3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:53 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)) 04:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:56:53 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-KruFhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:56:53 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)) 04:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:56:53 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-HcUf_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:56:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:56:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:55 INFO - Downloading blessings-1.6.tar.gz 04:56:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:56:55 INFO - Installing collected packages: blessings 04:56:55 INFO - Running setup.py install for blessings 04:56:55 INFO - Successfully installed blessings 04:56:55 INFO - Cleaning up... 04:56:55 INFO - Return code: 0 04:56:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:56:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:56:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:56:56 INFO - Reading from file tmpfile_stdout 04:56:56 INFO - Current package versions: 04:56:56 INFO - blessings == 1.6 04:56:56 INFO - blobuploader == 1.2.4 04:56:56 INFO - docopt == 0.6.1 04:56:56 INFO - functools32 == 3.2.3-2 04:56:56 INFO - jsonschema == 2.5.1 04:56:56 INFO - manifestparser == 1.1 04:56:56 INFO - mozInstall == 1.12 04:56:56 INFO - mozcrash == 1.0 04:56:56 INFO - mozdebug == 0.1 04:56:56 INFO - mozdevice == 0.50 04:56:56 INFO - mozfile == 1.2 04:56:56 INFO - mozhttpd == 0.7 04:56:56 INFO - mozinfo == 0.9 04:56:56 INFO - mozleak == 0.1 04:56:56 INFO - mozlog == 3.4 04:56:56 INFO - moznetwork == 0.27 04:56:56 INFO - mozprocess == 0.25 04:56:56 INFO - mozprofile == 0.28 04:56:56 INFO - mozrunner == 6.13 04:56:56 INFO - mozscreenshot == 0.1 04:56:56 INFO - mozsystemmonitor == 0.3 04:56:56 INFO - moztest == 0.8 04:56:56 INFO - mozversion == 1.4 04:56:56 INFO - psutil == 3.1.1 04:56:56 INFO - requests == 1.2.3 04:56:56 INFO - wsgiref == 0.1.2 04:56:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:56 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')]} 04:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:56 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')]} 04:56:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:56 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 0x10e77ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e77d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb93458420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e48bd30>, '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 0x10e43edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e47fbe8>, '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.MxiFK01Aau/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.llfId1VLD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:56:56 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 04:56:56 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 04:56:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MxiFK01Aau/Render', 04:56:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:56 INFO - 'HOME': '/Users/cltbld', 04:56:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:56 INFO - 'LOGNAME': 'cltbld', 04:56:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:56 INFO - 'MOZ_NO_REMOTE': '1', 04:56:56 INFO - 'NO_EM_RESTART': '1', 04:56:56 INFO - 'PAGER': '/bin/cat', 04:56:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:56 INFO - 'PWD': '/builds/slave/test', 04:56:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:56 INFO - 'SHELL': '/bin/bash', 04:56:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llfId1VLD3/Listeners', 04:56:56 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:56:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:56 INFO - 'USER': 'cltbld', 04:56:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:56 INFO - 'XPC_FLAGS': '0x0', 04:56:56 INFO - 'XPC_SERVICE_NAME': '0', 04:56:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:56 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)) 04:56:56 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)) 04:56:56 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)) 04:56:56 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)) 04:56:56 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)) 04:56:56 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)) 04:56:56 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)) 04:56:56 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)) 04:56:56 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)) 04:56:56 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)) 04:56:56 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)) 04:56:56 INFO - Cleaning up... 04:56:56 INFO - Return code: 0 04:56:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:56:56 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')]} 04:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:56 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')]} 04:56:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:56 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 0x10e77ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e77d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb93458420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e48bd30>, '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 0x10e43edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e47fbe8>, '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.MxiFK01Aau/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.llfId1VLD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 04:56:56 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 04:56:56 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 04:56:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MxiFK01Aau/Render', 04:56:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:56 INFO - 'HOME': '/Users/cltbld', 04:56:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:56 INFO - 'LOGNAME': 'cltbld', 04:56:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:56 INFO - 'MOZ_NO_REMOTE': '1', 04:56:56 INFO - 'NO_EM_RESTART': '1', 04:56:56 INFO - 'PAGER': '/bin/cat', 04:56:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:56 INFO - 'PWD': '/builds/slave/test', 04:56:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:56 INFO - 'SHELL': '/bin/bash', 04:56:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llfId1VLD3/Listeners', 04:56:56 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:56:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:56 INFO - 'USER': 'cltbld', 04:56:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:56 INFO - 'XPC_FLAGS': '0x0', 04:56:56 INFO - 'XPC_SERVICE_NAME': '0', 04:56:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:56:56 INFO - Cleaning up... 04:56:56 INFO - Return code: 0 04:56:56 INFO - Running post-action listener: _resource_record_post_action 04:56:56 INFO - Running post-action listener: _start_resource_monitoring 04:56:56 INFO - Starting resource monitoring. 04:56:56 INFO - [mozharness: 2017-05-11 11:56:56.639259Z] Finished create-virtualenv step (success) 04:56:56 INFO - [mozharness: 2017-05-11 11:56:56.640200Z] Running install step. 04:56:56 INFO - Running pre-action listener: _resource_record_pre_action 04:56:56 INFO - Running main action method: install 04:56:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:56:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:56:56 INFO - Reading from file tmpfile_stdout 04:56:56 INFO - Detecting whether we're running mozinstall >=1.0... 04:56:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:56:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:56:57 INFO - Reading from file tmpfile_stdout 04:56:57 INFO - Output received: 04:56:57 INFO - Usage: mozinstall [options] installer 04:56:57 INFO - Options: 04:56:57 INFO - -h, --help show this help message and exit 04:56:57 INFO - -d DEST, --destination=DEST 04:56:57 INFO - Directory to install application into. [default: 04:56:57 INFO - "/builds/slave/test"] 04:56:57 INFO - --app=APP Application being installed. [default: firefox] 04:56:57 INFO - mkdir: /builds/slave/test/build/application 04:56:57 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'] 04:56:57 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 04:57:17 INFO - Reading from file tmpfile_stdout 04:57:17 INFO - Output received: 04:57:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:57:17 INFO - Running post-action listener: _resource_record_post_action 04:57:17 INFO - [mozharness: 2017-05-11 11:57:17.958342Z] Finished install step (success) 04:57:17 INFO - [mozharness: 2017-05-11 11:57:17.958517Z] Running run-tests step. 04:57:17 INFO - Running pre-action listener: _resource_record_pre_action 04:57:17 INFO - Running main action method: run_tests 04:57:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:57:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:57:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:57:17 INFO - 2017-05-11 04:57:17.972 screenresolution[1621:7504] starting screenresolution argv=screenresolution get 04:57:17 INFO - 2017-05-11 04:57:17.975 screenresolution[1621:7504] Display 0: 1600x1200x32@60 04:57:17 INFO - 2017-05-11 04:57:17.981 screenresolution[1622:7507] starting screenresolution argv=screenresolution list 04:57:17 INFO - Available Modes on Display 0 04:57:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:57:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:57:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:57:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:57:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:57:18 INFO - Intel Iris: 04:57:18 INFO - Chipset Model: Intel Iris 04:57:18 INFO - Type: GPU 04:57:18 INFO - Bus: Built-In 04:57:18 INFO - VRAM (Dynamic, Max): 1536 MB 04:57:18 INFO - Vendor: Intel (0x8086) 04:57:18 INFO - Device ID: 0x0a2e 04:57:18 INFO - Revision ID: 0x0009 04:57:18 INFO - Displays: 04:57:18 INFO - Display: 04:57:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:57:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:57:18 INFO - Display Serial Number: 1600x1200 60 04:57:18 INFO - Main Display: Yes 04:57:18 INFO - Mirror: Off 04:57:18 INFO - Online: Yes 04:57:18 INFO - Rotation: Supported 04:57:18 INFO - Return code: 0 04:57:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:57:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:57:18 INFO - Python 2.7.3 04:57:18 INFO - Return code: 0 04:57:18 INFO - grabbing minidump binary from tooltool 04:57:18 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')]} 04:57:18 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 0x10e48bd30>, '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 0x10e43edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e47fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:57:18 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 04:57:18 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 04:57:18 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 04:57:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:57:18 INFO - Return code: 0 04:57:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:57:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:57:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:57:18 INFO - ENV: RUST_BACKTRACE is now 1 04:57:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:57:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:57:18 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/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0321', '--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 04:57:18 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/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0321 --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 04:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MxiFK01Aau/Render', 04:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:57:18 INFO - 'HOME': '/Users/cltbld', 04:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:57:18 INFO - 'LOGNAME': 'cltbld', 04:57:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:57:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:57:18 INFO - 'MOZ_NO_REMOTE': '1', 04:57:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:57:18 INFO - 'NO_EM_RESTART': '1', 04:57:18 INFO - 'PAGER': '/bin/cat', 04:57:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:57:18 INFO - 'PWD': '/builds/slave/test', 04:57:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:57:18 INFO - 'RUST_BACKTRACE': '1', 04:57:18 INFO - 'SHELL': '/bin/bash', 04:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.llfId1VLD3/Listeners', 04:57:18 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 04:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:57:18 INFO - 'USER': 'cltbld', 04:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:57:18 INFO - 'XPC_FLAGS': '0x0', 04:57:18 INFO - 'XPC_SERVICE_NAME': '0', 04:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:57:18 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/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0321', '--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 04:57:18 INFO - mozversion application_buildid: 20170511034727 04:57:18 INFO - mozversion application_changeset: 86754a7acc0e12cf3c575e9867958c2bacc8f465 04:57:18 INFO - mozversion application_display_name: Nightly 04:57:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:57:18 INFO - mozversion application_name: Firefox 04:57:18 INFO - mozversion application_remotingname: firefox 04:57:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:57:18 INFO - mozversion application_vendor: Mozilla 04:57:18 INFO - mozversion application_version: 55.0a1 04:57:18 INFO - mozversion platform_buildid: 20170511034727 04:57:18 INFO - mozversion platform_changeset: 86754a7acc0e12cf3c575e9867958c2bacc8f465 04:57:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:57:18 INFO - mozversion platform_version: 55.0a1 04:57:18 INFO - using testdate: 1494503838 04:57:18 INFO - actual date: 1494503838 04:57:18 INFO - starting webserver on 'localhost:49240' 04:57:18 INFO - SUITE-START | Running 2 tests 04:57:18 INFO - TEST-START | dromaeo_css 04:57:18 INFO - operating with platform_type : mac_ 04:57:18 INFO - Initialising browser for dromaeo_css test... 04:57:18 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmp944i2V/profile http://localhost:49240/getInfo.html) 04:57:21 INFO - PID 1632 | 2017-05-11 04:57:21.021 plugin-container[1634:7682] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973b, name = 'com.apple.tsm.portname' 04:57:21 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:57:21 INFO - PID 1632 | 2017-05-11 04:57:21.024 plugin-container[1634:7682] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 04:57:21 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:57:21 INFO - PID 1632 | 2017-05-11 04:57:21.024 plugin-container[1634:7682] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:57:21 INFO - PID 1632 | __metrics Screen width/height:1600/1200 04:57:21 INFO - PID 1632 | colorDepth:24 04:57:21 INFO - PID 1632 | Browser inner width/height: 1024/691 04:57:21 INFO - PID 1632 | __metrics 04:57:21 INFO - PID 1632 | 2017-05-11 04:57:21.685 plugin-container[1635:7746] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 04:57:21 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:57:21 INFO - PID 1632 | 2017-05-11 04:57:21.685 plugin-container[1635:7746] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 04:57:21 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:57:21 INFO - PID 1632 | 2017-05-11 04:57:21.685 plugin-container[1635:7746] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:57:22 INFO - TEST-INFO | 1632: exit 0 04:57:22 INFO - Browser initialized. 04:57:22 INFO - Running cycle 1/1 for dromaeo_css test... 04:57:22 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmp944i2V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:57:24 INFO - PID 1636 | 2017-05-11 04:57:24.308 plugin-container[1637:7855] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 04:57:24 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:57:24 INFO - PID 1636 | 2017-05-11 04:57:24.309 plugin-container[1637:7855] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 04:57:24 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:57:24 INFO - PID 1636 | 2017-05-11 04:57:24.309 plugin-container[1637:7855] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:57:24 INFO - PID 1636 | 2017-05-11 04:57:24.768 plugin-container[1638:7898] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 04:57:24 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:57:24 INFO - PID 1636 | 2017-05-11 04:57:24.769 plugin-container[1638:7898] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 04:57:24 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:57:24 INFO - PID 1636 | 2017-05-11 04:57:24.769 plugin-container[1638:7898] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:57:34 INFO - PID 1636 | RSS: Main: 228655104 04:57:34 INFO - PID 1636 | 05:00:37 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 05:00:39 INFO - PID 1636 | RSS: Main: 251363328 05:00:39 INFO - PID 1636 | 05:03:41 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 05:03:41 INFO - PID 1636 | RSS: Main: 256729088 05:03:41 INFO - PID 1636 | 05:06:43 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 05:06:44 INFO - PID 1636 | RSS: Main: 266498048 05:06:44 INFO - PID 1636 | 05:09:45 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 05:09:46 INFO - PID 1636 | RSS: Main: 260509696 05:09:46 INFO - PID 1636 | 05:12:49 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 05:12:49 INFO - PID 1636 | RSS: Main: 264839168 05:12:49 INFO - PID 1636 | 05:15:51 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 05:15:51 INFO - PID 1636 | RSS: Main: 263700480 05:15:51 INFO - PID 1636 | 05:15:51 INFO - PID 1636 | __start_tp_report 05:15:51 INFO - PID 1636 | _x_x_mozilla_page_load 05:15:51 INFO - PID 1636 | _x_x_mozilla_page_load_details 05:15:51 INFO - PID 1636 | |i|pagename|runs| 05:15:51 INFO - PID 1636 | |0;dojo.html;2612.0335475873926;3539.61064282929;3621.1128273572976;3650.7079433645313;3684.244729830384;4979.900401991958;6114.419025732089;6167.660778657178;6256.937430625704;6265.780697675582;6125.784909797661;6186.783462578811;6211.378862113786;6229.563930524865;6275.968620156904;5395.7032363220005;5434.592405569579;5452.563794896399;5461.563074953994;5476.91784623231;4518.661100417466;5519.558435325184;5575.470330318613;5589;5613.382527921925;5352.090144675414;5435.239066530688;5482.068048431776;5541.750621222014;5551.72241387931;5475.780968761244;5509.338879334466;5515;5519.337679478448;5535.474129957647;5394.514493695587;5435.402105768402;5437.755301011464;5456.91814622781;5467.234587157801;6138.907916381258;6191.318954914956;6245.687715614246;6284.622922624657;6311.715972781236;6505.0892874997535;6596.076549283104;6686.93313066868;6690.364415380531;6725.495587830883;6782.423494003016;6821.829454263633;6825.146856642919;6828.2147553031045;6834.555753875982;6290.676742299921;6335.968320158369;6344.873102537969;6350.110984462179;6350.142730731292;6725.226598941086;6825.283345248777;6876.037354770337;6896.862062758817;6960.060391847137;6419.293877673452;6500.642464664489;6584.440322572635;6623.966880165568;6647.933520664828;6630.834229144213;6678.899816502756;6702.296258892746;6786.491013174032;6832.316768323228;6686.531942764057;6686.765963191265;6690.665466726644;6698.4641228701585;6700.698468568926;6435.388638079375;6517.706703198368;6537.771178008747;6698.7320507179165;6724.697388617524;4471.5081341052455;5153.974230128901;5352.116900711339;5357.5446087082255;5386.865328366822;4058.5941405859767;4651.511591282934;4711.293306004127;4740.478547359788;4768.904621907543;6367.204099487564;6370.140031095742;6373.044043393528;6393.392627700361;6394.616323020634;6473.320301368382;6703.363180497845;6767.052612634235;6787.694553745093;6891.931080689129;6315.652639104894;6363.268224154189;6369.9681501592195;6372.872542549123;6399.296077431479;5952.553558483131;5966.134910437962;5966.492848107872;5974;5980.671063091571;6755;6862.759803406857;6953.582785032914;6983.1620205575655;7041.471889608198;6803.93196068033;6833.829154271183;6834.931650683429;6885.655717214019;6915.412189963909;5751.7411716472025;7031.472639551952;7042.929570704227;7050.25972272904;7055.682494287872;5393.86515337104;5655.462731040545;6461.2892581816905;6710;6711.597304161766;6788.96605516989;6839.2476827547935;6872.10662613857;6872.347127022925;6872.41584465326;6584.078229048033;6615.66921653925;6636.867262654623;6639.3692599203;6660.56706314088;6309.400606942333;6424.582402143846;6492.188476440445;6545.574537655038;6647.268800431852;5082.085224659449;5770.451807078321;6456.096146539582;6462.192225971754;6466.256380516301;6351.904721429275;6452.580582262138;6524.575902566318;6552.803415897531;6657.500687448558;6459.160309159779;6486.416222539994;6509.316521765147;6511.120998665119;6519.4784417245555 05:15:51 INFO - PID 1636 | |1;ext.html;689.7861662884507;720.6288761287936;818.2799136759653;1210.8607510136337;1266.195965561868;37002.81498592507;37172.442413363824;37267.440988385126;37400;37449.812750936275;37786;38099.61900380995;38133.23733525328;38190.809045954804;38240;7711.344535714471;7850.882236766453;8233.38249631277;8375.329973602098;8445.493270403766;8415.200555947205;8446.746597602081;8525.488470691778;8532.61603227853;8540;8486.618102185386;8499.235068843804;8551.273141782925;8573.74278771638;8578.227959483645;8437.325013998865;8508.532030738306;8540.231379175842;8551.444156129832;8551.786205344853;7127.180374256975;8332.416730828845;8344.582770861432;8389.790255243606;8390.573602487591;37263.81368093169;37481.62518374809;37488.43767343492;37518.68684596053;37579.624203757885;3641.8725344612812;3662.8717994870053;3676.17286110623;3681.134933290701;3681.2821499807546;30227.244318891982;30523.542146867814;30616.08151755451;30685;30687.386252274828;3132.201288671397;3178.253110519026;3183.840807959593;3191.2500562367827;3205.5031470122167;289639.72521923477;291393;311892;320092.7967521149;321106;46739.5326046742;46767.76616116966;46871.29693054607;46897.53102468931;46983.5301646986;48237;48384;50692.746536267085;51319.48680513222;51687.96624067573;14275.64310892236;14380.424783008772;14420.567382978528;14423.85576144246;14433;11446.54213831454;11531.827022594667;11542;11553.711157221136;11602.70993225176;13362.331883405985;13514.932425338007;13529.699951008351;13575.32123393846;13649.658758531115;5188.273641690166;5552.167174923713;6250.74997000124;6270.6864656766975;6311.400416960381;896.7758060484879;938.2306508662831;998.7627713920765;1042.5464922758624;1083.664064140119;23591.646125308132;24605.63091553628;24748.886300116537;24766.752332476444;24783.5043299133;1137.9317240965568;1142.6229344316355;1168.480026388266;1173.8356630071796;1176.6999415149082;11117.332960022424;11465.197436179555;12163.756724865452;12165.574204902787;12759.61721148367;282891.1710882906;333785;337902;341569;342023;53043;53341.733291333294;53355;53379.19931201035;53626.19560706592;1502.894797364196;1545.6367753577674;1577.928993195286;1581.6915701438338;1585.9127747973973;18393.54016149607;23573.17493887706;24273;24357.51284974255;24464.941882264717;37307.81346093252;37342.06644833901;37395;37442.812785935894;37488.812555937046;280750;281781.1821881837;282951;284522;284523.5773821201;34898.65101348954;34934.82532587321;35052.82473587616;35073.64926350704;35119;42130.789346053076;42298.577014229464;43991.78004109959;44018.559814401444;44077.33883991679;24166.509326152038;29003.56494652624;29820.85089574538;30580.541291880196;30858.845705771328;30192.69807301899;30590.235244119074;30631.468426578136;30666.540001899542;30671.293406894638;36236.91289261326;36325;36347.90956271316;36394.636053640184;36430.27139457143;28657.283567910134;31220.68779312178;31336.843315783277;31497.8425107873;31504.684953150176;24075.51848963046;24290.392740181636;24540.87729561341;24564.894579744312;24570.754292456848 05:15:51 INFO - PID 1636 | |2;jquery.html;427.1435771278586;432.95670432956706;438.6227844054115;450.4594486616061;451.64545831522264;16040;20743.273985410513;21137.837418941952;21918.342449726493;21941.902904854775;21960;22003.889980550073;22369.55260894781;22430.663540046873;23623.76376236237;5961.791337303195;6865.729839979607;6898.099655843179;7043.823904402379;7073.504854660176;6818.352256535622;6971.268016858233;6996.796009257093;7001.264867188948;7005.29947005298;6645.3354664533645;6960.756373526928;6986;7025.086738723984;7043.436525078007;5935.910961335583;6655.900161497581;6868.313168683117;6937.028815965768;6945.826354341157;6736.528443008991;6867.622280774555;6869.278725733776;6884;6898.034275201476;28308;29115.41769164626;29450.410991780045;29758.404831903455;29863.70136298631;2354.105439932832;2388.474535602165;2397.3646983549374;2398.712154541449;2400.819938504619;18609.813901860944;19533;19696.11367488452;19876;20203.595928081504;2328.557574060923;2369.040538581877;2370.9762902370926;2388.880555972212;2398.8680622565753;152161.23919380442;164641;165349;167032.16483917623;167310.95320262;25110;25132.74867251304;25477.98088076456;25525.87237063803;25825.741742582333;42448.36327455091;42477.57522424798;42504.3624345635;42618.57381426146;42677.14645707068;9002.279817614575;9121.863172052424;9212;9456.57445414958;9489.288303377274;8737.60680769367;8845.027047024822;8887.73336799884;9033.277337812959;9036.728898133066;8208;8240.793980150424;8248.587570621445;8292.709755158541;8358.707445239388;2281.6349384098467;2289.107248173214;2289.1301305504135;2290.8854557272402;2474.3937735254617;929.1683942871227;968.7239136846106;980.568549838069;980.9215262778961;1007.7228762090484;20866.79133208679;21174.15303387878;21323.68014479784;21348.252811151535;21432;999.2555546118189;1003.4631472162504;1022.8158931392421;1028.7222449938624;1029.9485025748832;9225.538723063954;9625.51872406377;10398.68803935883;10865.83701244482;10964.122870170368;531978;533175;537072.8317225734;551163;561892;115526;115941;117397;127236;127755;1153.4694040741354;1178.5177568615018;1197.4252358867618;1201.5013769285824;1201.6815543880878;15455.61360965985;18101.728474072635;18184.818151818312;18279.36022239215;18525.44423667292;53586.73206633942;54022.72988635032;54878.80484780564;56179.31462056145;59051.409485904594;71802.28197717956;72189;72839;73155;73417;8747.956260218658;9020.684276050306;9054.230390416888;9136.6802161924;9166.17504424605;13901.513447029305;14544.27278636085;14549.272536372928;14999.775003375176;15037.849621503645;12030.398480076135;12157.574484892986;12325.198862073936;12377.443015064164;12464.688382790502;10895.455227238448;11339.46917166172;11427.942860285646;11546.13403994687;11967.401629918295;11982.760344793229;12366.010719142267;12487.625371238877;12499.437525311565;12675.556355527513;8894.288456923301;8922.107789221025;8924.821503570021;9249.861252081359;9324.906750932403;12026.819597706217;12052.51789928393;12098.75802483963;12352.135350525377;12402.13185077036 05:15:51 INFO - PID 1636 | |3;mootools.html;1414.8302203735552;1460.7679465776298;1461.8757405620515;1480.104536755263;1481.9851801481982;8828.337874659393;11382.2032457728;11477.770444591113;11755.118366122531;12045;11770.352630605314;11935.522579096825;11971.042316614672;11979.580714674988;11989.760204795899;2173.608750424923;2521.7982561395092;2555.744425557448;2566.217303722364;2569.1264969910226;2502.874856257189;2530.987345063272;2533.3793220660964;2534.1510593951048;2545.4272788622598;2491.800655947529;2533.07542746897;2536.6195070739445;2555.923322300334;2558.411565339973;2469.1604854349516;2502.399424138203;2516.358328626198;2517.924462266135;2518.3829961659326;2487.8756062197;2499.0253801017616;2508.034406753413;2511.309389917769;2517.5845985412384;11558.30650160993;11726.530938762438;11738.237014594024;11798.528058877635;11816.940915295454;5736.282964629421;6529.2817790043055;6770.221424536192;6777;6781.355771201728;3877.4765406670303;3884.2425726982965;3949.637375105584;4190.25538272432;4194.182134483777;1211.4790640024785;1241.900647948162;1284.627458037168;1287.1890708853457;1288.6262983734705;13967.37146828395;15673.608159795982;15998.680026399523;16046.679066418723;16195.757063644172;5274.709890955957;5658.519025882846;5700.686462244616;5836.970815145897;5853.414658534112;11970.521179152736;11976.640700778991;12001.099917506048;12015.279083255033;12103.757924841453;6559.672016399257;6565.277819439858;6567.9671601641685;6568.080468734381;6588.341165883373;6051.727672254671;6109.236345456818;6209.230369344806;6248.937510624926;6277.686115694197;4310.094880075157;4437.911241775147;4471.083427897338;4488.551144885485;4501.919539310607;1239.6652903716126;1256.9622911312674;1259.9433025513874;1269.0418733856063;1272.840894888139;662.3939095727355;684.8938414545753;820.5404973215019;836.5733475927199;844.5059640110604;6193.690315484298;6311.368863113652;6323.936760632427;6378.394052564999;6406.2953075161695;594.4352864778477;598.6677394046259;615.8152554233712;618.5793660310942;619.3001907844198;3937.684985201177;4150.813213405404;4586.885327866763;4629;4751.762411879392;15024.6477816997;15288.006279591791;15299.541013769605;15299.694006119815;15364.385424582892;14748.778768318483;15136.243187840564;15177.013494122846;15182.089074655556;15226.086434813726;7582.924170758332;8221.54781487012;8573.18554737299;8742.38803283764;8759.34304927133;6576.359843077821;7073.665690322078;7082.043924070184;7167.856642867009;7220.494565380373;10811.513481893177;12384;12875.905548028522;13111.14777539457;13128.868711313147;10117.949410253195;12317.260964342167;12694;12811.359432028548;13017.414216360476;9183.127602877716;9224.939131999638;9227.169554740105;9463.006384329821;9556.331056826233;11019.173561982723;11096.00135987765;11111.611093611686;11114.49984750672;11162.665120046411;8144.959275203586;8726.821879046502;8915.598798053974;9212.355135140477;9248.445093294424;8966.058563850702;8988.955055224682;9030.051844556227;9119.908800911906;9214.907850921405;8034.83930321402;8142.022957245168;8212.958935205286;8226.753197404087;8265.504069755834;5934.821955341346;5989.101634754822;6031.306399764083;6159.23009623797;6175.166352542349;6083.878322433615;6122.510199184144;6241.407066328691;6262.843428914313;6362.968185159045 05:15:51 INFO - PID 1636 | |4;prototype.html;301.9592355032071;375.2120546851612;394.29027750049914;407.13483846825494;408.4751094843127;6304.71628776705;6368.872622547547;6403.5197360198;6494.610323380594;6513.316101809313;6195.907061394083;6313.517402644128;6316.936830631692;6328.746850126001;6345.397187267214;3791.4218331534544;4043.65628921542;4579.542045795411;4605.4933957264675;4605.746683932382;3975.6223158799867;4397.538258482861;4527.253003254459;4564.109998550284;4617.6536759742985;4251.383549385338;4504.324351347292;4510.368548403226;4511.954880451186;4518.932216016762;4514.97742511287;4555.954440455586;4562.156001139767;4576.26779715244;4588.426446694163;3794.9810250948567;3861.2277544490935;3864.3623802072625;3873.992761881931;3905.7070719695853;4943.629227807929;5907.881842363128;6114.908276375858;6156.168917196166;6185.103160041814;5221.008008478377;5430.728463576806;5441.863953401157;5443;5475.945240547583;5578.721063946827;5612.943870561283;5731.312242530881;6181.474574661159;6771.424428923548;4390.538993405678;4395.692301538894;4402.603765661106;4405.713628614129;4415.293553031532;6457.447499512832;6520.543561950665;6943.653386892115;7215.711371545131;7350.264973502661;4430.18041662287;4448.977755111268;4465.303184789759;4465.575770301816;4484.798184081724;3958.980205098956;4529.456465224194;4543.613792827647;4545.36364908913;4562.954370456319;5256.684598924;5399.244105825188;5403.729813509309;5416.91874621881;5430.864228394322;6782.728690852409;6876.931230687629;6886.931130688729;6892;6905.965470172718;1463.66075041336;1495.9326830292446;1556.8754499640002;1562.9528216095243;1572.638293192572;939.9295052871062;960.2318145483586;978.2027647467434;1019.7654539455818;1024.6055268721595;5506.972465137648;6507.7071531781185;6521.47828173745;6534.771283005072;6541.313162118005;6532.673366331759;6812.216595091529;7084.25615310385;7122.358987691133;7125.89311160333;5552.7778888843995;6535.771248006297;6605.603663780189;6628.801135965929;6630.370114839083;3004.9248768780517;3586.4757478071715;3738.532683414573;3746.250749850041;3771.2268984858038;6514.967425162844;7313.158986716489;7398.149212840484;7436.144843342977;7467.1039475263315;6177.62934223948;7050.823729406806;7127.7924933780005;7383.261673832574;7478.364339031244;7383.889241661379;7401.222871598405;7426.774582193985;7462.328390444777;7781.5331080135375;5390.541803946709;6548;6884.4148247399535;7060.152781666233;7227.421806255592;5749.022666146849;6986;7061.858762824818;7065.364117229474;7112.110986126734;7074.221835597978;7317;7419.629018549159;7419.9595111344815;7426.405887529093;4540.568645978627;4550.158220729176;4557.749323787223;4561.270196768501;4572.908541829212;5896.882062358814;6331;7043.2604576518725;7190.928090719026;7211.639418029183;6217.658028808278;6472.255690595642;6897.827554311182;6909.343612356818;6933.37599616037;6865.288211156665;6867;6876.621785801829;6907.654617269016;6922.965385173042;6563.606183628997;6658.500612453989;6727.596344219362;6738.663066846736;6762.594244345159;5414.891702166014;5936.050231962865;6328.335524769833;6540.771073012612;6823.55646882951;5999.400059993965;6422.454091402096;6429;6624.735010599522;6690.765823196165 05:15:51 INFO - PID 1636 | |5;yui.html;708.7590219325435;818.8658707689848;829.767665053785;859.4155973937724;860.948343099414;14631;15203.467878624251;16461.341546338146;16945.830541694595;16991.320347186112;17156.22796974136;17980.280788768465;17983.640327193447;18072.638547229046;18145.54636134094;813.2639960835423;955.8709574207462;958.3387462650784;962.210986990668;971.6404930175845;981.4405788700443;984.3404918704449;984.7882705218377;985.2069084387066;988.6440881282732;1027.0807627173674;1031.793641271745;1032.3238278927315;1032.6592224565875;1033.3489901362132;1027.3579013116803;1029.6241871716845;1029.953652085658;1030.9690709278734;1031.20597140202;747.2452822649109;757.6363345594091;757.7196437318172;758.981025474362;759.0587671287619;17068.573285667833;17517.912410437868;17728;17766.200520976457;17786.733199002025;16768.413105541247;16848.241829117596;17061.91469042659;17210.56973575658;17447.389341373117;13708.3831227595;13789.034767566274;14113.858861411358;14122.22327771972;14129.505467308698;3303.091649796301;3378.45944648855;3381.22231886665;3386.204242003127;3386.847591858372;127533.72466275311;129815;130288.3485582566;131058.34470827585;132092.33953830265;10337.276390652582;12260.203086799329;12375.195612285173;12384.380780960915;12396.628101156979;20831.479213019797;20891.477713057295;21196.788032119788;21215.78784212169;21471.248506302203;7544.962275188589;7670.0795904491815;7705.807354816062;7729.265719756614;7729.497582657109;3851.2105018471148;3857.3056849767313;3943.11279962012;3965.484487016727;3967.940480892789;4124.711270211116;4257.978710106429;4264.125854199881;4270.145970805851;4280.593343632412;906.7325139083986;943.5706753427174;945.4232917920064;948.8671585977969;950.1543626172712;607.1651479216077;637.9681015949277;656.7701304543372;665.517499812632;687.2646268492765;11591.710207244718;12000.639980800415;12110.697232569257;12206.511739530497;12268.570600028956;624.188554878656;635.269440143829;636.3254949753276;649.3538928765909;652.9902051469231;5201.505856943584;5403.432639572867;5601.215829783833;6164.075388691643;6189.938100618936;112341.31488027686;112420;113182.86817131723;115274.423627883;116501;90089.64865527023;90180.09819901717;99153.50423247837;100034.4998275004;100434.4978275104;82845.58577207076;83765.74351384734;85036.57481712553;85304;85813.14186858176;10744.623938162127;11750.707422183725;12390.62828115158;12772.978161747224;12826.61520154397;31511.684883150876;33659.653613855175;36596.353164107146;38842;42073.73778786641;56516;61748.69125654343;66076.6696166516;73945.63027184829;74340.51318973482;14466;16143.354265829235;16311.184440777677;16848.410305639245;16854.07302598369;16390.75413868769;16669.91665041667;17885.10574471297;18082.186301616723;18262.452126436227;13674.521391751241;17004;17245.8790178643;17460.476185714448;17607.647847043245;16754.66490670204;16891.577710557336;16932.746008810125;16942.576435588715;17038.14809259507;16139.838601614303;16224;16715;16836.74744878803;16879.156042197596;11677.773833747335;11801.468933898166;12099.092568057255;12145.574904878287;12439.937800310941;11873.643790686294;12614.810777838524;13264.602061938158;13276.203427794364;13276.402561884932 05:15:51 INFO - PID 1636 | __end_tp_report 05:15:51 INFO - PID 1636 | __start_cc_report 05:15:51 INFO - PID 1636 | _x_x_mozilla_cycle_collect,405 05:15:51 INFO - PID 1636 | __end_cc_report 05:15:51 INFO - PID 1636 | __startTimestamp1494504951848__endTimestamp 05:15:51 INFO - PID 1636 | 05:15:51 INFO - PID 1636 | ------- Summary: start ------- 05:15:51 INFO - PID 1636 | Number of tests: 6 05:15:51 INFO - PID 1636 | 05:15:51 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6157.03 Median:6419.29 stddev:789.57 (12.3%) stddev-sans-first:741.40 05:15:51 INFO - PID 1636 | Values: 2612.0 3539.6 3621.1 3650.7 3684.2 4979.9 6114.4 6167.7 6256.9 6265.8 6125.8 6186.8 6211.4 6229.6 6276.0 5395.7 5434.6 5452.6 5461.6 5476.9 4518.7 5519.6 5575.5 5589.0 5613.4 5352.1 5435.2 5482.1 5541.8 5551.7 5475.8 5509.3 5515.0 5519.3 5535.5 5394.5 5435.4 5437.8 5456.9 5467.2 6138.9 6191.3 6245.7 6284.6 6311.7 6505.1 6596.1 6686.9 6690.4 6725.5 6782.4 6821.8 6825.1 6828.2 6834.6 6290.7 6336.0 6344.9 6350.1 6350.1 6725.2 6825.3 6876.0 6896.9 6960.1 6419.3 6500.6 6584.4 6624.0 6647.9 6630.8 6678.9 6702.3 6786.5 6832.3 6686.5 6686.8 6690.7 6698.5 6700.7 6435.4 6517.7 6537.8 6698.7 6724.7 4471.5 5154.0 5352.1 5357.5 5386.9 4058.6 4651.5 4711.3 4740.5 4768.9 6367.2 6370.1 6373.0 6393.4 6394.6 6473.3 6703.4 6767.1 6787.7 6891.9 6315.7 6363.3 6370.0 6372.9 6399.3 5952.6 5966.1 5966.5 5974.0 5980.7 6755.0 6862.8 6953.6 6983.2 7041.5 6803.9 6833.8 6834.9 6885.7 6915.4 5751.7 7031.5 7042.9 7050.3 7055.7 5393.9 5655.5 6461.3 6710.0 6711.6 6789.0 6839.2 6872.1 6872.3 6872.4 6584.1 6615.7 6636.9 6639.4 6660.6 6309.4 6424.6 6492.2 6545.6 6647.3 5082.1 5770.5 6456.1 6462.2 6466.3 6351.9 6452.6 6524.6 6552.8 6657.5 6459.2 6486.4 6509.3 6511.1 6519.5 05:15:51 INFO - PID 1636 | 05:15:51 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:45439.94 Median:34993.83 stddev:80496.54 (230.0%) stddev-sans-first:80652.23 05:15:51 INFO - PID 1636 | Values: 689.8 720.6 818.3 1210.9 1266.2 37002.8 37172.4 37267.4 37400.0 37449.8 37786.0 38099.6 38133.2 38190.8 38240.0 7711.3 7850.9 8233.4 8375.3 8445.5 8415.2 8446.7 8525.5 8532.6 8540.0 8486.6 8499.2 8551.3 8573.7 8578.2 8437.3 8508.5 8540.2 8551.4 8551.8 7127.2 8332.4 8344.6 8389.8 8390.6 37263.8 37481.6 37488.4 37518.7 37579.6 3641.9 3662.9 3676.2 3681.1 3681.3 30227.2 30523.5 30616.1 30685.0 30687.4 3132.2 3178.3 3183.8 3191.3 3205.5 289639.7 291393.0 311892.0 320092.8 321106.0 46739.5 46767.8 46871.3 46897.5 46983.5 48237.0 48384.0 50692.7 51319.5 51688.0 14275.6 14380.4 14420.6 14423.9 14433.0 11446.5 11531.8 11542.0 11553.7 11602.7 13362.3 13514.9 13529.7 13575.3 13649.7 5188.3 5552.2 6250.7 6270.7 6311.4 896.8 938.2 998.8 1042.5 1083.7 23591.6 24605.6 24748.9 24766.8 24783.5 1137.9 1142.6 1168.5 1173.8 1176.7 11117.3 11465.2 12163.8 12165.6 12759.6 282891.2 333785.0 337902.0 341569.0 342023.0 53043.0 53341.7 53355.0 53379.2 53626.2 1502.9 1545.6 1577.9 1581.7 1585.9 18393.5 23573.2 24273.0 24357.5 24464.9 37307.8 37342.1 37395.0 37442.8 37488.8 280750.0 281781.2 282951.0 284522.0 284523.6 34898.7 34934.8 35052.8 35073.6 35119.0 42130.8 42298.6 43991.8 44018.6 44077.3 24166.5 29003.6 29820.9 30580.5 30858.8 30192.7 30590.2 30631.5 30666.5 30671.3 36236.9 36325.0 36347.9 36394.6 36430.3 28657.3 31220.7 31336.8 31497.8 31504.7 24075.5 24290.4 24540.9 24564.9 24570.8 05:15:51 INFO - PID 1636 | 05:15:51 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:36569.04 Median:25501.93 stddev:92079.04 (361.1%) stddev-sans-first:92297.36 05:15:51 INFO - PID 1636 | Values: 427.1 433.0 438.6 450.5 451.6 16040.0 20743.3 21137.8 21918.3 21941.9 21960.0 22003.9 22369.6 22430.7 23623.8 5961.8 6865.7 6898.1 7043.8 7073.5 6818.4 6971.3 6996.8 7001.3 7005.3 6645.3 6960.8 6986.0 7025.1 7043.4 5935.9 6655.9 6868.3 6937.0 6945.8 6736.5 6867.6 6869.3 6884.0 6898.0 28308.0 29115.4 29450.4 29758.4 29863.7 2354.1 2388.5 2397.4 2398.7 2400.8 18609.8 19533.0 19696.1 19876.0 20203.6 2328.6 2369.0 2371.0 2388.9 2398.9 152161.2 164641.0 165349.0 167032.2 167311.0 25110.0 25132.7 25478.0 25525.9 25825.7 42448.4 42477.6 42504.4 42618.6 42677.1 9002.3 9121.9 9212.0 9456.6 9489.3 8737.6 8845.0 8887.7 9033.3 9036.7 8208.0 8240.8 8248.6 8292.7 8358.7 2281.6 2289.1 2289.1 2290.9 2474.4 929.2 968.7 980.6 980.9 1007.7 20866.8 21174.2 21323.7 21348.3 21432.0 999.3 1003.5 1022.8 1028.7 1029.9 9225.5 9625.5 10398.7 10865.8 10964.1 531978.0 533175.0 537072.8 551163.0 561892.0 115526.0 115941.0 117397.0 127236.0 127755.0 1153.5 1178.5 1197.4 1201.5 1201.7 15455.6 18101.7 18184.8 18279.4 18525.4 53586.7 54022.7 54878.8 56179.3 59051.4 71802.3 72189.0 72839.0 73155.0 73417.0 8748.0 9020.7 9054.2 9136.7 9166.2 13901.5 14544.3 14549.3 14999.8 15037.8 12030.4 12157.6 12325.2 12377.4 12464.7 10895.5 11339.5 11427.9 11546.1 11967.4 11982.8 12366.0 12487.6 12499.4 12675.6 8894.3 8922.1 8924.8 9249.9 9324.9 12026.8 12052.5 12098.8 12352.1 12402.1 05:15:51 INFO - PID 1636 | 05:15:51 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:7043.97 Median:3910.96 stddev:4404.46 (112.6%) stddev-sans-first:4396.50 05:15:51 INFO - PID 1636 | Values: 1414.8 1460.8 1461.9 1480.1 1482.0 8828.3 11382.2 11477.8 11755.1 12045.0 11770.4 11935.5 11971.0 11979.6 11989.8 2173.6 2521.8 2555.7 2566.2 2569.1 2502.9 2531.0 2533.4 2534.2 2545.4 2491.8 2533.1 2536.6 2555.9 2558.4 2469.2 2502.4 2516.4 2517.9 2518.4 2487.9 2499.0 2508.0 2511.3 2517.6 11558.3 11726.5 11738.2 11798.5 11816.9 5736.3 6529.3 6770.2 6777.0 6781.4 3877.5 3884.2 3949.6 4190.3 4194.2 1211.5 1241.9 1284.6 1287.2 1288.6 13967.4 15673.6 15998.7 16046.7 16195.8 5274.7 5658.5 5700.7 5837.0 5853.4 11970.5 11976.6 12001.1 12015.3 12103.8 6559.7 6565.3 6568.0 6568.1 6588.3 6051.7 6109.2 6209.2 6248.9 6277.7 4310.1 4437.9 4471.1 4488.6 4501.9 1239.7 1257.0 1259.9 1269.0 1272.8 662.4 684.9 820.5 836.6 844.5 6193.7 6311.4 6323.9 6378.4 6406.3 594.4 598.7 615.8 618.6 619.3 3937.7 4150.8 4586.9 4629.0 4751.8 15024.6 15288.0 15299.5 15299.7 15364.4 14748.8 15136.2 15177.0 15182.1 15226.1 7582.9 8221.5 8573.2 8742.4 8759.3 6576.4 7073.7 7082.0 7167.9 7220.5 10811.5 12384.0 12875.9 13111.1 13128.9 10117.9 12317.3 12694.0 12811.4 13017.4 9183.1 9224.9 9227.2 9463.0 9556.3 11019.2 11096.0 11111.6 11114.5 11162.7 8145.0 8726.8 8915.6 9212.4 9248.4 8966.1 8989.0 9030.1 9119.9 9214.9 8034.8 8142.0 8213.0 8226.8 8265.5 5934.8 5989.1 6031.3 6159.2 6175.2 6083.9 6122.5 6241.4 6262.8 6363.0 05:15:51 INFO - PID 1636 | 05:15:51 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5467.52 Median:6206.78 stddev:1777.86 (28.6%) stddev-sans-first:1740.05 05:15:51 INFO - PID 1636 | Values: 302.0 375.2 394.3 407.1 408.5 6304.7 6368.9 6403.5 6494.6 6513.3 6195.9 6313.5 6316.9 6328.7 6345.4 3791.4 4043.7 4579.5 4605.5 4605.7 3975.6 4397.5 4527.3 4564.1 4617.7 4251.4 4504.3 4510.4 4512.0 4518.9 4515.0 4556.0 4562.2 4576.3 4588.4 3795.0 3861.2 3864.4 3874.0 3905.7 4943.6 5907.9 6114.9 6156.2 6185.1 5221.0 5430.7 5441.9 5443.0 5475.9 5578.7 5612.9 5731.3 6181.5 6771.4 4390.5 4395.7 4402.6 4405.7 4415.3 6457.4 6520.5 6943.7 7215.7 7350.3 4430.2 4449.0 4465.3 4465.6 4484.8 3959.0 4529.5 4543.6 4545.4 4563.0 5256.7 5399.2 5403.7 5416.9 5430.9 6782.7 6876.9 6886.9 6892.0 6906.0 1463.7 1495.9 1556.9 1563.0 1572.6 939.9 960.2 978.2 1019.8 1024.6 5507.0 6507.7 6521.5 6534.8 6541.3 6532.7 6812.2 7084.3 7122.4 7125.9 5552.8 6535.8 6605.6 6628.8 6630.4 3004.9 3586.5 3738.5 3746.3 3771.2 6515.0 7313.2 7398.1 7436.1 7467.1 6177.6 7050.8 7127.8 7383.3 7478.4 7383.9 7401.2 7426.8 7462.3 7781.5 5390.5 6548.0 6884.4 7060.2 7227.4 5749.0 6986.0 7061.9 7065.4 7112.1 7074.2 7317.0 7419.6 7420.0 7426.4 4540.6 4550.2 4557.7 4561.3 4572.9 5896.9 6331.0 7043.3 7190.9 7211.6 6217.7 6472.3 6897.8 6909.3 6933.4 6865.3 6867.0 6876.6 6907.7 6923.0 6563.6 6658.5 6727.6 6738.7 6762.6 5414.9 5936.1 6328.3 6540.8 6823.6 5999.4 6422.5 6429.0 6624.7 6690.8 05:15:51 INFO - PID 1636 | 05:15:51 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:22462.91 Median:17932.69 stddev:32616.23 (181.9%) stddev-sans-first:32666.82 05:15:51 INFO - PID 1636 | Values: 708.8 818.9 829.8 859.4 860.9 14631.0 15203.5 16461.3 16945.8 16991.3 17156.2 17980.3 17983.6 18072.6 18145.5 813.3 955.9 958.3 962.2 971.6 981.4 984.3 984.8 985.2 988.6 1027.1 1031.8 1032.3 1032.7 1033.3 1027.4 1029.6 1030.0 1031.0 1031.2 747.2 757.6 757.7 759.0 759.1 17068.6 17517.9 17728.0 17766.2 17786.7 16768.4 16848.2 17061.9 17210.6 17447.4 13708.4 13789.0 14113.9 14122.2 14129.5 3303.1 3378.5 3381.2 3386.2 3386.8 127533.7 129815.0 130288.3 131058.3 132092.3 10337.3 12260.2 12375.2 12384.4 12396.6 20831.5 20891.5 21196.8 21215.8 21471.2 7545.0 7670.1 7705.8 7729.3 7729.5 3851.2 3857.3 3943.1 3965.5 3967.9 4124.7 4258.0 4264.1 4270.1 4280.6 906.7 943.6 945.4 948.9 950.2 607.2 638.0 656.8 665.5 687.3 11591.7 12000.6 12110.7 12206.5 12268.6 624.2 635.3 636.3 649.4 653.0 5201.5 5403.4 5601.2 6164.1 6189.9 112341.3 112420.0 113182.9 115274.4 116501.0 90089.6 90180.1 99153.5 100034.5 100434.5 82845.6 83765.7 85036.6 85304.0 85813.1 10744.6 11750.7 12390.6 12773.0 12826.6 31511.7 33659.7 36596.4 38842.0 42073.7 56516.0 61748.7 66076.7 73945.6 74340.5 14466.0 16143.4 16311.2 16848.4 16854.1 16390.8 16669.9 17885.1 18082.2 18262.5 13674.5 17004.0 17245.9 17460.5 17607.6 16754.7 16891.6 16932.7 16942.6 17038.1 16139.8 16224.0 16715.0 16836.7 16879.2 11677.8 11801.5 12099.1 12145.6 12439.9 11873.6 12614.8 13264.6 13276.2 13276.4 05:15:51 INFO - PID 1636 | -------- Summary: end -------- 05:15:51 INFO - PID 1636 | 05:15:52 INFO - TEST-INFO | 1636: exit 0 05:15:52 INFO - TEST-OK | dromaeo_css | took 1113907ms 05:15:52 INFO - TEST-START | kraken 05:15:52 INFO - operating with platform_type : mac_ 05:15:52 INFO - Initialising browser for kraken test... 05:15:52 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpjRn7qf/profile http://localhost:49240/getInfo.html) 05:15:54 INFO - PID 1651 | 2017-05-11 05:15:54.705 plugin-container[1652:10301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933b, name = 'com.apple.tsm.portname' 05:15:54 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:15:54 INFO - PID 1651 | 2017-05-11 05:15:54.709 plugin-container[1652:10301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 05:15:54 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:15:54 INFO - PID 1651 | 2017-05-11 05:15:54.709 plugin-container[1652:10301] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:15:54 INFO - PID 1651 | __metrics Screen width/height:1600/1200 05:15:54 INFO - PID 1651 | colorDepth:24 05:15:54 INFO - PID 1651 | Browser inner width/height: 1024/691 05:15:54 INFO - PID 1651 | __metrics 05:15:55 INFO - TEST-INFO | 1651: exit 0 05:15:55 INFO - Browser initialized. 05:15:55 INFO - Running cycle 1/1 for kraken test... 05:15:55 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpjRn7qf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:15:58 INFO - PID 1653 | 2017-05-11 05:15:57.996 plugin-container[1654:10425] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x954b, name = 'com.apple.tsm.portname' 05:15:58 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:15:58 INFO - PID 1653 | 2017-05-11 05:15:57.996 plugin-container[1654:10425] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x440f, name = 'com.apple.CFPasteboardClient' 05:15:58 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:15:58 INFO - PID 1653 | 2017-05-11 05:15:57.996 plugin-container[1654:10425] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:15:58 INFO - PID 1653 | 2017-05-11 05:15:58.496 plugin-container[1655:10474] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x902b, name = 'com.apple.tsm.portname' 05:15:58 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:15:58 INFO - PID 1653 | 2017-05-11 05:15:58.496 plugin-container[1655:10474] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x902f, name = 'com.apple.CFPasteboardClient' 05:15:58 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:15:58 INFO - PID 1653 | 2017-05-11 05:15:58.496 plugin-container[1655:10474] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:16:07 INFO - PID 1653 | RSS: Main: 244305920 05:16:07 INFO - PID 1653 | 05:16:10 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 05:16:11 INFO - PID 1653 | RSS: Main: 253071360 05:16:11 INFO - PID 1653 | 05:16:14 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 05:16:14 INFO - PID 1653 | RSS: Main: 259469312 05:16:14 INFO - PID 1653 | 05:16:18 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 05:16:18 INFO - PID 1653 | RSS: Main: 255422464 05:16:18 INFO - PID 1653 | 05:16:20 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 05:16:21 INFO - PID 1653 | RSS: Main: 257761280 05:16:21 INFO - PID 1653 | 05:16:23 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:16:23 INFO - PID 1653 | RSS: Main: 258334720 05:16:23 INFO - PID 1653 | 05:16:26 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 05:16:27 INFO - PID 1653 | RSS: Main: 263880704 05:16:27 INFO - PID 1653 | 05:16:30 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 05:16:30 INFO - PID 1653 | RSS: Main: 265523200 05:16:30 INFO - PID 1653 | 05:16:33 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 05:16:34 INFO - PID 1653 | RSS: Main: 264937472 05:16:34 INFO - PID 1653 | 05:16:35 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:16:36 INFO - PID 1653 | RSS: Main: 264593408 05:16:36 INFO - PID 1653 | 05:16:38 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:16:39 INFO - PID 1653 | RSS: Main: 264421376 05:16:39 INFO - PID 1653 | 05:16:41 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:16:41 INFO - PID 1653 | RSS: Main: 263970816 05:16:41 INFO - PID 1653 | 05:16:44 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:16:45 INFO - PID 1653 | RSS: Main: 264245248 05:16:45 INFO - PID 1653 | 05:16:48 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:16:49 INFO - PID 1653 | RSS: Main: 264556544 05:16:49 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 05:16:51 INFO - PID 1653 | RSS: Main: 266383360 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | __start_tp_report 05:16:51 INFO - PID 1653 | _x_x_mozilla_page_load 05:16:51 INFO - PID 1653 | _x_x_mozilla_page_load_details 05:16:51 INFO - PID 1653 | |i|pagename|runs| 05:16:51 INFO - PID 1653 | |0;ai-astar;135;169;120;114;117;114;117;115;116;114 05:16:51 INFO - PID 1653 | |1;audio-beat-detection;147;111;117;153;113;115;112;123;138;112 05:16:51 INFO - PID 1653 | |2;audio-dft;231;279;197;213;214;228;197;198;197;193 05:16:51 INFO - PID 1653 | |3;audio-fft;84;59;59;79;60;50;53;63;85;86 05:16:51 INFO - PID 1653 | |4;audio-oscillator;81;66;82;70;70;69;86;69;69;89 05:16:51 INFO - PID 1653 | |5;imaging-gaussian-blur;93;94;93;92;91;92;93;92;93;93 05:16:51 INFO - PID 1653 | |6;imaging-darkroom;102;101;99;98;99;99;101;102;140;102 05:16:51 INFO - PID 1653 | |7;imaging-desaturate;93;94;92;91;91;90;89;94;109;86 05:16:51 INFO - PID 1653 | |8;json-parse-financial;50;51;52;69;43;41;53;57;70;76 05:16:51 INFO - PID 1653 | |9;json-stringify-tinderbox;56;60;56;64;69;52;49;51;69;50 05:16:51 INFO - PID 1653 | |10;stanford-crypto-aes;79;87;79;83;81;81;83;85;83;77 05:16:51 INFO - PID 1653 | |11;stanford-crypto-ccm;137;171;136;133;125;150;156;157;202;154 05:16:51 INFO - PID 1653 | |12;stanford-crypto-pbkdf2;184;187;178;194;187;221;189;210;233;237 05:16:51 INFO - PID 1653 | |13;stanford-crypto-sha256-iterative;67;72;58;67;76;97;92;95;66;72 05:16:51 INFO - PID 1653 | __end_tp_report 05:16:51 INFO - PID 1653 | __start_cc_report 05:16:51 INFO - PID 1653 | _x_x_mozilla_cycle_collect,994 05:16:51 INFO - PID 1653 | __end_cc_report 05:16:51 INFO - PID 1653 | __startTimestamp1494505011309__endTimestamp 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | ------- Summary: start ------- 05:16:51 INFO - PID 1653 | Number of tests: 14 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | [#0] ai-astar Cycles:10 Average:123.10 Median:117.00 stddev:17.31 (14.8%) stddev-sans-first:17.82 05:16:51 INFO - PID 1653 | Values: 135.0 169.0 120.0 114.0 117.0 114.0 117.0 115.0 116.0 114.0 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | [#1] audio-beat-detection Cycles:10 Average:124.10 Median:120.00 stddev:15.90 (13.2%) stddev-sans-first:14.54 05:16:51 INFO - PID 1653 | Values: 147.0 111.0 117.0 153.0 113.0 115.0 112.0 123.0 138.0 112.0 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | [#2] audio-dft Cycles:10 Average:214.70 Median:213.50 stddev:26.39 (12.4%) stddev-sans-first:27.33 05:16:51 INFO - PID 1653 | Values: 231.0 279.0 197.0 213.0 214.0 228.0 197.0 198.0 197.0 193.0 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | [#3] audio-fft Cycles:10 Average:67.80 Median:71.00 stddev:14.10 (19.9%) stddev-sans-first:13.68 05:16:51 INFO - PID 1653 | Values: 84.0 59.0 59.0 79.0 60.0 50.0 53.0 63.0 85.0 86.0 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | [#4] audio-oscillator Cycles:10 Average:75.10 Median:75.50 stddev:8.44 (11.2%) stddev-sans-first:8.68 05:16:51 INFO - PID 1653 | Values: 81.0 66.0 82.0 70.0 70.0 69.0 86.0 69.0 69.0 89.0 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:92.60 Median:93.00 stddev:0.84 (0.9%) stddev-sans-first:0.88 05:16:51 INFO - PID 1653 | Values: 93.0 94.0 93.0 92.0 91.0 92.0 93.0 92.0 93.0 93.0 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | [#6] imaging-darkroom Cycles:10 Average:104.30 Median:119.00 stddev:12.63 (10.6%) stddev-sans-first:13.37 05:16:51 INFO - PID 1653 | Values: 102.0 101.0 99.0 98.0 99.0 99.0 101.0 102.0 140.0 102.0 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | [#7] imaging-desaturate Cycles:10 Average:92.90 Median:91.50 stddev:6.15 (6.7%) stddev-sans-first:6.53 05:16:51 INFO - PID 1653 | Values: 93.0 94.0 92.0 91.0 91.0 90.0 89.0 94.0 109.0 86.0 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:55.00 stddev:11.76 (21.4%) stddev-sans-first:12.26 05:16:51 INFO - PID 1653 | Values: 50.0 51.0 52.0 69.0 43.0 41.0 53.0 57.0 70.0 76.0 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:57.60 Median:58.00 stddev:7.59 (13.1%) stddev-sans-first:8.03 05:16:51 INFO - PID 1653 | Values: 56.0 60.0 56.0 64.0 69.0 52.0 49.0 51.0 69.0 50.0 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:83.00 stddev:3.01 (3.6%) stddev-sans-first:3.02 05:16:51 INFO - PID 1653 | Values: 79.0 87.0 79.0 83.0 81.0 81.0 83.0 85.0 83.0 77.0 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:152.10 Median:155.00 stddev:22.31 (14.4%) stddev-sans-first:22.99 05:16:51 INFO - PID 1653 | Values: 137.0 171.0 136.0 133.0 125.0 150.0 156.0 157.0 202.0 154.0 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.00 Median:202.00 stddev:21.59 (10.7%) stddev-sans-first:21.89 05:16:51 INFO - PID 1653 | Values: 184.0 187.0 178.0 194.0 187.0 221.0 189.0 210.0 233.0 237.0 05:16:51 INFO - PID 1653 | 05:16:51 INFO - PID 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.20 Median:74.00 stddev:13.64 (18.4%) stddev-sans-first:14.06 05:16:51 INFO - PID 1653 | Values: 67.0 72.0 58.0 67.0 76.0 97.0 92.0 95.0 66.0 72.0 05:16:51 INFO - PID 1653 | -------- Summary: end -------- 05:16:51 INFO - PID 1653 | 05:16:52 INFO - TEST-INFO | 1653: exit 0 05:16:52 INFO - TEST-OK | kraken | took 59758ms 05:16:52 INFO - SUITE-END | took 1173s 05:16:52 INFO - Completed test suite (00:19:33) 05:16:52 INFO - javascript benchmark 05:16:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7999.952917375511, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2612.0335475873926, 3539.61064282929, 3621.1128273572976, 3650.7079433645313, 3684.244729830384, 4979.900401991958, 6114.419025732089, 6167.660778657178, 6256.937430625704, 6265.780697675582, 6125.784909797661, 6186.783462578811, 6211.378862113786, 6229.563930524865, 6275.968620156904, 5395.7032363220005, 5434.592405569579, 5452.563794896399, 5461.563074953994, 5476.91784623231, 4518.661100417466, 5519.558435325184, 5575.470330318613, 5589.0, 5613.382527921925, 5352.090144675414, 5435.239066530688, 5482.068048431776, 5541.750621222014, 5551.72241387931, 5475.780968761244, 5509.338879334466, 5515.0, 5519.337679478448, 5535.474129957647, 5394.514493695587, 5435.402105768402, 5437.755301011464, 5456.91814622781, 5467.234587157801, 6138.907916381258, 6191.318954914956, 6245.687715614246, 6284.622922624657, 6311.715972781236, 6505.0892874997535, 6596.076549283104, 6686.93313066868, 6690.364415380531, 6725.495587830883, 6782.423494003016, 6821.829454263633, 6825.146856642919, 6828.2147553031045, 6834.555753875982, 6290.676742299921, 6335.968320158369, 6344.873102537969, 6350.110984462179, 6350.142730731292, 6725.226598941086, 6825.283345248777, 6876.037354770337, 6896.862062758817, 6960.060391847137, 6419.293877673452, 6500.642464664489, 6584.440322572635, 6623.966880165568, 6647.933520664828, 6630.834229144213, 6678.899816502756, 6702.296258892746, 6786.491013174032, 6832.316768323228, 6686.531942764057, 6686.765963191265, 6690.665466726644, 6698.4641228701585, 6700.698468568926, 6435.388638079375, 6517.706703198368, 6537.771178008747, 6698.7320507179165, 6724.697388617524, 4471.5081341052455, 5153.974230128901, 5352.116900711339, 5357.5446087082255, 5386.865328366822, 4058.5941405859767, 4651.511591282934, 4711.293306004127, 4740.478547359788, 4768.904621907543, 6367.204099487564, 6370.140031095742, 6373.044043393528, 6393.392627700361, 6394.616323020634, 6473.320301368382, 6703.363180497845, 6767.052612634235, 6787.694553745093, 6891.931080689129, 6315.652639104894, 6363.268224154189, 6369.9681501592195, 6372.872542549123, 6399.296077431479, 5952.553558483131, 5966.134910437962, 5966.492848107872, 5974.0, 5980.671063091571, 6755.0, 6862.759803406857, 6953.582785032914, 6983.1620205575655, 7041.471889608198, 6803.93196068033, 6833.829154271183, 6834.931650683429, 6885.655717214019, 6915.412189963909, 5751.7411716472025, 7031.472639551952, 7042.929570704227, 7050.25972272904, 7055.682494287872, 5393.86515337104, 5655.462731040545, 6461.2892581816905, 6710.0, 6711.597304161766, 6788.96605516989, 6839.2476827547935, 6872.10662613857, 6872.347127022925, 6872.41584465326, 6584.078229048033, 6615.66921653925, 6636.867262654623, 6639.3692599203, 6660.56706314088, 6309.400606942333, 6424.582402143846, 6492.188476440445, 6545.574537655038, 6647.268800431852, 5082.085224659449, 5770.451807078321, 6456.096146539582, 6462.192225971754, 6466.256380516301, 6351.904721429275, 6452.580582262138, 6524.575902566318, 6552.803415897531, 6657.500687448558, 6459.160309159779, 6486.416222539994, 6509.316521765147, 6511.120998665119, 6519.4784417245555], "value": 6101.622427785745, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.7861662884507, 720.6288761287936, 818.2799136759653, 1210.8607510136337, 1266.195965561868, 37002.81498592507, 37172.442413363824, 37267.440988385126, 37400.0, 37449.812750936275, 37786.0, 38099.61900380995, 38133.23733525328, 38190.809045954804, 38240.0, 7711.344535714471, 7850.882236766453, 8233.38249631277, 8375.329973602098, 8445.493270403766, 8415.200555947205, 8446.746597602081, 8525.488470691778, 8532.61603227853, 8540.0, 8486.618102185386, 8499.235068843804, 8551.273141782925, 8573.74278771638, 8578.227959483645, 8437.325013998865, 8508.532030738306, 8540.231379175842, 8551.444156129832, 8551.786205344853, 7127.180374256975, 8332.416730828845, 8344.582770861432, 8389.790255243606, 8390.573602487591, 37263.81368093169, 37481.62518374809, 37488.43767343492, 37518.68684596053, 37579.624203757885, 3641.8725344612812, 3662.8717994870053, 3676.17286110623, 3681.134933290701, 3681.2821499807546, 30227.244318891982, 30523.542146867814, 30616.08151755451, 30685.0, 30687.386252274828, 3132.201288671397, 3178.253110519026, 3183.840807959593, 3191.2500562367827, 3205.5031470122167, 289639.72521923477, 291393.0, 311892.0, 320092.7967521149, 321106.0, 46739.5326046742, 46767.76616116966, 46871.29693054607, 46897.53102468931, 46983.5301646986, 48237.0, 48384.0, 50692.746536267085, 51319.48680513222, 51687.96624067573, 14275.64310892236, 14380.424783008772, 14420.567382978528, 14423.85576144246, 14433.0, 11446.54213831454, 11531.827022594667, 11542.0, 11553.711157221136, 11602.70993225176, 13362.331883405985, 13514.932425338007, 13529.699951008351, 13575.32123393846, 13649.658758531115, 5188.273641690166, 5552.167174923713, 6250.74997000124, 6270.6864656766975, 6311.400416960381, 896.7758060484879, 938.2306508662831, 998.7627713920765, 1042.5464922758624, 1083.664064140119, 23591.646125308132, 24605.63091553628, 24748.886300116537, 24766.752332476444, 24783.5043299133, 1137.9317240965568, 1142.6229344316355, 1168.480026388266, 1173.8356630071796, 1176.6999415149082, 11117.332960022424, 11465.197436179555, 12163.756724865452, 12165.574204902787, 12759.61721148367, 282891.1710882906, 333785.0, 337902.0, 341569.0, 342023.0, 53043.0, 53341.733291333294, 53355.0, 53379.19931201035, 53626.19560706592, 1502.894797364196, 1545.6367753577674, 1577.928993195286, 1581.6915701438338, 1585.9127747973973, 18393.54016149607, 23573.17493887706, 24273.0, 24357.51284974255, 24464.941882264717, 37307.81346093252, 37342.06644833901, 37395.0, 37442.812785935894, 37488.812555937046, 280750.0, 281781.1821881837, 282951.0, 284522.0, 284523.5773821201, 34898.65101348954, 34934.82532587321, 35052.82473587616, 35073.64926350704, 35119.0, 42130.789346053076, 42298.577014229464, 43991.78004109959, 44018.559814401444, 44077.33883991679, 24166.509326152038, 29003.56494652624, 29820.85089574538, 30580.541291880196, 30858.845705771328, 30192.69807301899, 30590.235244119074, 30631.468426578136, 30666.540001899542, 30671.293406894638, 36236.91289261326, 36325.0, 36347.90956271316, 36394.636053640184, 36430.27139457143, 28657.283567910134, 31220.68779312178, 31336.843315783277, 31497.8425107873, 31504.684953150176, 24075.51848963046, 24290.392740181636, 24540.87729561341, 24564.894579744312, 24570.754292456848], "value": 17506.252317823113, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.1435771278586, 432.95670432956706, 438.6227844054115, 450.4594486616061, 451.64545831522264, 16040.0, 20743.273985410513, 21137.837418941952, 21918.342449726493, 21941.902904854775, 21960.0, 22003.889980550073, 22369.55260894781, 22430.663540046873, 23623.76376236237, 5961.791337303195, 6865.729839979607, 6898.099655843179, 7043.823904402379, 7073.504854660176, 6818.352256535622, 6971.268016858233, 6996.796009257093, 7001.264867188948, 7005.29947005298, 6645.3354664533645, 6960.756373526928, 6986.0, 7025.086738723984, 7043.436525078007, 5935.910961335583, 6655.900161497581, 6868.313168683117, 6937.028815965768, 6945.826354341157, 6736.528443008991, 6867.622280774555, 6869.278725733776, 6884.0, 6898.034275201476, 28308.0, 29115.41769164626, 29450.410991780045, 29758.404831903455, 29863.70136298631, 2354.105439932832, 2388.474535602165, 2397.3646983549374, 2398.712154541449, 2400.819938504619, 18609.813901860944, 19533.0, 19696.11367488452, 19876.0, 20203.595928081504, 2328.557574060923, 2369.040538581877, 2370.9762902370926, 2388.880555972212, 2398.8680622565753, 152161.23919380442, 164641.0, 165349.0, 167032.16483917623, 167310.95320262, 25110.0, 25132.74867251304, 25477.98088076456, 25525.87237063803, 25825.741742582333, 42448.36327455091, 42477.57522424798, 42504.3624345635, 42618.57381426146, 42677.14645707068, 9002.279817614575, 9121.863172052424, 9212.0, 9456.57445414958, 9489.288303377274, 8737.60680769367, 8845.027047024822, 8887.73336799884, 9033.277337812959, 9036.728898133066, 8208.0, 8240.793980150424, 8248.587570621445, 8292.709755158541, 8358.707445239388, 2281.6349384098467, 2289.107248173214, 2289.1301305504135, 2290.8854557272402, 2474.3937735254617, 929.1683942871227, 968.7239136846106, 980.568549838069, 980.9215262778961, 1007.7228762090484, 20866.79133208679, 21174.15303387878, 21323.68014479784, 21348.252811151535, 21432.0, 999.2555546118189, 1003.4631472162504, 1022.8158931392421, 1028.7222449938624, 1029.9485025748832, 9225.538723063954, 9625.51872406377, 10398.68803935883, 10865.83701244482, 10964.122870170368, 531978.0, 533175.0, 537072.8317225734, 551163.0, 561892.0, 115526.0, 115941.0, 117397.0, 127236.0, 127755.0, 1153.4694040741354, 1178.5177568615018, 1197.4252358867618, 1201.5013769285824, 1201.6815543880878, 15455.61360965985, 18101.728474072635, 18184.818151818312, 18279.36022239215, 18525.44423667292, 53586.73206633942, 54022.72988635032, 54878.80484780564, 56179.31462056145, 59051.409485904594, 71802.28197717956, 72189.0, 72839.0, 73155.0, 73417.0, 8747.956260218658, 9020.684276050306, 9054.230390416888, 9136.6802161924, 9166.17504424605, 13901.513447029305, 14544.27278636085, 14549.272536372928, 14999.775003375176, 15037.849621503645, 12030.398480076135, 12157.574484892986, 12325.198862073936, 12377.443015064164, 12464.688382790502, 10895.455227238448, 11339.46917166172, 11427.942860285646, 11546.13403994687, 11967.401629918295, 11982.760344793229, 12366.010719142267, 12487.625371238877, 12499.437525311565, 12675.556355527513, 8894.288456923301, 8922.107789221025, 8924.821503570021, 9249.861252081359, 9324.906750932403, 12026.819597706217, 12052.51789928393, 12098.75802483963, 12352.135350525377, 12402.13185077036], "value": 11306.479138208746, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.8302203735552, 1460.7679465776298, 1461.8757405620515, 1480.104536755263, 1481.9851801481982, 8828.337874659393, 11382.2032457728, 11477.770444591113, 11755.118366122531, 12045.0, 11770.352630605314, 11935.522579096825, 11971.042316614672, 11979.580714674988, 11989.760204795899, 2173.608750424923, 2521.7982561395092, 2555.744425557448, 2566.217303722364, 2569.1264969910226, 2502.874856257189, 2530.987345063272, 2533.3793220660964, 2534.1510593951048, 2545.4272788622598, 2491.800655947529, 2533.07542746897, 2536.6195070739445, 2555.923322300334, 2558.411565339973, 2469.1604854349516, 2502.399424138203, 2516.358328626198, 2517.924462266135, 2518.3829961659326, 2487.8756062197, 2499.0253801017616, 2508.034406753413, 2511.309389917769, 2517.5845985412384, 11558.30650160993, 11726.530938762438, 11738.237014594024, 11798.528058877635, 11816.940915295454, 5736.282964629421, 6529.2817790043055, 6770.221424536192, 6777.0, 6781.355771201728, 3877.4765406670303, 3884.2425726982965, 3949.637375105584, 4190.25538272432, 4194.182134483777, 1211.4790640024785, 1241.900647948162, 1284.627458037168, 1287.1890708853457, 1288.6262983734705, 13967.37146828395, 15673.608159795982, 15998.680026399523, 16046.679066418723, 16195.757063644172, 5274.709890955957, 5658.519025882846, 5700.686462244616, 5836.970815145897, 5853.414658534112, 11970.521179152736, 11976.640700778991, 12001.099917506048, 12015.279083255033, 12103.757924841453, 6559.672016399257, 6565.277819439858, 6567.9671601641685, 6568.080468734381, 6588.341165883373, 6051.727672254671, 6109.236345456818, 6209.230369344806, 6248.937510624926, 6277.686115694197, 4310.094880075157, 4437.911241775147, 4471.083427897338, 4488.551144885485, 4501.919539310607, 1239.6652903716126, 1256.9622911312674, 1259.9433025513874, 1269.0418733856063, 1272.840894888139, 662.3939095727355, 684.8938414545753, 820.5404973215019, 836.5733475927199, 844.5059640110604, 6193.690315484298, 6311.368863113652, 6323.936760632427, 6378.394052564999, 6406.2953075161695, 594.4352864778477, 598.6677394046259, 615.8152554233712, 618.5793660310942, 619.3001907844198, 3937.684985201177, 4150.813213405404, 4586.885327866763, 4629.0, 4751.762411879392, 15024.6477816997, 15288.006279591791, 15299.541013769605, 15299.694006119815, 15364.385424582892, 14748.778768318483, 15136.243187840564, 15177.013494122846, 15182.089074655556, 15226.086434813726, 7582.924170758332, 8221.54781487012, 8573.18554737299, 8742.38803283764, 8759.34304927133, 6576.359843077821, 7073.665690322078, 7082.043924070184, 7167.856642867009, 7220.494565380373, 10811.513481893177, 12384.0, 12875.905548028522, 13111.14777539457, 13128.868711313147, 10117.949410253195, 12317.260964342167, 12694.0, 12811.359432028548, 13017.414216360476, 9183.127602877716, 9224.939131999638, 9227.169554740105, 9463.006384329821, 9556.331056826233, 11019.173561982723, 11096.00135987765, 11111.611093611686, 11114.49984750672, 11162.665120046411, 8144.959275203586, 8726.821879046502, 8915.598798053974, 9212.355135140477, 9248.445093294424, 8966.058563850702, 8988.955055224682, 9030.051844556227, 9119.908800911906, 9214.907850921405, 8034.83930321402, 8142.022957245168, 8212.958935205286, 8226.753197404087, 8265.504069755834, 5934.821955341346, 5989.101634754822, 6031.306399764083, 6159.23009623797, 6175.166352542349, 6083.878322433615, 6122.510199184144, 6241.407066328691, 6262.843428914313, 6362.968185159045], "value": 5307.10791537112, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.9592355032071, 375.2120546851612, 394.29027750049914, 407.13483846825494, 408.4751094843127, 6304.71628776705, 6368.872622547547, 6403.5197360198, 6494.610323380594, 6513.316101809313, 6195.907061394083, 6313.517402644128, 6316.936830631692, 6328.746850126001, 6345.397187267214, 3791.4218331534544, 4043.65628921542, 4579.542045795411, 4605.4933957264675, 4605.746683932382, 3975.6223158799867, 4397.538258482861, 4527.253003254459, 4564.109998550284, 4617.6536759742985, 4251.383549385338, 4504.324351347292, 4510.368548403226, 4511.954880451186, 4518.932216016762, 4514.97742511287, 4555.954440455586, 4562.156001139767, 4576.26779715244, 4588.426446694163, 3794.9810250948567, 3861.2277544490935, 3864.3623802072625, 3873.992761881931, 3905.7070719695853, 4943.629227807929, 5907.881842363128, 6114.908276375858, 6156.168917196166, 6185.103160041814, 5221.008008478377, 5430.728463576806, 5441.863953401157, 5443.0, 5475.945240547583, 5578.721063946827, 5612.943870561283, 5731.312242530881, 6181.474574661159, 6771.424428923548, 4390.538993405678, 4395.692301538894, 4402.603765661106, 4405.713628614129, 4415.293553031532, 6457.447499512832, 6520.543561950665, 6943.653386892115, 7215.711371545131, 7350.264973502661, 4430.18041662287, 4448.977755111268, 4465.303184789759, 4465.575770301816, 4484.798184081724, 3958.980205098956, 4529.456465224194, 4543.613792827647, 4545.36364908913, 4562.954370456319, 5256.684598924, 5399.244105825188, 5403.729813509309, 5416.91874621881, 5430.864228394322, 6782.728690852409, 6876.931230687629, 6886.931130688729, 6892.0, 6905.965470172718, 1463.66075041336, 1495.9326830292446, 1556.8754499640002, 1562.9528216095243, 1572.638293192572, 939.9295052871062, 960.2318145483586, 978.2027647467434, 1019.7654539455818, 1024.6055268721595, 5506.972465137648, 6507.7071531781185, 6521.47828173745, 6534.771283005072, 6541.313162118005, 6532.673366331759, 6812.216595091529, 7084.25615310385, 7122.358987691133, 7125.89311160333, 5552.7778888843995, 6535.771248006297, 6605.603663780189, 6628.801135965929, 6630.370114839083, 3004.9248768780517, 3586.4757478071715, 3738.532683414573, 3746.250749850041, 3771.2268984858038, 6514.967425162844, 7313.158986716489, 7398.149212840484, 7436.144843342977, 7467.1039475263315, 6177.62934223948, 7050.823729406806, 7127.7924933780005, 7383.261673832574, 7478.364339031244, 7383.889241661379, 7401.222871598405, 7426.774582193985, 7462.328390444777, 7781.5331080135375, 5390.541803946709, 6548.0, 6884.4148247399535, 7060.152781666233, 7227.421806255592, 5749.022666146849, 6986.0, 7061.858762824818, 7065.364117229474, 7112.110986126734, 7074.221835597978, 7317.0, 7419.629018549159, 7419.9595111344815, 7426.405887529093, 4540.568645978627, 4550.158220729176, 4557.749323787223, 4561.270196768501, 4572.908541829212, 5896.882062358814, 6331.0, 7043.2604576518725, 7190.928090719026, 7211.639418029183, 6217.658028808278, 6472.255690595642, 6897.827554311182, 6909.343612356818, 6933.37599616037, 6865.288211156665, 6867.0, 6876.621785801829, 6907.654617269016, 6922.965385173042, 6563.606183628997, 6658.500612453989, 6727.596344219362, 6738.663066846736, 6762.594244345159, 5414.891702166014, 5936.050231962865, 6328.335524769833, 6540.771073012612, 6823.55646882951, 5999.400059993965, 6422.454091402096, 6429.0, 6624.735010599522, 6690.765823196165], "value": 4897.154885477337, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.7590219325435, 818.8658707689848, 829.767665053785, 859.4155973937724, 860.948343099414, 14631.0, 15203.467878624251, 16461.341546338146, 16945.830541694595, 16991.320347186112, 17156.22796974136, 17980.280788768465, 17983.640327193447, 18072.638547229046, 18145.54636134094, 813.2639960835423, 955.8709574207462, 958.3387462650784, 962.210986990668, 971.6404930175845, 981.4405788700443, 984.3404918704449, 984.7882705218377, 985.2069084387066, 988.6440881282732, 1027.0807627173674, 1031.793641271745, 1032.3238278927315, 1032.6592224565875, 1033.3489901362132, 1027.3579013116803, 1029.6241871716845, 1029.953652085658, 1030.9690709278734, 1031.20597140202, 747.2452822649109, 757.6363345594091, 757.7196437318172, 758.981025474362, 759.0587671287619, 17068.573285667833, 17517.912410437868, 17728.0, 17766.200520976457, 17786.733199002025, 16768.413105541247, 16848.241829117596, 17061.91469042659, 17210.56973575658, 17447.389341373117, 13708.3831227595, 13789.034767566274, 14113.858861411358, 14122.22327771972, 14129.505467308698, 3303.091649796301, 3378.45944648855, 3381.22231886665, 3386.204242003127, 3386.847591858372, 127533.72466275311, 129815.0, 130288.3485582566, 131058.34470827585, 132092.33953830265, 10337.276390652582, 12260.203086799329, 12375.195612285173, 12384.380780960915, 12396.628101156979, 20831.479213019797, 20891.477713057295, 21196.788032119788, 21215.78784212169, 21471.248506302203, 7544.962275188589, 7670.0795904491815, 7705.807354816062, 7729.265719756614, 7729.497582657109, 3851.2105018471148, 3857.3056849767313, 3943.11279962012, 3965.484487016727, 3967.940480892789, 4124.711270211116, 4257.978710106429, 4264.125854199881, 4270.145970805851, 4280.593343632412, 906.7325139083986, 943.5706753427174, 945.4232917920064, 948.8671585977969, 950.1543626172712, 607.1651479216077, 637.9681015949277, 656.7701304543372, 665.517499812632, 687.2646268492765, 11591.710207244718, 12000.639980800415, 12110.697232569257, 12206.511739530497, 12268.570600028956, 624.188554878656, 635.269440143829, 636.3254949753276, 649.3538928765909, 652.9902051469231, 5201.505856943584, 5403.432639572867, 5601.215829783833, 6164.075388691643, 6189.938100618936, 112341.31488027686, 112420.0, 113182.86817131723, 115274.423627883, 116501.0, 90089.64865527023, 90180.09819901717, 99153.50423247837, 100034.4998275004, 100434.4978275104, 82845.58577207076, 83765.74351384734, 85036.57481712553, 85304.0, 85813.14186858176, 10744.623938162127, 11750.707422183725, 12390.62828115158, 12772.978161747224, 12826.61520154397, 31511.684883150876, 33659.653613855175, 36596.353164107146, 38842.0, 42073.73778786641, 56516.0, 61748.69125654343, 66076.6696166516, 73945.63027184829, 74340.51318973482, 14466.0, 16143.354265829235, 16311.184440777677, 16848.410305639245, 16854.07302598369, 16390.75413868769, 16669.91665041667, 17885.10574471297, 18082.186301616723, 18262.452126436227, 13674.521391751241, 17004.0, 17245.8790178643, 17460.476185714448, 17607.647847043245, 16754.66490670204, 16891.577710557336, 16932.746008810125, 16942.576435588715, 17038.14809259507, 16139.838601614303, 16224.0, 16715.0, 16836.74744878803, 16879.156042197596, 11677.773833747335, 11801.468933898166, 12099.092568057255, 12145.574904878287, 12439.937800310941, 11873.643790686294, 12614.810777838524, 13264.602061938158, 13276.203427794364, 13276.402561884932], "value": 8350.754969062791, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1520.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 169.0, 120.0, 114.0, 117.0, 114.0, 117.0, 115.0, 116.0, 114.0], "value": 123.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 111.0, 117.0, 153.0, 113.0, 115.0, 112.0, 123.0, 138.0, 112.0], "value": 124.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [231.0, 279.0, 197.0, 213.0, 214.0, 228.0, 197.0, 198.0, 197.0, 193.0], "value": 214.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 59.0, 79.0, 60.0, 50.0, 53.0, 63.0, 85.0, 86.0], "value": 67.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 66.0, 82.0, 70.0, 70.0, 69.0, 86.0, 69.0, 69.0, 89.0], "value": 75.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 92.0, 91.0, 92.0, 93.0, 92.0, 93.0, 93.0], "value": 92.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 101.0, 99.0, 98.0, 99.0, 99.0, 101.0, 102.0, 140.0, 102.0], "value": 104.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 92.0, 91.0, 91.0, 90.0, 89.0, 94.0, 109.0, 86.0], "value": 92.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 52.0, 69.0, 43.0, 41.0, 53.0, 57.0, 70.0, 76.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 56.0, 64.0, 69.0, 52.0, 49.0, 51.0, 69.0, 50.0], "value": 57.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 87.0, 79.0, 83.0, 81.0, 81.0, 83.0, 85.0, 83.0, 77.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 171.0, 136.0, 133.0, 125.0, 150.0, 156.0, 157.0, 202.0, 154.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 187.0, 178.0, 194.0, 187.0, 221.0, 189.0, 210.0, 233.0, 237.0], "value": 202.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 58.0, 67.0, 76.0, 97.0, 92.0, 95.0, 66.0, 72.0], "value": 76.2, "unit": "score"}]}]} 05:16:52 INFO - Return code: 0 05:16:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:16:52 INFO - # TBPL SUCCESS # 05:16:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:16:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:16:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:16:52 INFO - 2017-05-11 05:16:52.641 screenresolution[1660:10863] starting screenresolution argv=screenresolution get 05:16:52 INFO - 2017-05-11 05:16:52.644 screenresolution[1660:10863] Display 0: 1600x1200x32@60 05:16:52 INFO - 2017-05-11 05:16:52.650 screenresolution[1661:10866] starting screenresolution argv=screenresolution list 05:16:52 INFO - Available Modes on Display 0 05:16:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:16:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:16:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:16:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:16:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:16:52 INFO - Intel Iris: 05:16:52 INFO - Chipset Model: Intel Iris 05:16:52 INFO - Type: GPU 05:16:52 INFO - Bus: Built-In 05:16:52 INFO - VRAM (Dynamic, Max): 1536 MB 05:16:52 INFO - Vendor: Intel (0x8086) 05:16:52 INFO - Device ID: 0x0a2e 05:16:52 INFO - Revision ID: 0x0009 05:16:52 INFO - Displays: 05:16:52 INFO - Display: 05:16:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:16:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:16:52 INFO - Display Serial Number: 1600x1200 60 05:16:52 INFO - Main Display: Yes 05:16:52 INFO - Mirror: Off 05:16:52 INFO - Online: Yes 05:16:52 INFO - Rotation: Supported 05:16:52 INFO - Return code: 0 05:16:52 INFO - Running post-action listener: _resource_record_post_action 05:16:52 INFO - [mozharness: 2017-05-11 12:16:52.884927Z] Finished run-tests step (success) 05:16:52 INFO - Running post-run listener: _resource_record_post_run 05:16:52 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 39443456; Write bytes: 528403968; Read time: 260; Write time: 1655 05:16:52 INFO - TinderboxPrint: CPU usage
54.2% 05:16:52 INFO - TinderboxPrint: I/O read bytes / time
39,443,456 / 260 05:16:52 INFO - TinderboxPrint: I/O write bytes / time
528,403,968 / 1,655 05:16:52 INFO - TinderboxPrint: CPU idle
2,188.8 (45.8%) 05:16:52 INFO - TinderboxPrint: CPU system
964.0 (20.2%) 05:16:52 INFO - TinderboxPrint: CPU user
1,629.0 (34.1%) 05:16:52 INFO - TinderboxPrint: Swap in / out
253,235,200 / 0 05:16:52 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 211003904; Write bytes: 205919744; Read time: 16488; Write time: 436 05:16:52 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 37329920; Write bytes: 311449600; Read time: 236; Write time: 1182 05:16:53 INFO - Running post-run listener: _upload_blobber_files 05:16:53 INFO - Blob upload gear active. 05:16:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:16:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:16:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:16:53 INFO - (blobuploader) - INFO - Open directory for files ... 05:16:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:16:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:16:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:54 INFO - (blobuploader) - INFO - Done attempting. 05:16:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:16:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:16:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:55 INFO - (blobuploader) - INFO - Done attempting. 05:16:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:16:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:16:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:56 INFO - (blobuploader) - INFO - Done attempting. 05:16:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:16:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:16:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:58 INFO - (blobuploader) - INFO - Done attempting. 05:16:58 INFO - (blobuploader) - INFO - Iteration through files over. 05:16:58 INFO - Return code: 0 05:16:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:16:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:16:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e9c13bc0279cfc5a562eccaddcbb7d1a96e904e21cececcf2d6aaff4887fbe0b067c96bc1026d95df5e7fec993196c3d6689892fb7880ba46d9648a732b7717", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b29d7f55b5738b739330424ffb12c6e96c3d6c4df6704b144f8eeacf3f17f25d0da427dae91e450f6dedf2870ed59a10904d6adf7f7d2d3464dc38cf7f97fec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b90a6c42176f2006489e919e7389c2f5dd5a040cf307ddcb3378b91a6e69858b6945b59301496cc4d9badca1cf7e4b33154ba54bdb930465a1d846826345fd69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3489fb53d6fcf591ed48627274f984397af304744855bbdc54f9ae37c05390a1d9885cd3a8e673f2e95b990e54dcab28cc83c8e8a03137d7bf8abe2cee6f5b25"} 05:16:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:16:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:16:58 INFO - Contents: 05:16:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e9c13bc0279cfc5a562eccaddcbb7d1a96e904e21cececcf2d6aaff4887fbe0b067c96bc1026d95df5e7fec993196c3d6689892fb7880ba46d9648a732b7717", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b29d7f55b5738b739330424ffb12c6e96c3d6c4df6704b144f8eeacf3f17f25d0da427dae91e450f6dedf2870ed59a10904d6adf7f7d2d3464dc38cf7f97fec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b90a6c42176f2006489e919e7389c2f5dd5a040cf307ddcb3378b91a6e69858b6945b59301496cc4d9badca1cf7e4b33154ba54bdb930465a1d846826345fd69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3489fb53d6fcf591ed48627274f984397af304744855bbdc54f9ae37c05390a1d9885cd3a8e673f2e95b990e54dcab28cc83c8e8a03137d7bf8abe2cee6f5b25"} 05:16:58 INFO - Running post-run listener: copy_logs_to_upload_dir 05:16:58 INFO - Copying logs to upload dir... 05:16:58 INFO - mkdir: /builds/slave/test/build/upload/logs 05:16:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.675698 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-11 05:16:58.890915) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 05:16:58.894144) ========= 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.MxiFK01Aau/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.llfId1VLD3/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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/6e9c13bc0279cfc5a562eccaddcbb7d1a96e904e21cececcf2d6aaff4887fbe0b067c96bc1026d95df5e7fec993196c3d6689892fb7880ba46d9648a732b7717", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b29d7f55b5738b739330424ffb12c6e96c3d6c4df6704b144f8eeacf3f17f25d0da427dae91e450f6dedf2870ed59a10904d6adf7f7d2d3464dc38cf7f97fec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b90a6c42176f2006489e919e7389c2f5dd5a040cf307ddcb3378b91a6e69858b6945b59301496cc4d9badca1cf7e4b33154ba54bdb930465a1d846826345fd69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3489fb53d6fcf591ed48627274f984397af304744855bbdc54f9ae37c05390a1d9885cd3a8e673f2e95b990e54dcab28cc83c8e8a03137d7bf8abe2cee6f5b25"} build_url:https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011291 build_url: 'https://queue.taskcluster.net/v1/task/AyamvUt_QAavSWG7kabMzw/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/6e9c13bc0279cfc5a562eccaddcbb7d1a96e904e21cececcf2d6aaff4887fbe0b067c96bc1026d95df5e7fec993196c3d6689892fb7880ba46d9648a732b7717", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b29d7f55b5738b739330424ffb12c6e96c3d6c4df6704b144f8eeacf3f17f25d0da427dae91e450f6dedf2870ed59a10904d6adf7f7d2d3464dc38cf7f97fec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b90a6c42176f2006489e919e7389c2f5dd5a040cf307ddcb3378b91a6e69858b6945b59301496cc4d9badca1cf7e4b33154ba54bdb930465a1d846826345fd69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3489fb53d6fcf591ed48627274f984397af304744855bbdc54f9ae37c05390a1d9885cd3a8e673f2e95b990e54dcab28cc83c8e8a03137d7bf8abe2cee6f5b25"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 05:16:58.927865) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 05:16:58.928186) ========= 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.MxiFK01Aau/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.llfId1VLD3/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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.005087 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 05:16:58.976510) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 05:16:58.976826) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 05:16:58.977139) ========= ========= Total master_lag: 0.17 =========