builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0239 starttime: 1486731087.0 results: success (0) buildid: 20170210033723 builduid: 0de97d8d1cd347f98784d51f93114db7 revision: 9a08294a8eb8cea3683c6355decebce1d1876fa1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-10 04:51:26.995716) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-10 04:51:26.996087) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-10 04:51:26.996372) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-10 04:51:27.028022) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 04:51:27.028325) ========= 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.9TUJl7S2A6/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.8gnxpa219T/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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-02-10 04:51:27-- 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% 145M=0s 2017-02-10 04:51:27 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125744 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 04:51:27.181823) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 04:51:27.182150) ========= 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.9TUJl7S2A6/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.8gnxpa219T/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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.069087 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 04:51:27.295294) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-10 04:51:27.295675) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9a08294a8eb8cea3683c6355decebce1d1876fa1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9a08294a8eb8cea3683c6355decebce1d1876fa1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9TUJl7S2A6/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.8gnxpa219T/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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-02-10 04:51:27,446 truncating revision to first 12 chars 2017-02-10 04:51:27,446 Setting DEBUG logging. 2017-02-10 04:51:27,446 attempt 1/10 2017-02-10 04:51:27,446 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9a08294a8eb8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-10 04:51:29,299 unpacking tar archive at: mozilla-aurora-9a08294a8eb8/testing/mozharness/ program finished with exit code 0 elapsedTime=2.214374 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-10 04:51:29.542462) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-10 04:51:29.542867) ========= script_repo_revision: 9a08294a8eb8cea3683c6355decebce1d1876fa1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-10 04:51:29.543311) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-10 04:51:29.543640) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-10 04:51:29.570983) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 47 secs) (at 2017-02-10 04:51:29.571382) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9TUJl7S2A6/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.8gnxpa219T/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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:51:29 INFO - MultiFileLogger online at 20170210 04:51:29 in /builds/slave/test 04:51:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:51:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:51:29 INFO - {'append_to_log': False, 04:51:29 INFO - 'base_work_dir': '/builds/slave/test', 04:51:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:51:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:51:29 INFO - 'branch': 'Mozilla-Aurora', 04:51:29 INFO - 'buildbot_json_path': 'buildprops.json', 04:51:29 INFO - 'config_files': ('talos/mac_config.py',), 04:51:29 INFO - 'default_actions': ('clobber', 04:51:29 INFO - 'read-buildbot-config', 04:51:29 INFO - 'download-and-extract', 04:51:29 INFO - 'populate-webroot', 04:51:29 INFO - 'create-virtualenv', 04:51:29 INFO - 'install', 04:51:29 INFO - 'run-tests'), 04:51:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:51:29 INFO - 'download_minidump_stackwalk': True, 04:51:29 INFO - 'download_symbols': 'ondemand', 04:51:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:51:29 INFO - 'tooltool.py': '/tools/tooltool.py', 04:51:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:51:29 INFO - '/tools/misc-python/virtualenv.py')}, 04:51:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:51:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:51:29 INFO - 'installer_path': 'installer.exe', 04:51:29 INFO - 'log_level': 'info', 04:51:29 INFO - 'log_name': 'talos', 04:51:29 INFO - 'log_to_console': True, 04:51:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:51:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:51:29 INFO - 'opt_config_files': (), 04:51:29 INFO - 'pip_index': False, 04:51:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:51:29 INFO - 'cmd': ('bash', 04:51:29 INFO - '-c', 04:51:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:51:29 INFO - 'enabled': True, 04:51:29 INFO - 'halt_on_failure': False, 04:51:29 INFO - 'name': 'check_screen_resolution'},), 04:51:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:51:29 INFO - 'cmd': ('bash', 04:51:29 INFO - '-c', 04:51:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:51:29 INFO - 'enabled': True, 04:51:29 INFO - 'halt_on_failure': False, 04:51:29 INFO - 'name': 'check_screen_resolution'},), 04:51:29 INFO - 'run_cmd_checks_enabled': True, 04:51:29 INFO - 'sps_profile': False, 04:51:29 INFO - 'sps_profile_interval': 0, 04:51:29 INFO - 'suite': 'dromaeojs-e10s', 04:51:29 INFO - 'system_bits': '32', 04:51:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:51:29 INFO - 'title': 't-yosemite-r7-0239', 04:51:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:51:29 INFO - 'use_talos_json': True, 04:51:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:51:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:51:29 INFO - 'work_dir': 'build'} 04:51:29 INFO - [mozharness: 2017-02-10 12:51:29.878040Z] Running clobber step. 04:51:29 INFO - Running pre-action listener: _resource_record_pre_action 04:51:29 INFO - Running main action method: clobber 04:51:29 INFO - rmtree: /builds/slave/test/build 04:51:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:51:32 INFO - Running post-action listener: _resource_record_post_action 04:51:32 INFO - [mozharness: 2017-02-10 12:51:32.985486Z] Finished clobber step (success) 04:51:32 INFO - [mozharness: 2017-02-10 12:51:32.985674Z] Running read-buildbot-config step. 04:51:32 INFO - Running pre-action listener: _resource_record_pre_action 04:51:32 INFO - Running main action method: read_buildbot_config 04:51:32 INFO - Using buildbot properties: 04:51:32 INFO - { 04:51:32 INFO - "project": "", 04:51:32 INFO - "product": "firefox", 04:51:32 INFO - "script_repo_revision": "production", 04:51:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 04:51:32 INFO - "repository": "", 04:51:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 04:51:32 INFO - "buildid": "20170210033723", 04:51:32 INFO - "pgo_build": "False", 04:51:32 INFO - "basedir": "/builds/slave/test", 04:51:32 INFO - "buildnumber": 31, 04:51:32 INFO - "slavename": "t-yosemite-r7-0239", 04:51:32 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 04:51:32 INFO - "platform": "yosemite_r7", 04:51:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 04:51:32 INFO - "branch": "mozilla-aurora", 04:51:32 INFO - "repo_path": "releases/mozilla-aurora", 04:51:32 INFO - "revision": "9a08294a8eb8cea3683c6355decebce1d1876fa1", 04:51:32 INFO - "stage_platform": "macosx64", 04:51:32 INFO - "builduid": "0de97d8d1cd347f98784d51f93114db7", 04:51:32 INFO - "slavebuilddir": "test" 04:51:32 INFO - } 04:51:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 04:51:32 INFO - Running post-action listener: _resource_record_post_action 04:51:32 INFO - [mozharness: 2017-02-10 12:51:32.991275Z] Finished read-buildbot-config step (success) 04:51:32 INFO - [mozharness: 2017-02-10 12:51:32.991457Z] Running download-and-extract step. 04:51:32 INFO - Running pre-action listener: _resource_record_pre_action 04:51:32 INFO - Running main action method: download_and_extract 04:51:32 INFO - mkdir: /builds/slave/test/build/tests 04:51:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:51:32 INFO - trying https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 04:51:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 04:51:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 04:51:34 INFO - Downloaded 1199 bytes. 04:51:34 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 04:51:34 INFO - Using the following test package requirements: 04:51:34 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 04:51:34 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:51:34 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 04:51:34 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:51:34 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 04:51:34 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:51:34 INFO - u'jsshell-mac.zip'], 04:51:34 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:51:34 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 04:51:34 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 04:51:34 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:51:34 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 04:51:34 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:51:34 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 04:51:34 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:51:34 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 04:51:34 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 04:51:34 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 04:51:34 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 04:51:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 04:51:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:51:34 INFO - Fetch https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 04:51:35 INFO - Expected file size: 16357919 04:51:35 INFO - Obtained file size: 16357919 04:51:44 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 04:51:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 04:51:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:51:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 04:51:45 INFO - Expected file size: 16357919 04:51:45 INFO - Obtained file size: 16357919 04:51:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 04:51:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 04:51:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 04:51:55 INFO - Expected file size: 14403485 04:51:55 INFO - Obtained file size: 14403485 04:51: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:51:56 INFO - trying https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 04:51:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 04:51:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 04:51:59 INFO - Downloaded 73827337 bytes. 04:51:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 04:51:59 INFO - mkdir: /builds/slave/test/properties 04:51:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:51:59 INFO - Writing to file /builds/slave/test/properties/build_url 04:51:59 INFO - Contents: 04:51:59 INFO - build_url:https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 04:51:59 INFO - Running post-action listener: _resource_record_post_action 04:51:59 INFO - Running post-action listener: set_extra_try_arguments 04:51:59 INFO - [mozharness: 2017-02-10 12:51:59.962675Z] Finished download-and-extract step (success) 04:51:59 INFO - [mozharness: 2017-02-10 12:51:59.962853Z] Running populate-webroot step. 04:51:59 INFO - Running pre-action listener: _resource_record_pre_action 04:51:59 INFO - Running main action method: populate_webroot 04:51:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:51:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:51:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:51:59 INFO - '--tppagecycles', 04:51:59 INFO - '7'], 04:51:59 INFO - 'tests': ['tsvgm']}}, 04:51:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:51:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:51:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:51:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:51:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:51:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:51:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:51:59 INFO - 'talos_options': ['--disable-e10s'], 04:51:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:51:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:51:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:51:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:51:59 INFO - 'talos_options': ['--disable-e10s'], 04:51:59 INFO - 'tests': ['damp', 'tps']}, 04:51:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:51:59 INFO - 'tests': ['damp', 'tps']}, 04:51:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:51:59 INFO - 'tests': ['dromaeo_dom']}, 04:51:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:51:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:51:59 INFO - 'tests': ['basic_compositor_video']}, 04:51:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:51:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:51:59 INFO - 'tests': ['a11yr', 04:51:59 INFO - 'ts_paint', 04:51:59 INFO - 'tpaint', 04:51:59 INFO - 'sessionrestore', 04:51:59 INFO - 'sessionrestore_no_auto_restore', 04:51:59 INFO - 'tabpaint']}, 04:51:59 INFO - 'other-e10s': {'tests': ['a11yr', 04:51:59 INFO - 'ts_paint', 04:51:59 INFO - 'tpaint', 04:51:59 INFO - 'sessionrestore', 04:51:59 INFO - 'sessionrestore_no_auto_restore', 04:51:59 INFO - 'tabpaint']}, 04:51:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:51:59 INFO - 'ts_paint', 04:51:59 INFO - 'tpaint', 04:51:59 INFO - 'sessionrestore', 04:51:59 INFO - 'sessionrestore_no_auto_restore', 04:51:59 INFO - 'tabpaint']}, 04:51:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:51:59 INFO - 'ts_paint', 04:51:59 INFO - 'tpaint', 04:51:59 INFO - 'sessionrestore', 04:51:59 INFO - 'sessionrestore_no_auto_restore', 04:51:59 INFO - 'tabpaint']}, 04:51:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:51:59 INFO - 'tests': ['a11yr', 04:51:59 INFO - 'ts_paint', 04:51:59 INFO - 'tpaint', 04:51:59 INFO - 'sessionrestore', 04:51:59 INFO - 'sessionrestore_no_auto_restore', 04:51:59 INFO - 'tabpaint']}, 04:51:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:51:59 INFO - 'tests': ['a11yr', 04:51:59 INFO - 'ts_paint', 04:51:59 INFO - 'tpaint', 04:51:59 INFO - 'sessionrestore', 04:51:59 INFO - 'sessionrestore_no_auto_restore', 04:51:59 INFO - 'tabpaint']}, 04:51:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:51:59 INFO - 'tests': ['tsvgx', 04:51:59 INFO - 'tsvgr_opacity', 04:51:59 INFO - 'tart', 04:51:59 INFO - 'tscrollx', 04:51:59 INFO - 'cart', 04:51:59 INFO - 'tsvg_static']}, 04:51:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:51:59 INFO - 'tsvgr_opacity', 04:51:59 INFO - 'tart', 04:51:59 INFO - 'tscrollx', 04:51:59 INFO - 'cart', 04:51:59 INFO - 'tsvg_static']}, 04:51:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:51:59 INFO - 'talos_options': ['--disable-e10s'], 04:51:59 INFO - 'tests': ['tp5o']}, 04:51:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:51:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:51:59 INFO - 'talos_options': ['--disable-e10s', 04:51:59 INFO - '--xperf_path', 04:51:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:51:59 INFO - 'tests': ['tp5n']}, 04:51:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:51:59 INFO - 'talos_options': ['--xperf_path', 04:51:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:51:59 INFO - 'tests': ['tp5n']}}, 04:51:59 INFO - 'talos.zip': {'path': '', 04:51:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:51:59 INFO - Running post-action listener: _resource_record_post_action 04:51:59 INFO - [mozharness: 2017-02-10 12:51:59.986382Z] Finished populate-webroot step (success) 04:51:59 INFO - [mozharness: 2017-02-10 12:51:59.986537Z] Running create-virtualenv step. 04:51:59 INFO - Running pre-action listener: _resource_record_pre_action 04:51:59 INFO - Running main action method: create_virtualenv 04:51:59 INFO - Creating virtualenv /builds/slave/test/build/venv 04:51:59 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:51:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:52:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:52:00 INFO - Using real prefix '/tools/python27' 04:52:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:52:01 INFO - Installing distribute.............................................................................................................................................................................................done. 04:52:05 INFO - Installing pip.................done. 04:52:05 INFO - Return code: 0 04:52:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:52:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:52:05 INFO - Reading from file tmpfile_stdout 04:52:05 INFO - Output received: 04:52:05 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:52:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:52:05 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:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:05 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:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:05 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 0x100d85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d88768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e2cf75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b63d30>, '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 0x100a8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b58be8>, '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.9TUJl7S2A6/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.8gnxpa219T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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:52:05 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:52:05 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:52:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9TUJl7S2A6/Render', 04:52:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:05 INFO - 'HOME': '/Users/cltbld', 04:52:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:05 INFO - 'LOGNAME': 'cltbld', 04:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:05 INFO - 'MOZ_NO_REMOTE': '1', 04:52:05 INFO - 'NO_EM_RESTART': '1', 04:52:05 INFO - 'PAGER': '/bin/cat', 04:52:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:05 INFO - 'PWD': '/builds/slave/test', 04:52:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:05 INFO - 'SHELL': '/bin/bash', 04:52:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8gnxpa219T/Listeners', 04:52:05 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 04:52:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:05 INFO - 'USER': 'cltbld', 04:52:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:05 INFO - 'XPC_FLAGS': '0x0', 04:52:05 INFO - 'XPC_SERVICE_NAME': '0', 04:52:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:05 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:52:05 INFO - Cleaning up... 04:52:05 INFO - Return code: 0 04:52:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:52:05 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:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:05 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:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:05 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 0x100d85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d88768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e2cf75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b63d30>, '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 0x100a8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b58be8>, '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.9TUJl7S2A6/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.8gnxpa219T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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:52:05 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:52:05 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:52:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9TUJl7S2A6/Render', 04:52:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:05 INFO - 'HOME': '/Users/cltbld', 04:52:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:05 INFO - 'LOGNAME': 'cltbld', 04:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:05 INFO - 'MOZ_NO_REMOTE': '1', 04:52:05 INFO - 'NO_EM_RESTART': '1', 04:52:05 INFO - 'PAGER': '/bin/cat', 04:52:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:05 INFO - 'PWD': '/builds/slave/test', 04:52:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:05 INFO - 'SHELL': '/bin/bash', 04:52:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8gnxpa219T/Listeners', 04:52:05 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 04:52:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:05 INFO - 'USER': 'cltbld', 04:52:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:05 INFO - 'XPC_FLAGS': '0x0', 04:52:05 INFO - 'XPC_SERVICE_NAME': '0', 04:52:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:06 INFO - Downloading/unpacking psutil>=3.1.1 04:52:06 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:52:06 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:52:06 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:52:06 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:52:06 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:52:06 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:52:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:52:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:52:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:52:10 INFO - Installing collected packages: psutil 04:52:10 INFO - Running setup.py install for psutil 04:52:11 INFO - building 'psutil._psutil_osx' extension 04:52:11 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:52:11 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:52:11 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:52:12 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:52:12 INFO - building 'psutil._psutil_posix' extension 04:52:12 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:52:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:52:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:52:12 INFO - ^ 04:52:12 INFO - 1 warning generated. 04:52:12 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:52:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:52:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:52:12 INFO - Successfully installed psutil 04:52:12 INFO - Cleaning up... 04:52:12 INFO - Return code: 0 04:52:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:52:12 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:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:12 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:52:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:12 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 0x100d85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d88768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e2cf75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b63d30>, '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 0x100a8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b58be8>, '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.9TUJl7S2A6/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.8gnxpa219T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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:52:12 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:52:12 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:52:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9TUJl7S2A6/Render', 04:52:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:12 INFO - 'HOME': '/Users/cltbld', 04:52:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:12 INFO - 'LOGNAME': 'cltbld', 04:52:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:12 INFO - 'MOZ_NO_REMOTE': '1', 04:52:12 INFO - 'NO_EM_RESTART': '1', 04:52:12 INFO - 'PAGER': '/bin/cat', 04:52:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:12 INFO - 'PWD': '/builds/slave/test', 04:52:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:12 INFO - 'SHELL': '/bin/bash', 04:52:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8gnxpa219T/Listeners', 04:52:12 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 04:52:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:12 INFO - 'USER': 'cltbld', 04:52:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:12 INFO - 'XPC_FLAGS': '0x0', 04:52:12 INFO - 'XPC_SERVICE_NAME': '0', 04:52:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:52:12 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:52:12 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:52:12 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:52:12 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:52:12 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:52:12 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:52:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:52:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:52:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:52:16 INFO - Installing collected packages: mozsystemmonitor 04:52:16 INFO - Running setup.py install for mozsystemmonitor 04:52:16 INFO - Successfully installed mozsystemmonitor 04:52:16 INFO - Cleaning up... 04:52:16 INFO - Return code: 0 04:52:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:52:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100d85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d88768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e2cf75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b63d30>, '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 0x100a8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b58be8>, '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.9TUJl7S2A6/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.8gnxpa219T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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:52:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:52:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:52:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9TUJl7S2A6/Render', 04:52:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:17 INFO - 'HOME': '/Users/cltbld', 04:52:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:17 INFO - 'LOGNAME': 'cltbld', 04:52:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:17 INFO - 'MOZ_NO_REMOTE': '1', 04:52:17 INFO - 'NO_EM_RESTART': '1', 04:52:17 INFO - 'PAGER': '/bin/cat', 04:52:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:17 INFO - 'PWD': '/builds/slave/test', 04:52:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:17 INFO - 'SHELL': '/bin/bash', 04:52:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8gnxpa219T/Listeners', 04:52:17 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 04:52:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:17 INFO - 'USER': 'cltbld', 04:52:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:17 INFO - 'XPC_FLAGS': '0x0', 04:52:17 INFO - 'XPC_SERVICE_NAME': '0', 04:52:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:17 INFO - Downloading/unpacking jsonschema==2.5.1 04:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:52:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:52:21 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:52:21 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:52:21 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:52:21 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:52:21 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:52:21 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:52:22 INFO - Downloading functools32-3.2.3-2.tar.gz 04:52:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:52:22 INFO - warning: no files found matching '*.txt' 04:52:22 INFO - no previously-included directories found matching 'build' 04:52:22 INFO - no previously-included directories found matching 'dist' 04:52:22 INFO - no previously-included directories found matching '.git*' 04:52:22 INFO - Installing collected packages: jsonschema, functools32 04:52:22 INFO - Running setup.py install for functools32 04:52:22 INFO - warning: no files found matching '*.txt' 04:52:22 INFO - no previously-included directories found matching 'build' 04:52:22 INFO - no previously-included directories found matching 'dist' 04:52:22 INFO - no previously-included directories found matching '.git*' 04:52:23 INFO - Successfully installed jsonschema functools32 04:52:23 INFO - Cleaning up... 04:52:23 INFO - Return code: 0 04:52:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100d85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d88768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e2cf75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b63d30>, '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 0x100a8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b58be8>, '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.9TUJl7S2A6/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.8gnxpa219T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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:52:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:52:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:52:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9TUJl7S2A6/Render', 04:52:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:23 INFO - 'HOME': '/Users/cltbld', 04:52:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:23 INFO - 'LOGNAME': 'cltbld', 04:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:23 INFO - 'MOZ_NO_REMOTE': '1', 04:52:23 INFO - 'NO_EM_RESTART': '1', 04:52:23 INFO - 'PAGER': '/bin/cat', 04:52:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:23 INFO - 'PWD': '/builds/slave/test', 04:52:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:23 INFO - 'SHELL': '/bin/bash', 04:52:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8gnxpa219T/Listeners', 04:52:23 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 04:52:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:23 INFO - 'USER': 'cltbld', 04:52:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:23 INFO - 'XPC_FLAGS': '0x0', 04:52:23 INFO - 'XPC_SERVICE_NAME': '0', 04:52:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:52:23 INFO - Cleaning up... 04:52:23 INFO - Return code: 0 04:52:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100d85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d88768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e2cf75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b63d30>, '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 0x100a8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b58be8>, '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.9TUJl7S2A6/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.8gnxpa219T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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:52:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:52:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:52:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9TUJl7S2A6/Render', 04:52:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:23 INFO - 'HOME': '/Users/cltbld', 04:52:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:23 INFO - 'LOGNAME': 'cltbld', 04:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:23 INFO - 'MOZ_NO_REMOTE': '1', 04:52:23 INFO - 'NO_EM_RESTART': '1', 04:52:23 INFO - 'PAGER': '/bin/cat', 04:52:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:23 INFO - 'PWD': '/builds/slave/test', 04:52:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:23 INFO - 'SHELL': '/bin/bash', 04:52:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8gnxpa219T/Listeners', 04:52:23 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 04:52:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:23 INFO - 'USER': 'cltbld', 04:52:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:23 INFO - 'XPC_FLAGS': '0x0', 04:52:23 INFO - 'XPC_SERVICE_NAME': '0', 04:52:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:23 INFO - Downloading/unpacking blobuploader==1.2.4 04:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:27 INFO - Downloading blobuploader-1.2.4.tar.gz 04:52:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:52:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:52:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:29 INFO - Downloading docopt-0.6.1.tar.gz 04:52:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:52:29 INFO - Installing collected packages: blobuploader, requests, docopt 04:52:29 INFO - Running setup.py install for blobuploader 04:52:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:52:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:52:29 INFO - Running setup.py install for requests 04:52:30 INFO - Running setup.py install for docopt 04:52:30 INFO - Successfully installed blobuploader requests docopt 04:52:30 INFO - Cleaning up... 04:52:30 INFO - Return code: 0 04:52:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:52:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:30 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 0x100d85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d88768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e2cf75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b63d30>, '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 0x100a8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b58be8>, '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.9TUJl7S2A6/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.8gnxpa219T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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:52:30 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:52:30 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:52:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9TUJl7S2A6/Render', 04:52:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:30 INFO - 'HOME': '/Users/cltbld', 04:52:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:30 INFO - 'LOGNAME': 'cltbld', 04:52:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:30 INFO - 'MOZ_NO_REMOTE': '1', 04:52:30 INFO - 'NO_EM_RESTART': '1', 04:52:30 INFO - 'PAGER': '/bin/cat', 04:52:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:30 INFO - 'PWD': '/builds/slave/test', 04:52:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:30 INFO - 'SHELL': '/bin/bash', 04:52:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8gnxpa219T/Listeners', 04:52:30 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 04:52:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:30 INFO - 'USER': 'cltbld', 04:52:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:30 INFO - 'XPC_FLAGS': '0x0', 04:52:30 INFO - 'XPC_SERVICE_NAME': '0', 04:52:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:52:31 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-KtF2Y9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:52:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:52:31 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-HyHDAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:52:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:52:31 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-l5L96I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:52:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:52:31 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-evIVUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:52:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:52:31 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-9KYRFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:52:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:52:31 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-QPGsly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:52:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:52:31 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-96Hdu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:52:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:52:31 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-qbE3Pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:52:32 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-ZTIGqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:52:32 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-cbothb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:52:32 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-UxA56O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:52:32 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-jbfiE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:52:32 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-uPNthw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:52:32 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-tM3MNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:32 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-Dyx_ij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:52:32 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-QV1Wse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:52:33 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-ALdegS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:52:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:52:33 INFO - Running setup.py install for manifestparser 04:52:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:52:33 INFO - Running setup.py install for mozcrash 04:52:33 INFO - Running setup.py install for mozdebug 04:52:33 INFO - Running setup.py install for mozdevice 04:52:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:52:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:52:34 INFO - Running setup.py install for mozfile 04:52:34 INFO - Running setup.py install for mozhttpd 04:52:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:52:34 INFO - Running setup.py install for mozinfo 04:52:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:52:34 INFO - Running setup.py install for mozInstall 04:52:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:52:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:52:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:52:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:52:34 INFO - Running setup.py install for mozleak 04:52:34 INFO - Running setup.py install for mozlog 04:52:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:52:35 INFO - Running setup.py install for moznetwork 04:52:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:52:35 INFO - Running setup.py install for mozprocess 04:52:35 INFO - Running setup.py install for mozprofile 04:52:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:52:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:52:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:52:35 INFO - Running setup.py install for mozrunner 04:52:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:52:36 INFO - Running setup.py install for mozscreenshot 04:52:36 INFO - Running setup.py install for moztest 04:52:36 INFO - Running setup.py install for mozversion 04:52:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:52:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:52:36 INFO - Cleaning up... 04:52:36 INFO - Return code: 0 04:52:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:52:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:52:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:36 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 0x100d85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d88768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e2cf75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b63d30>, '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 0x100a8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b58be8>, '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.9TUJl7S2A6/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.8gnxpa219T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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:52:36 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:52:36 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:52:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9TUJl7S2A6/Render', 04:52:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:36 INFO - 'HOME': '/Users/cltbld', 04:52:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:36 INFO - 'LOGNAME': 'cltbld', 04:52:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:36 INFO - 'MOZ_NO_REMOTE': '1', 04:52:36 INFO - 'NO_EM_RESTART': '1', 04:52:36 INFO - 'PAGER': '/bin/cat', 04:52:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:36 INFO - 'PWD': '/builds/slave/test', 04:52:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:36 INFO - 'SHELL': '/bin/bash', 04:52:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8gnxpa219T/Listeners', 04:52:36 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 04:52:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:36 INFO - 'USER': 'cltbld', 04:52:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:36 INFO - 'XPC_FLAGS': '0x0', 04:52:36 INFO - 'XPC_SERVICE_NAME': '0', 04:52:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:52:36 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-vjaTT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:52:37 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:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:52:37 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-ASlulf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:52:37 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:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:52:37 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-DCbZ2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:52:37 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:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:52:37 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-xmNp6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:52:37 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-AAwKck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:52:37 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:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:52:37 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-4PQrii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:52:37 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:52:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:52:37 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-QUBm69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:52:38 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:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:52:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-T_FFHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:52:38 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:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:52:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-l1E1BA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:52:38 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:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:52:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-FErFHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:52:38 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:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:52:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-lf0UfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:52:38 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:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:52:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-XU01GU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:52:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-MbtJAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:52:38 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:52:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:52:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-RpigA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:52:39 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:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:39 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-3ixuEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:39 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:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:52:39 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-LR9700-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:52:39 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:52:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:52:39 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-7Z68Ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:52:39 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:52:39 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:52:39 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:52:39 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:52:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:52:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:52:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:52:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:52:43 INFO - Downloading blessings-1.6.tar.gz 04:52:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:52:43 INFO - Installing collected packages: blessings 04:52:43 INFO - Running setup.py install for blessings 04:52:43 INFO - Successfully installed blessings 04:52:43 INFO - Cleaning up... 04:52:43 INFO - Return code: 0 04:52:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:52:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:52:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:52:43 INFO - Reading from file tmpfile_stdout 04:52:43 INFO - Current package versions: 04:52:43 INFO - blessings == 1.6 04:52:43 INFO - blobuploader == 1.2.4 04:52:43 INFO - docopt == 0.6.1 04:52:43 INFO - functools32 == 3.2.3-2 04:52:43 INFO - jsonschema == 2.5.1 04:52:43 INFO - manifestparser == 1.1 04:52:43 INFO - mozInstall == 1.12 04:52:43 INFO - mozcrash == 1.0 04:52:43 INFO - mozdebug == 0.1 04:52:43 INFO - mozdevice == 0.49 04:52:43 INFO - mozfile == 1.2 04:52:43 INFO - mozhttpd == 0.7 04:52:43 INFO - mozinfo == 0.9 04:52:43 INFO - mozleak == 0.1 04:52:43 INFO - mozlog == 3.4 04:52:43 INFO - moznetwork == 0.27 04:52:43 INFO - mozprocess == 0.24 04:52:43 INFO - mozprofile == 0.28 04:52:43 INFO - mozrunner == 6.13 04:52:43 INFO - mozscreenshot == 0.1 04:52:43 INFO - mozsystemmonitor == 0.3 04:52:43 INFO - moztest == 0.8 04:52:43 INFO - mozversion == 1.4 04:52:43 INFO - psutil == 3.1.1 04:52:43 INFO - requests == 1.2.3 04:52:43 INFO - wsgiref == 0.1.2 04:52:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:52: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:52:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52: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:52:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:43 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 0x100d85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d88768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e2cf75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b63d30>, '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 0x100a8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b58be8>, '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.9TUJl7S2A6/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.8gnxpa219T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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:52:43 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:52:43 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:52:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9TUJl7S2A6/Render', 04:52:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:43 INFO - 'HOME': '/Users/cltbld', 04:52:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:43 INFO - 'LOGNAME': 'cltbld', 04:52:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:43 INFO - 'MOZ_NO_REMOTE': '1', 04:52:43 INFO - 'NO_EM_RESTART': '1', 04:52:43 INFO - 'PAGER': '/bin/cat', 04:52:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:43 INFO - 'PWD': '/builds/slave/test', 04:52:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:43 INFO - 'SHELL': '/bin/bash', 04:52:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8gnxpa219T/Listeners', 04:52:43 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 04:52:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:43 INFO - 'USER': 'cltbld', 04:52:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:43 INFO - 'XPC_FLAGS': '0x0', 04:52:43 INFO - 'XPC_SERVICE_NAME': '0', 04:52:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:44 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:52:44 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:52:44 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:52:44 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:52:44 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:52:44 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:52:44 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:52:44 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:52:44 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:52:44 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:52:44 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:52:44 INFO - Cleaning up... 04:52:44 INFO - Return code: 0 04:52:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:52:44 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:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:44 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:52:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:44 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 0x100d85b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d88768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5e2cf75c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b63d30>, '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 0x100a8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b58be8>, '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.9TUJl7S2A6/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.8gnxpa219T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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:52:44 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:52:44 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:52:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9TUJl7S2A6/Render', 04:52:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:52:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:52:44 INFO - 'HOME': '/Users/cltbld', 04:52:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:52:44 INFO - 'LOGNAME': 'cltbld', 04:52:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:44 INFO - 'MOZ_NO_REMOTE': '1', 04:52:44 INFO - 'NO_EM_RESTART': '1', 04:52:44 INFO - 'PAGER': '/bin/cat', 04:52:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:52:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:44 INFO - 'PWD': '/builds/slave/test', 04:52:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:52:44 INFO - 'SHELL': '/bin/bash', 04:52:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8gnxpa219T/Listeners', 04:52:44 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 04:52:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:52:44 INFO - 'USER': 'cltbld', 04:52:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:52:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:52:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:44 INFO - 'XPC_FLAGS': '0x0', 04:52:44 INFO - 'XPC_SERVICE_NAME': '0', 04:52:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:52:44 INFO - Cleaning up... 04:52:44 INFO - Return code: 0 04:52:44 INFO - Running post-action listener: _resource_record_post_action 04:52:44 INFO - Running post-action listener: _start_resource_monitoring 04:52:44 INFO - Starting resource monitoring. 04:52:44 INFO - [mozharness: 2017-02-10 12:52:44.740633Z] Finished create-virtualenv step (success) 04:52:44 INFO - [mozharness: 2017-02-10 12:52:44.741867Z] Running install step. 04:52:44 INFO - Running pre-action listener: _resource_record_pre_action 04:52:44 INFO - Running main action method: install 04:52:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:52:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:52:45 INFO - Reading from file tmpfile_stdout 04:52:45 INFO - Detecting whether we're running mozinstall >=1.0... 04:52:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:52:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:52:45 INFO - Reading from file tmpfile_stdout 04:52:45 INFO - Output received: 04:52:45 INFO - Usage: mozinstall [options] installer 04:52:45 INFO - Options: 04:52:45 INFO - -h, --help show this help message and exit 04:52:45 INFO - -d DEST, --destination=DEST 04:52:45 INFO - Directory to install application into. [default: 04:52:45 INFO - "/builds/slave/test"] 04:52:45 INFO - --app=APP Application being installed. [default: firefox] 04:52:45 INFO - mkdir: /builds/slave/test/build/application 04:52:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:52:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 04:53:13 INFO - Reading from file tmpfile_stdout 04:53:13 INFO - Output received: 04:53:13 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:53:13 INFO - Running post-action listener: _resource_record_post_action 04:53:13 INFO - [mozharness: 2017-02-10 12:53:13.896469Z] Finished install step (success) 04:53:13 INFO - [mozharness: 2017-02-10 12:53:13.896691Z] Running run-tests step. 04:53:13 INFO - Running pre-action listener: _resource_record_pre_action 04:53:13 INFO - Running main action method: run_tests 04:53:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:53:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:53:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:53:13 INFO - 2017-02-10 04:53:13.915 screenresolution[1649:7492] starting screenresolution argv=screenresolution get 04:53:13 INFO - 2017-02-10 04:53:13.919 screenresolution[1649:7492] Display 0: 1600x1200x32@60 04:53:13 INFO - 2017-02-10 04:53:13.928 screenresolution[1650:7499] starting screenresolution argv=screenresolution list 04:53:13 INFO - Available Modes on Display 0 04:53:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:53:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:53:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:53:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:53:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:53:14 INFO - Intel Iris: 04:53:14 INFO - Chipset Model: Intel Iris 04:53:14 INFO - Type: GPU 04:53:14 INFO - Bus: Built-In 04:53:14 INFO - VRAM (Dynamic, Max): 1536 MB 04:53:14 INFO - Vendor: Intel (0x8086) 04:53:14 INFO - Device ID: 0x0a2e 04:53:14 INFO - Revision ID: 0x0009 04:53:14 INFO - Displays: 04:53:14 INFO - Display: 04:53:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:53:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:53:14 INFO - Display Serial Number: 1600x1200 60 04:53:14 INFO - Main Display: Yes 04:53:14 INFO - Mirror: Off 04:53:14 INFO - Online: Yes 04:53:14 INFO - Rotation: Supported 04:53:14 INFO - Return code: 0 04:53:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:53:14 INFO - Python 2.7.3 04:53:14 INFO - Return code: 0 04:53:14 INFO - grabbing minidump binary from tooltool 04:53:14 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:53:14 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 0x100b63d30>, '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 0x100a8adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100b58be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:53:14 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:53:14 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:53:14 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:53:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:53:14 INFO - Return code: 0 04:53:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:53:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:53:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:53:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:53:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:53:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0239', '--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:53:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0239 --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:53:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9TUJl7S2A6/Render', 04:53:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:53:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:53:14 INFO - 'HOME': '/Users/cltbld', 04:53:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:53:14 INFO - 'LOGNAME': 'cltbld', 04:53:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:53:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:53:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:14 INFO - 'MOZ_NO_REMOTE': '1', 04:53:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:53:14 INFO - 'NO_EM_RESTART': '1', 04:53:14 INFO - 'PAGER': '/bin/cat', 04:53:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:53:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:14 INFO - 'PWD': '/builds/slave/test', 04:53:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:53:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:53:14 INFO - 'SHELL': '/bin/bash', 04:53:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8gnxpa219T/Listeners', 04:53:14 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 04:53:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:53:14 INFO - 'USER': 'cltbld', 04:53:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:53:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:53:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:14 INFO - 'XPC_FLAGS': '0x0', 04:53:14 INFO - 'XPC_SERVICE_NAME': '0', 04:53:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:53:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0239', '--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:53:14 INFO - mozversion application_buildid: 20170210033723 04:53:14 INFO - mozversion application_changeset: 9a08294a8eb8cea3683c6355decebce1d1876fa1 04:53:14 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:53:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:53:14 INFO - mozversion application_name: Firefox 04:53:14 INFO - mozversion application_remotingname: firefox-dev 04:53:14 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:53:14 INFO - mozversion application_vendor: Mozilla 04:53:14 INFO - mozversion application_version: 53.0a2 04:53:14 INFO - mozversion platform_buildid: 20170210033723 04:53:14 INFO - mozversion platform_changeset: 9a08294a8eb8cea3683c6355decebce1d1876fa1 04:53:14 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:53:14 INFO - mozversion platform_version: 53.0a2 04:53:14 INFO - using testdate: 1486731194 04:53:14 INFO - actual date: 1486731194 04:53:14 INFO - starting webserver on 'localhost:49244' 04:53:14 INFO - SUITE-START | Running 2 tests 04:53:14 INFO - TEST-START | dromaeo_css 04:53:14 INFO - operating with platform_type : mac_ 04:53:14 INFO - Initialising browser for dromaeo_css test... 04:53:14 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpG1jYkf/profile http://localhost:49244/getInfo.html) 04:53:18 INFO - PROCESS | 1660 | 2017-02-10 04:53:18.680 plugin-container[1662:7659] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x914b, name = 'com.apple.tsm.portname' 04:53:18 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:53:18 INFO - PROCESS | 1660 | 2017-02-10 04:53:18.687 plugin-container[1662:7659] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b0f, name = 'com.apple.CFPasteboardClient' 04:53:18 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:53:18 INFO - PROCESS | 1660 | 2017-02-10 04:53:18.687 plugin-container[1662:7659] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:53:18 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 04:53:18 INFO - PROCESS | 1660 | colorDepth:24 04:53:18 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 04:53:18 INFO - PROCESS | 1660 | __metrics 04:53:18 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 04:53:19 INFO - TEST-INFO | 1660: exit 0 04:53:19 INFO - Browser initialized. 04:53:19 INFO - Running cycle 1/1 for dromaeo_css test... 04:53:19 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpG1jYkf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:53:23 INFO - PROCESS | 1663 | 2017-02-10 04:53:23.344 plugin-container[1664:7792] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 04:53:23 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:53:23 INFO - PROCESS | 1663 | 2017-02-10 04:53:23.345 plugin-container[1664:7792] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4713, name = 'com.apple.CFPasteboardClient' 04:53:23 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:53:23 INFO - PROCESS | 1663 | 2017-02-10 04:53:23.345 plugin-container[1664:7792] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:53:32 INFO - PROCESS | 1663 | RSS: Main: 218234880 04:53:32 INFO - PROCESS | 1663 | 04:56:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 04:56:36 INFO - PROCESS | 1663 | RSS: Main: 238845952 04:56:36 INFO - PROCESS | 1663 | 04:58:20 INFO - PROCESS | 1663 | ************************* 04:58:20 INFO - PROCESS | 1663 | A coding exception was thrown and uncaught in a Task. 04:58:20 INFO - PROCESS | 1663 | 04:58:20 ERROR - PROCESS | 1663 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:58:20 INFO - PROCESS | 1663 | Full stack: 04:58:20 INFO - PROCESS | 1663 | ************************* 04:59:39 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 04:59:39 INFO - PROCESS | 1663 | RSS: Main: 249823232 04:59:39 INFO - PROCESS | 1663 | 05:02:42 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 05:02:42 INFO - PROCESS | 1663 | RSS: Main: 256172032 05:02:42 INFO - PROCESS | 1663 | 05:05:45 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 05:05:45 INFO - PROCESS | 1663 | RSS: Main: 253042688 05:05:45 INFO - PROCESS | 1663 | 05:08:49 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 05:08:49 INFO - PROCESS | 1663 | RSS: Main: 256479232 05:08:49 INFO - PROCESS | 1663 | 05:11:52 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 05:11:52 INFO - PROCESS | 1663 | RSS: Main: 258179072 05:11:52 INFO - PROCESS | 1663 | 05:11:52 INFO - PROCESS | 1663 | __start_tp_report 05:11:52 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 05:11:52 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 05:11:52 INFO - PROCESS | 1663 | |i|pagename|runs| 05:11:52 INFO - PROCESS | 1663 | |0;dojo.html;2032.6950957356394;2252.324302709188;2261.525079733257;2306.527161931804;2317.8261630377724;3696.4085746280603;3738.6074462181487;3741.1021354874806;3761.5298087739034;3764.5482542094987;3727.384981478053;3734;3750.512433383656;3787.8674246401383;3806.2006978534473;3357.9926022193367;3428.4000299947534;3470.2192006798523;3494.615592284853;3597.3165098631175;3463.6882680558747;3478.9737027576925;3486.738494612914;3508.9298214035703;3587.905688764926;3413.9146521337043;3448.758586898918;3485.7734247273975;3524.841382137797;3531.434970404736;3351.128706536318;3385.5598772159583;3435.9656403435897;3443;3446.207372304359;2667.2531691126514;2935.398243360106;2950.9114726558237;3369.898903032913;3445.1214940190343;3763.209725957552;3806.8286927088348;3818.996806759339;3934.9016274593077;3936.114374265796;3263.608366995952;4038.7778672173017;4072.859599312197;4159.376093585956;4189.811458484345;4089.284375234352;4090.1615168890603;4137.524184718765;4165.854195103157;4197.895052623679;3808.219315040438;3828.2917660232947;3859.9771060668945;3878.127421330178;3884.2814079395403;3477.8608855646;3830.889042177793;3939.980300098481;4092.7748973806133;4351.912021994502;3323.900282991514;3337.0489410517885;3957.703172262092;3997.8800635980965;3999.5400528939545;3516.701080408143;4007.957930937977;4063.2889244382113;4075.673946084306;4184.958150418518;3900.1224724436775;3966.2464131814863;3966.8413263469715;3969.5236571611013;4025.657819085352;3902.9609703902597;4036.798160091984;4083.591640835952;4122.91754164915;4197.8320867165585;3157.778955473095;3189.728873045817;3212.3093534890113;3249.8862539811466;3382.6279109297957;2807.4385122975486;2906.7182261924286;2922.5616157576533;2946.9705302946695;2965.303153758865;3198.2484116232667;3673.94489082664;3704.759190652599;3717.5724791648768;3747.737658363943;4083.97958010214;4197.664186865043;4233.872983810491;4283.571642835692;4293.592108749664;3696.2053158571034;3703.5555733312176;3795.753276037104;3835.2521258354354;3886.4753258309765;2908.2438565972575;3084.6915308468974;3464.532288141069;3541.8488991078;3596.8381422836037;3619.601843797233;4261.403403523509;4300.4194433751045;4317.942104184495;4369.300911854088;4043.474348334698;4086.2644723949975;4177.707560470738;4230.365445183247;4234.724742891703;4100.835966561304;4125.50493940729;4272.487301523837;4276.31578947367;4315.115401342655;4014.7791871446184;4066.877993660195;4069.4099355593867;4078.6533144683035;4128.195001974706;3430.4168291390024;3882.398228274622;4035.616616421435;4187.811548480387;4199.097194103282;4076.8776936691947;4214.7471151731;4250.341197114452;4276.037891474393;4302.935455968226;4310.633596144237;4316.985508405585;4385.364122202327;4397.626201772885;4425.468943726773;3681.4477828325967;4147.97926010368;4224.725392849455;4311.3964045034345;4383.561643835591;3542.220711443478;4299.312110062375;4303.569643035797;4348.9130217396105;4506.819727211006;4104.507459104927;4157.9376309354775;4161.8959526012095;4349.543297953669;4382.693211475166 05:11:52 INFO - PROCESS | 1663 | |1;ext.html;324.73209602078293;333.6713337362698;343.3116601214564;363.4765937050647;373.96073412291724;25284.49431011381;28455.288617784565;29614.407711845775;30092;30554.69445305546;24612.630810537856;30839.537406938915;31540.684593154063;32139.83930080346;32179.195520112007;5185.818496352628;5611.9438805612035;6409.743610255584;6421.518386121036;6527.216733991928;5755.366909639936;7439.814004649873;7762.184970578092;7866.724664636738;8064.233897779702;7729.961350193241;7730.806729831743;7764.417668674843;7854.900313956051;8027.558484283362;7770.650320735552;7851.45039847211;7996.800079997988;8011.958445402119;8053.395995300376;7784.688612455495;7816.570088645122;7897.052353717533;7902.446828721992;7954.642041108106;28393.432131357466;28829.56755648667;29161.854190729122;29187.124386268446;29631;2601.2456387647558;2657.720939301384;2785.7632101271415;2785.874635641401;2808.648918885139;20840.791592084188;21026;21377.67933480999;21462.819544925023;21625.24311649085;2156.2345367394505;2197.2419515267206;2203.537257175995;2219.0125394199586;2257.7290725113094;267931;325138;329772;332536.99999999756;341233;45557.54442455533;46016;46852.7657361711;46958.76520617375;47839.282410763866;47483.81290467766;47793.80515487141;48280.5171948283;48437.757811210715;48468;11134.832977505344;11386.975172234539;11625.18623696333;11717.88282117168;11738.178327517162;7779.57212353326;8233;8650.87023694645;8792.692255771017;8898.510581917928;10920.945395272973;11479.426028698532;12377.938110309391;12765.297908615115;13215.008874334271;4527.7509736964785;4607.18669234535;4994.850154495371;5017.648764586444;5156.1750119980625;560.9186667933127;584.2967749011584;589.837794606478;604.727872457396;628.5097623853402;19937.302194423126;20137.49656258576;20751.481212969797;21535;21984.780152198593;584.5177728374108;584.5878655547829;604.4952464691944;743.5947408662292;847.1824689174975;8094.028716553933;9573.18627916621;9944.552495137736;9978;10075.697729068139;407141;416149;416496;418790;432603;30709.846450767604;36743.081422964104;37012.259754804756;38008;38687.6131238684;944.5324564340696;958.9547393341292;959.9280053995839;962.993671613152;986.7483791633088;15607.649293465538;17047.48857534276;18383;18656;19913;29088.7091129086;31840.885569004975;32334.83832580822;32691.673083268863;32913.34173316568;349731;354151;364951;365702;370706;14477.855221447651;17336.133193340534;17403.21685524129;17420.651586968444;17430.73853892218;28711.282217944718;28991.710082898902;29768.255793605334;30109.548356774227;30234.84882575573;21372.89313553422;23375.7143357117;24525.632115517925;24739.25782226536;25719.614205786555;25454.109106181193;26632.60051099274;27737.583936241375;28709.569356460088;29061.41877162487;27389.726102739514;27792.16623501298;27944.16167514978;28169.71830281671;28570.14289571316;23254;23774.88112559426;23995.880020599787;24432.14487492929;25074.122405715712;16333.101679407271;17448.91275543614;17693.911530442267;17962.820371796115;18287.994160320777 05:11:52 INFO - PROCESS | 1663 | |2;jquery.html;241.66720536242624;257.2385738214884;269.53101603210416;282.7229315271034;290.74850254529827;10116.39301641902;10472.424016679079;13177.736445271088;14406.279686015696;14586.197759123244;13337.799933001013;13858.93070534646;14540.200288984102;14651.047681900669;14805.3337599808;3815.9809200953955;4145.917081658365;4151;4151.252774500589;4151.854685086023;3888.2223555288915;3927.7054220933546;4021.1957608478274;4054.493188351456;4112.588741125894;4048.3725022621543;4093.079057212118;4169.72896761711;4188.97905510472;4290.828366865321;4008.8396464141547;4067.7966101694797;4071.491063617048;4132.772697501636;4159.292920203573;3922.235164142993;3930.4693866327966;3967.2462232175794;3973;4074.4295798587923;8989.865152022725;8989.921209454842;8993.910060899372;9007;9021.864672029924;1556.8598826105592;1675.2628843308905;1740.3647668600995;1785.2412724592127;1804.2692709452692;6351.951927931932;7125.7149714011375;8156;8222.917770822274;10713.982171693677;1194.0268681024936;1223.5167108991898;1241.7268200995766;1290.5096063495905;1312.212672396564;90092;91259.54370228107;91994;92474;94987.0501294992;6839.768841608558;7652.770416887391;8131.18688131194;8218.424710270223;8506.36202284831;10535.736606584744;10635.20235982289;10772.515236814363;10790.460476976277;10938.906109388998;5046.697198168122;5474.835754927359;5590.189422533791;5914.822555323347;5958.672273025025;5789.45000224978;5904.7933322334375;5905.881882362329;5926.377730338252;5993.280806303184;2710.891564337444;3261;3265.591801024872;3267.1015470745733;3295.307985323061;1206.2943178240828;1332.1274565159836;1368.3773882883265;1373.642852858264;1399.0696187035508;632.895572230586;646.2890820097855;717.1859938969308;724.6014691919423;737.1559564298955;13853.653658658613;14401.135931844123;14406;14592.343344549521;14667.97324187318;549.0939949083963;611.6483022262217;631.7157279224367;659.8911179655303;663.9336066393322;4311.159323931866;4696.976515117471;7258.673359698826;7260.346568808833;8430.662773488992;509209;519335.80664192874;532226;541307;541384;56281;77227.227727722;99371.00628993762;104478.43282350618;110897.44551277193;660.5442244851038;698.6646409723361;700.6461736822973;713.6966789114606;715.5456285258794;12719.93640031794;12873.712628737427;12890.806637900627;13772.724545508834;14059.578212653636;23622;32085.03744887657;39030.41454378243;40261;45846.54153458423;50143.24785128153;53916.46083539271;56056.318310450755;57877.71061144667;58906;4277.95722042788;4880.219164933594;5072.340595722532;5132.358455193101;5779.219805326227;8401.873971890549;8608.827823443622;8900.42147260426;8919.79175505774;9173.35786494939;6217.813465596039;6615;7522.322990930843;7621.428392870446;7757.883631745415;6703.29615390377;7833.412494062854;8016.318612917968;8110.432269741062;8685.261752751087;7822.295993360397;8381.539015354214;8514.829703405774;8926.69805645181;9214.44713317203;5947.10793380979;6008.279006519071;6271.3415091414745;6277.874442511215;6366.840828979127;7392.260773922565;7525.473216874766;7825;7975.2423519765525;8076.111627720828 05:11:52 INFO - PROCESS | 1663 | |3;mootools.html;869.9739007829766;960.2653969713166;964.1948972607872;979.9612410844505;1014.8782146142464;4795.544423279783;5427.538659213967;5488.012157811592;5732.369439361672;5795.362510123889;5380.273663055486;5755.482006619403;5784.739686714098;5841.415858414157;5957.374475680045;1569.1840243073623;1598.3526671697923;1601.175394671745;1607.4373969110777;1607.517744676598;1545.3895711194073;1582.7309357409267;1586.8095828500595;1588.317023679817;1588.3884704388836;1575.149419313575;1587.984120158801;1605.702944955187;1609.89535680181;1614.7093523165825;1362.7070179911268;1479.186447453896;1554.5880341709455;1555.9611009724679;1574.4568123997203;1432.6633241188313;1450.927453627325;1529.915854627995;1595.2502323907745;1610.0742073307779;5406.405295417501;5606.299212598426;5754.935336962633;5808.651480911159;5873.295204575478;3410.6759857813468;3524.312759011994;3582.695470884978;3587.65917237862;3644.2711457708565;2300.976990230093;2376.2871138658334;2394.97605023951;2416.4804567018177;2420.1529464687396;659.0147729144959;778.9766307010742;796.9003874515686;830.5016989806068;850.3154960256991;7300.817479563053;7304.415646748247;7422.294881986203;7464.850702985964;7468.2531746825425;2982.522796352573;3224.5485632011537;3268.3168168393295;3318.6515415881468;3322.1362445764275;6410.903836442457;6511.186101737283;6561.540692151599;6585.242697089896;6594.439472644783;3031.7423019043626;3588.6052534221217;3592.550931133608;3603.0992251937014;3652.160003199226;2769.9307517312227;2840.403515261818;3310.1393637654382;3352.379809735207;3377.7297816174646;2464.8644324561965;2604.231751633273;2726.2230264374557;2752.8348299102117;2773.764230040469;766.5094339622646;867.96528138875;881.3257857738835;903.3811838890381;921.2860033474111;532.6803917649457;535.1712152928658;544.901917654818;552.9972350138223;557.417498713837;3455.87904423344;3646.908827279339;3841.5966323535627;3874.3994680824517;3957.2085582883537;600.4775845014777;681.1247546902306;687.9518433709692;703.525120543631;706.4348521183135;2536.1884197056766;2537.3022418834967;2551.045829139733;2589.2491177558672;2601.648777415063;6706.698198580978;6730.764423245163;7009.579425234502;7038.472114591324;7068.363847253772;6816.250212476623;6834.863302733917;6902.275261097613;6955.89566156508;6985.301469852974;5132.409772876093;5341;5342.706151161723;5358.544523715528;5450.264214331094;3268.362669279563;3363.276895467486;3453.430184019709;3739.2708421858565;3779.093017675793;4481.7535035572055;6313.463355614824;6326.778562750282;6426.132472116392;6571.441427478718;5911.231539899785;6315.842103947438;6409.519286053658;6464.095026696173;6468.191476065492;4537.274036154199;4726.220173671306;4930.901381972412;4988.950110498994;5003.224500202475;5273.762680679302;5399.973000134974;5454.618176727591;5547.362053363915;5569.303837020372;4813.7593120343145;4928.088303663691;4955.479674634112;4959.156943319716;4967.801287948441;3777.490038844721;4886.462489126265;4892.902141957212;4904.5340692634145;4940.036692845005;4269.7224680395675;4355.825766969286;4373.671974601982;4380.335472520422;4429.335599659948;3325.2518183409506;3356.6978971892645;3376.4260075786683;3377.4764911438765;3500.1074725944723;3326.617438994547;3358.6809253120914;3496.1434448560267;3502.614712381687;3503.3343664703625 05:11:52 INFO - PROCESS | 1663 | |4;prototype.html;216.2776327067594;236.44553521990926;244.278158042983;265.21760805623404;267.46240057484454;3513.631068737784;3918.8628398006076;3924.980375098124;4047.8178481968307;4083.5712250213746;3199.0882598459443;3649.8540058397634;3704.3147017801725;3776.565694945082;3909.2767837949928;2643.3788059805884;2934.5598160275918;3025.0924722583245;3033.7572994160523;3043.1022848259818;3003.8735474197174;3015.251154330494;3068.5090385538206;3083.3062560923836;3134.952975705366;2556.440571251535;2623.6720409948757;2937.251000994754;2971.0789655206845;2989.805662631933;2826.18040768177;2900.4772494440376;2968.827313211291;3016.3816417634334;3025.7276845084048;2206.393241858486;2583.6641236639302;2632.7893768498475;2667.9733202668112;2725.74105459981;3376.8649254030056;3540.406816932365;3763.5295588051495;3793.696504279651;3868.5551161616495;2907.8368652538943;3410.198603328216;3411.3966435775146;3412.607550131742;3498.2129020970333;3152.8738850445957;3645.90360542689;3986.54154772202;4038.878833634966;4151.6886233532305;2478.392793765539;2849.8173258097863;2946.0867131189398;2955.88176472943;2968.5992391027153;4100.753954762724;4177.895552611179;4207.666103912975;4294.5490723473895;4363.759993200372;2502.224310463762;2788.6235358226786;2866.813657112281;2880.6399200099986;2902.753265972374;2867.913962581126;2945.774377057119;2950.6164198654037;2986.596809430699;3011.2020314616643;2773.7503624673877;2846.34534057168;3398.9490157647656;3456.0150357148596;3572.6070132285427;3995.2009598079917;4047.392891066305;4056.817443215062;4091.6522095622204;4117.423560701504;967.5355829202023;988.2884323287221;988.4810474500945;996.4071377530358;1009.3194830607104;629.298332359416;651.2998526583941;669.7287598522606;675.5305062981279;690.4752388184916;3409.8465569049454;3638.452508803895;3731.8017230615555;3760.7367484276383;3876.96123038766;3934.8229329680235;4250.404943307939;4256.467401895867;4292.18448494785;4316.352547117895;3751.643593858579;3789.5073640427117;3801.650414103009;3840.8655697050467;3865.43951127085;1773.973390399145;1849.557345270691;1986.9965667337901;1991.6912878503856;2066.772655007948;3815.931539169037;3978.5822488638855;4012.879613611596;4159.417681524589;4211.894702632398;3497.3070735533497;3734.402495600691;3972.5431575368625;4065.4833568962636;4111.650509706648;3764.7364684471822;3976.562578116405;4040.313146765057;4142;4262.957370426256;3316.3864685033345;3542.4509201073874;3552.5845995160116;3885.048163200034;3969.4839670842607;3549.8112227571714;3911.8630847920185;3988.4814974054348;4050.6354428100435;4065.4714887065675;4096.283150448719;4106.3635136553885;4110.445089912823;4183.477065366829;4186.330187170038;2384.8211384146607;2450.375154335633;2679.4775018871915;2773.70876058011;2808.2308145867974;3410.356973345677;4032.0726232967168;4153.300354769424;4229.809658565434;4248.723832950848;3257.7393808495735;3487.9476807847395;3920.902487035324;3970.3448930926074;3990.3615421532418;3757.576363245746;3984.187194826289;4029.2948733972025;4075.4090656853996;4080.877758616404;3354.2285274387514;3460.6193318735423;3757.643023912724;3812.561555421162;4041.1513582146904;3418.418868792361;3935.822887969991;3990.4014397840556;4038.788363491;4116.958830411658;3283.917902052468;3384.3738908302043;3444.896653100411;3744.831482583345;3781.7920014399474 05:11:52 INFO - PROCESS | 1663 | |5;yui.html;468.7047160289017;514.5214950096408;548.909429944059;552.5358698693097;561.5292712066906;9510.952445237774;10388;10455.68632941013;10466.895331046688;10537.683869483919;7952.483088599237;8072.313853322475;9602.807943841137;9743.853842192355;9792.559334829932;557.559527972902;569.163329905039;581.8479410766685;585.9014348097318;587.4830149468462;448.10379241516966;460.21303570893645;539.1293061705337;563.4787821265334;566.8639526513628;612.4396177497584;615.0743131586955;617.5522746009152;622.1880446017944;624.8937680594287;596.090961284041;617.2716194890028;617.9721912513925;621.8476473264056;624.1636207481996;456.34058785055606;456.3702091114274;460.94238220222474;463.7820224494482;464.23633123511974;8731.476111433334;10220.540075696537;10587.682369528926;10633.415162166079;10856.565737370496;8315.667373305061;9453.385529940531;9462;9817.116459518606;10390.948045259802;6902.968454602138;7783.494072885244;7957.196997844976;8154;8192.467489613156;2297.5290065536524;2470.3330100872845;2475.801935845128;2490.1657944588583;2520.395105174763;53994.46005539973;69240.30759692389;71251.57496850034;71659;73776;6529.444997175198;7287.890681639779;7598.886016709644;7727.343175830118;7763.92236077632;15530.9223453882;15646.608834779221;15847.049177049414;16116.919415402848;16177.94843335203;5047.343845300087;5072.974635126875;5088.465711100355;5230.503102205284;5235.476452354734;2538.8222824402483;2649.417128231786;2659.175655546787;2664.5869890167055;2755.8070935034357;2206.5476577301615;2379.3218932604123;2412.963805542918;2432.6959130108735;2483.2177863972793;476.09066682636;490.2694984473109;493.6371766751435;499.81007217257934;506.1319836480454;470.6493662221598;477.0435277269053;482.2188055350355;484.23974360254766;490.93617829681915;6808.2170550386345;7115.53749006313;7153.034340364088;7241.384482319062;7283.417326613858;426.76741176059124;440.44283980764357;474.6629892776099;475.5078493758943;486.76148687143046;2942.9264268393463;3113.548535462345;3235.902922912316;3457.5505184325875;3534.54050973377;63235.36764632295;68523.65738171278;68665.65667171733;70470.942935856;71756;47216;47582.048359032626;56635.716821415626;59531.10703339453;62538;51941;51959;54501.1824822628;56637;57104;5397.055515284731;6214.161088253028;6408.455281301141;6900.516963812485;6936.895946560907;32570;33963.4338394465;35055.12362190965;35935;36144.638553614124;48792.53622391334;51500.48499514957;52993.7350313246;53178.67053323543;53793.462065378844;8852.760613514241;9347;9434.448830612355;9920.305578609428;10116.08955194036;9919.900800992187;10229.74425639365;10304.587816487257;10479.161667066464;10517.10604598587;9398.20115290208;9531.261681078466;9575.23398128162;9648;9684.564194611401;9156.908430915872;9712.562934667816;9816.011934361015;10060;10319.535620896928;9399.906000940176;9570.425774453555;9570.95214523923;9703.854442183232;9714.465704386044;6817;7686.154523002353;7778.961105194664;8161.632726527439;8252.711155109542;6815.352541508549;7501.962490187514;8187.181281871765;8208.917910820815;8390.136877010098 05:11:52 INFO - PROCESS | 1663 | __end_tp_report 05:11:52 INFO - PROCESS | 1663 | __start_cc_report 05:11:52 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,455 05:11:52 INFO - PROCESS | 1663 | __end_cc_report 05:11:52 INFO - PROCESS | 1663 | __startTimestamp1486732312888__endTimestamp 05:11:52 INFO - PROCESS | 1663 | 05:11:52 INFO - PROCESS | 1663 | ------- Summary: start ------- 05:11:52 INFO - PROCESS | 1663 | Number of tests: 6 05:11:52 INFO - PROCESS | 1663 | 05:11:52 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:3786.19 Median:3884.28 stddev:486.72 (12.5%) stddev-sans-first:468.38 05:11:52 INFO - PROCESS | 1663 | Values: 2032.7 2252.3 2261.5 2306.5 2317.8 3696.4 3738.6 3741.1 3761.5 3764.5 3727.4 3734.0 3750.5 3787.9 3806.2 3358.0 3428.4 3470.2 3494.6 3597.3 3463.7 3479.0 3486.7 3508.9 3587.9 3413.9 3448.8 3485.8 3524.8 3531.4 3351.1 3385.6 3436.0 3443.0 3446.2 2667.3 2935.4 2950.9 3369.9 3445.1 3763.2 3806.8 3819.0 3934.9 3936.1 3263.6 4038.8 4072.9 4159.4 4189.8 4089.3 4090.2 4137.5 4165.9 4197.9 3808.2 3828.3 3860.0 3878.1 3884.3 3477.9 3830.9 3940.0 4092.8 4351.9 3323.9 3337.0 3957.7 3997.9 3999.5 3516.7 4008.0 4063.3 4075.7 4185.0 3900.1 3966.2 3966.8 3969.5 4025.7 3903.0 4036.8 4083.6 4122.9 4197.8 3157.8 3189.7 3212.3 3249.9 3382.6 2807.4 2906.7 2922.6 2947.0 2965.3 3198.2 3673.9 3704.8 3717.6 3747.7 4084.0 4197.7 4233.9 4283.6 4293.6 3696.2 3703.6 3795.8 3835.3 3886.5 2908.2 3084.7 3464.5 3541.8 3596.8 3619.6 4261.4 4300.4 4317.9 4369.3 4043.5 4086.3 4177.7 4230.4 4234.7 4100.8 4125.5 4272.5 4276.3 4315.1 4014.8 4066.9 4069.4 4078.7 4128.2 3430.4 3882.4 4035.6 4187.8 4199.1 4076.9 4214.7 4250.3 4276.0 4302.9 4310.6 4317.0 4385.4 4397.6 4425.5 3681.4 4148.0 4224.7 4311.4 4383.6 3542.2 4299.3 4303.6 4348.9 4506.8 4104.5 4157.9 4161.9 4349.5 4382.7 05:11:52 INFO - PROCESS | 1663 | 05:11:52 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:46335.13 Median:16329.79 stddev:98356.63 (602.3%) stddev-sans-first:98571.88 05:11:52 INFO - PROCESS | 1663 | Values: 324.7 333.7 343.3 363.5 374.0 25284.5 28455.3 29614.4 30092.0 30554.7 24612.6 30839.5 31540.7 32139.8 32179.2 5185.8 5611.9 6409.7 6421.5 6527.2 5755.4 7439.8 7762.2 7866.7 8064.2 7730.0 7730.8 7764.4 7854.9 8027.6 7770.7 7851.5 7996.8 8012.0 8053.4 7784.7 7816.6 7897.1 7902.4 7954.6 28393.4 28829.6 29161.9 29187.1 29631.0 2601.2 2657.7 2785.8 2785.9 2808.6 20840.8 21026.0 21377.7 21462.8 21625.2 2156.2 2197.2 2203.5 2219.0 2257.7 267931.0 325138.0 329772.0 332537.0 341233.0 45557.5 46016.0 46852.8 46958.8 47839.3 47483.8 47793.8 48280.5 48437.8 48468.0 11134.8 11387.0 11625.2 11717.9 11738.2 7779.6 8233.0 8650.9 8792.7 8898.5 10920.9 11479.4 12377.9 12765.3 13215.0 4527.8 4607.2 4994.9 5017.6 5156.2 560.9 584.3 589.8 604.7 628.5 19937.3 20137.5 20751.5 21535.0 21984.8 584.5 584.6 604.5 743.6 847.2 8094.0 9573.2 9944.6 9978.0 10075.7 407141.0 416149.0 416496.0 418790.0 432603.0 30709.8 36743.1 37012.3 38008.0 38687.6 944.5 959.0 959.9 963.0 986.7 15607.6 17047.5 18383.0 18656.0 19913.0 29088.7 31840.9 32334.8 32691.7 32913.3 349731.0 354151.0 364951.0 365702.0 370706.0 14477.9 17336.1 17403.2 17420.7 17430.7 28711.3 28991.7 29768.3 30109.5 30234.8 21372.9 23375.7 24525.6 24739.3 25719.6 25454.1 26632.6 27737.6 28709.6 29061.4 27389.7 27792.2 27944.2 28169.7 28570.1 23254.0 23774.9 23995.9 24432.1 25074.1 16333.1 17448.9 17693.9 17962.8 18288.0 05:11:52 INFO - PROCESS | 1663 | 05:11:52 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:27541.66 Median:257170.68 stddev:87712.20 (34.1%) stddev-sans-first:87934.30 05:11:52 INFO - PROCESS | 1663 | Values: 241.7 257.2 269.5 282.7 290.7 10116.4 10472.4 13177.7 14406.3 14586.2 13337.8 13858.9 14540.2 14651.0 14805.3 3816.0 4145.9 4151.0 4151.3 4151.9 3888.2 3927.7 4021.2 4054.5 4112.6 4048.4 4093.1 4169.7 4189.0 4290.8 4008.8 4067.8 4071.5 4132.8 4159.3 3922.2 3930.5 3967.2 3973.0 4074.4 8989.9 8989.9 8993.9 9007.0 9021.9 1556.9 1675.3 1740.4 1785.2 1804.3 6352.0 7125.7 8156.0 8222.9 10714.0 1194.0 1223.5 1241.7 1290.5 1312.2 90092.0 91259.5 91994.0 92474.0 94987.1 6839.8 7652.8 8131.2 8218.4 8506.4 10535.7 10635.2 10772.5 10790.5 10938.9 5046.7 5474.8 5590.2 5914.8 5958.7 5789.5 5904.8 5905.9 5926.4 5993.3 2710.9 3261.0 3265.6 3267.1 3295.3 1206.3 1332.1 1368.4 1373.6 1399.1 632.9 646.3 717.2 724.6 737.2 13853.7 14401.1 14406.0 14592.3 14668.0 549.1 611.6 631.7 659.9 663.9 4311.2 4697.0 7258.7 7260.3 8430.7 509209.0 519335.8 532226.0 541307.0 541384.0 56281.0 77227.2 99371.0 104478.4 110897.4 660.5 698.7 700.6 713.7 715.5 12719.9 12873.7 12890.8 13772.7 14059.6 23622.0 32085.0 39030.4 40261.0 45846.5 50143.2 53916.5 56056.3 57877.7 58906.0 4278.0 4880.2 5072.3 5132.4 5779.2 8401.9 8608.8 8900.4 8919.8 9173.4 6217.8 6615.0 7522.3 7621.4 7757.9 6703.3 7833.4 8016.3 8110.4 8685.3 7822.3 8381.5 8514.8 8926.7 9214.4 5947.1 6008.3 6271.3 6277.9 6366.8 7392.3 7525.5 7825.0 7975.2 8076.1 05:11:52 INFO - PROCESS | 1663 | 05:11:52 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:3701.98 Median:4804.65 stddev:2032.49 (42.3%) stddev-sans-first:2027.05 05:11:52 INFO - PROCESS | 1663 | Values: 870.0 960.3 964.2 980.0 1014.9 4795.5 5427.5 5488.0 5732.4 5795.4 5380.3 5755.5 5784.7 5841.4 5957.4 1569.2 1598.4 1601.2 1607.4 1607.5 1545.4 1582.7 1586.8 1588.3 1588.4 1575.1 1588.0 1605.7 1609.9 1614.7 1362.7 1479.2 1554.6 1556.0 1574.5 1432.7 1450.9 1529.9 1595.3 1610.1 5406.4 5606.3 5754.9 5808.7 5873.3 3410.7 3524.3 3582.7 3587.7 3644.3 2301.0 2376.3 2395.0 2416.5 2420.2 659.0 779.0 796.9 830.5 850.3 7300.8 7304.4 7422.3 7464.9 7468.3 2982.5 3224.5 3268.3 3318.7 3322.1 6410.9 6511.2 6561.5 6585.2 6594.4 3031.7 3588.6 3592.6 3603.1 3652.2 2769.9 2840.4 3310.1 3352.4 3377.7 2464.9 2604.2 2726.2 2752.8 2773.8 766.5 868.0 881.3 903.4 921.3 532.7 535.2 544.9 553.0 557.4 3455.9 3646.9 3841.6 3874.4 3957.2 600.5 681.1 688.0 703.5 706.4 2536.2 2537.3 2551.0 2589.2 2601.6 6706.7 6730.8 7009.6 7038.5 7068.4 6816.3 6834.9 6902.3 6955.9 6985.3 5132.4 5341.0 5342.7 5358.5 5450.3 3268.4 3363.3 3453.4 3739.3 3779.1 4481.8 6313.5 6326.8 6426.1 6571.4 5911.2 6315.8 6409.5 6464.1 6468.2 4537.3 4726.2 4930.9 4989.0 5003.2 5273.8 5400.0 5454.6 5547.4 5569.3 4813.8 4928.1 4955.5 4959.2 4967.8 3777.5 4886.5 4892.9 4904.5 4940.0 4269.7 4355.8 4373.7 4380.3 4429.3 3325.3 3356.7 3376.4 3377.5 3500.1 3326.6 3358.7 3496.1 3502.6 3503.3 05:11:52 INFO - PROCESS | 1663 | 05:11:52 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:3260.80 Median:3733.10 stddev:984.47 (26.4%) stddev-sans-first:960.34 05:11:52 INFO - PROCESS | 1663 | Values: 216.3 236.4 244.3 265.2 267.5 3513.6 3918.9 3925.0 4047.8 4083.6 3199.1 3649.9 3704.3 3776.6 3909.3 2643.4 2934.6 3025.1 3033.8 3043.1 3003.9 3015.3 3068.5 3083.3 3135.0 2556.4 2623.7 2937.3 2971.1 2989.8 2826.2 2900.5 2968.8 3016.4 3025.7 2206.4 2583.7 2632.8 2668.0 2725.7 3376.9 3540.4 3763.5 3793.7 3868.6 2907.8 3410.2 3411.4 3412.6 3498.2 3152.9 3645.9 3986.5 4038.9 4151.7 2478.4 2849.8 2946.1 2955.9 2968.6 4100.8 4177.9 4207.7 4294.5 4363.8 2502.2 2788.6 2866.8 2880.6 2902.8 2867.9 2945.8 2950.6 2986.6 3011.2 2773.8 2846.3 3398.9 3456.0 3572.6 3995.2 4047.4 4056.8 4091.7 4117.4 967.5 988.3 988.5 996.4 1009.3 629.3 651.3 669.7 675.5 690.5 3409.8 3638.5 3731.8 3760.7 3877.0 3934.8 4250.4 4256.5 4292.2 4316.4 3751.6 3789.5 3801.7 3840.9 3865.4 1774.0 1849.6 1987.0 1991.7 2066.8 3815.9 3978.6 4012.9 4159.4 4211.9 3497.3 3734.4 3972.5 4065.5 4111.7 3764.7 3976.6 4040.3 4142.0 4263.0 3316.4 3542.5 3552.6 3885.0 3969.5 3549.8 3911.9 3988.5 4050.6 4065.5 4096.3 4106.4 4110.4 4183.5 4186.3 2384.8 2450.4 2679.5 2773.7 2808.2 3410.4 4032.1 4153.3 4229.8 4248.7 3257.7 3487.9 3920.9 3970.3 3990.4 3757.6 3984.2 4029.3 4075.4 4080.9 3354.2 3460.6 3757.6 3812.6 4041.2 3418.4 3935.8 3990.4 4038.8 4117.0 3283.9 3384.4 3444.9 3744.8 3781.8 05:11:52 INFO - PROCESS | 1663 | 05:11:52 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:13915.25 Median:559.54 stddev:19651.64 (3512.1%) stddev-sans-first:19680.83 05:11:52 INFO - PROCESS | 1663 | Values: 468.7 514.5 548.9 552.5 561.5 9511.0 10388.0 10455.7 10466.9 10537.7 7952.5 8072.3 9602.8 9743.9 9792.6 557.6 569.2 581.8 585.9 587.5 448.1 460.2 539.1 563.5 566.9 612.4 615.1 617.6 622.2 624.9 596.1 617.3 618.0 621.8 624.2 456.3 456.4 460.9 463.8 464.2 8731.5 10220.5 10587.7 10633.4 10856.6 8315.7 9453.4 9462.0 9817.1 10390.9 6903.0 7783.5 7957.2 8154.0 8192.5 2297.5 2470.3 2475.8 2490.2 2520.4 53994.5 69240.3 71251.6 71659.0 73776.0 6529.4 7287.9 7598.9 7727.3 7763.9 15530.9 15646.6 15847.0 16116.9 16177.9 5047.3 5073.0 5088.5 5230.5 5235.5 2538.8 2649.4 2659.2 2664.6 2755.8 2206.5 2379.3 2413.0 2432.7 2483.2 476.1 490.3 493.6 499.8 506.1 470.6 477.0 482.2 484.2 490.9 6808.2 7115.5 7153.0 7241.4 7283.4 426.8 440.4 474.7 475.5 486.8 2942.9 3113.5 3235.9 3457.6 3534.5 63235.4 68523.7 68665.7 70470.9 71756.0 47216.0 47582.0 56635.7 59531.1 62538.0 51941.0 51959.0 54501.2 56637.0 57104.0 5397.1 6214.2 6408.5 6900.5 6936.9 32570.0 33963.4 35055.1 35935.0 36144.6 48792.5 51500.5 52993.7 53178.7 53793.5 8852.8 9347.0 9434.4 9920.3 10116.1 9919.9 10229.7 10304.6 10479.2 10517.1 9398.2 9531.3 9575.2 9648.0 9684.6 9156.9 9712.6 9816.0 10060.0 10319.5 9399.9 9570.4 9571.0 9703.9 9714.5 6817.0 7686.2 7779.0 8161.6 8252.7 6815.4 7502.0 8187.2 8208.9 8390.1 05:11:52 INFO - PROCESS | 1663 | -------- Summary: end -------- 05:11:52 INFO - PROCESS | 1663 | 05:11:53 INFO - TEST-INFO | 1663: exit 0 05:11:53 INFO - TEST-OK | dromaeo_css | took 1118985ms 05:11:53 INFO - TEST-START | kraken 05:11:53 INFO - operating with platform_type : mac_ 05:11:53 INFO - Initialising browser for kraken test... 05:11:53 INFO - TEST-INFO | started process 1783 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpACSl64/profile http://localhost:49244/getInfo.html) 05:11:57 INFO - PROCESS | 1783 | 2017-02-10 05:11:57.202 plugin-container[1785:10183] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 05:11:57 INFO - PROCESS | 1783 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:11:57 INFO - PROCESS | 1783 | 2017-02-10 05:11:57.207 plugin-container[1785:10183] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b53, name = 'com.apple.CFPasteboardClient' 05:11:57 INFO - PROCESS | 1783 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:11:57 INFO - PROCESS | 1783 | 2017-02-10 05:11:57.207 plugin-container[1785:10183] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:11:57 INFO - PROCESS | 1783 | __metrics Screen width/height:1600/1200 05:11:57 INFO - PROCESS | 1783 | colorDepth:24 05:11:57 INFO - PROCESS | 1783 | Browser inner width/height: 1024/689 05:11:57 INFO - PROCESS | 1783 | __metrics 05:11:57 ERROR - PROCESS | 1783 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 05:11:58 INFO - TEST-INFO | 1783: exit 0 05:11:58 INFO - Browser initialized. 05:11:58 INFO - Running cycle 1/1 for kraken test... 05:11:58 INFO - TEST-INFO | started process 1786 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpACSl64/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:12:01 INFO - PROCESS | 1786 | 2017-02-10 05:12:01.699 plugin-container[1787:10309] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4f, name = 'com.apple.tsm.portname' 05:12:01 INFO - PROCESS | 1786 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:12:01 INFO - PROCESS | 1786 | 2017-02-10 05:12:01.699 plugin-container[1787:10309] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 05:12:01 INFO - PROCESS | 1786 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:12:01 INFO - PROCESS | 1786 | 2017-02-10 05:12:01.699 plugin-container[1787:10309] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:12:11 INFO - PROCESS | 1786 | RSS: Main: 218820608 05:12:11 INFO - PROCESS | 1786 | 05:12:14 INFO - PROCESS | 1786 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 05:12:15 INFO - PROCESS | 1786 | RSS: Main: 232701952 05:12:15 INFO - PROCESS | 1786 | 05:12:19 INFO - PROCESS | 1786 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 05:12:20 INFO - PROCESS | 1786 | RSS: Main: 239734784 05:12:20 INFO - PROCESS | 1786 | 05:12:24 INFO - PROCESS | 1786 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 05:12:24 INFO - PROCESS | 1786 | RSS: Main: 244240384 05:12:24 INFO - PROCESS | 1786 | 05:12:27 INFO - PROCESS | 1786 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 05:12:28 INFO - PROCESS | 1786 | RSS: Main: 244727808 05:12:28 INFO - PROCESS | 1786 | 05:12:30 INFO - PROCESS | 1786 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:12:31 INFO - PROCESS | 1786 | RSS: Main: 249663488 05:12:31 INFO - PROCESS | 1786 | 05:12:35 INFO - PROCESS | 1786 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 05:12:36 INFO - PROCESS | 1786 | RSS: Main: 247164928 05:12:36 INFO - PROCESS | 1786 | 05:12:40 INFO - PROCESS | 1786 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 05:12:41 INFO - PROCESS | 1786 | RSS: Main: 248045568 05:12:41 INFO - PROCESS | 1786 | 05:12:45 INFO - PROCESS | 1786 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 05:12:46 INFO - PROCESS | 1786 | RSS: Main: 248479744 05:12:46 INFO - PROCESS | 1786 | 05:12:48 INFO - PROCESS | 1786 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:12:48 INFO - PROCESS | 1786 | RSS: Main: 248496128 05:12:48 INFO - PROCESS | 1786 | 05:12:51 INFO - PROCESS | 1786 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:12:51 INFO - PROCESS | 1786 | RSS: Main: 248819712 05:12:51 INFO - PROCESS | 1786 | 05:12:54 INFO - PROCESS | 1786 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:12:55 INFO - PROCESS | 1786 | RSS: Main: 248684544 05:12:55 INFO - PROCESS | 1786 | 05:12:59 INFO - PROCESS | 1786 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:13:00 INFO - PROCESS | 1786 | RSS: Main: 246427648 05:13:00 INFO - PROCESS | 1786 | 05:13:05 INFO - PROCESS | 1786 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:13:05 INFO - PROCESS | 1786 | RSS: Main: 248070144 05:13:05 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 05:13:08 INFO - PROCESS | 1786 | RSS: Main: 249495552 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | __start_tp_report 05:13:08 INFO - PROCESS | 1786 | _x_x_mozilla_page_load 05:13:08 INFO - PROCESS | 1786 | _x_x_mozilla_page_load_details 05:13:08 INFO - PROCESS | 1786 | |i|pagename|runs| 05:13:08 INFO - PROCESS | 1786 | |0;ai-astar;171;171;165;183;178;167;161;169;178;173 05:13:08 INFO - PROCESS | 1786 | |1;audio-beat-detection;206;173;166;213;171;179;206;203;199;194 05:13:08 INFO - PROCESS | 1786 | |2;audio-dft;246;243;274;281;358;255;254;256;253;346 05:13:08 INFO - PROCESS | 1786 | |3;audio-fft;123;98;107;150;79;125;84;123;141;128 05:13:08 INFO - PROCESS | 1786 | |4;audio-oscillator;127;102;131;113;102;103;118;108;103;120 05:13:08 INFO - PROCESS | 1786 | |5;imaging-gaussian-blur;140;134;130;151;138;131;138;171;202;201 05:13:08 INFO - PROCESS | 1786 | |6;imaging-darkroom;147;143;143;154;212;146;139;192;180;164 05:13:08 INFO - PROCESS | 1786 | |7;imaging-desaturate;149;136;123;130;133;146;135;149;135;131 05:13:08 INFO - PROCESS | 1786 | |8;json-parse-financial;80;84;78;90;91;91;90;90;82;93 05:13:08 INFO - PROCESS | 1786 | |9;json-stringify-tinderbox;100;107;104;103;111;111;99;95;112;128 05:13:08 INFO - PROCESS | 1786 | |10;stanford-crypto-aes;126;166;155;155;129;122;130;160;151;121 05:13:08 INFO - PROCESS | 1786 | |11;stanford-crypto-ccm;236;239;209;201;226;214;249;238;325;254 05:13:08 INFO - PROCESS | 1786 | |12;stanford-crypto-pbkdf2;323;349;269;249;254;276;297;260;254;247 05:13:08 INFO - PROCESS | 1786 | |13;stanford-crypto-sha256-iterative;91;97;109;105;94;109;93;95;114;129 05:13:08 INFO - PROCESS | 1786 | __end_tp_report 05:13:08 INFO - PROCESS | 1786 | __start_cc_report 05:13:08 INFO - PROCESS | 1786 | _x_x_mozilla_cycle_collect,1196 05:13:08 INFO - PROCESS | 1786 | __end_cc_report 05:13:08 INFO - PROCESS | 1786 | __startTimestamp1486732388313__endTimestamp 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | ------- Summary: start ------- 05:13:08 INFO - PROCESS | 1786 | Number of tests: 14 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | [#0] ai-astar Cycles:10 Average:171.60 Median:172.00 stddev:6.65 (3.9%) stddev-sans-first:7.05 05:13:08 INFO - PROCESS | 1786 | Values: 171.0 171.0 165.0 183.0 178.0 167.0 161.0 169.0 178.0 173.0 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | [#1] audio-beat-detection Cycles:10 Average:191.00 Median:201.00 stddev:17.14 (8.5%) stddev-sans-first:17.30 05:13:08 INFO - PROCESS | 1786 | Values: 206.0 173.0 166.0 213.0 171.0 179.0 206.0 203.0 199.0 194.0 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | [#2] audio-dft Cycles:10 Average:276.60 Median:265.00 stddev:41.49 (15.7%) stddev-sans-first:42.50 05:13:08 INFO - PROCESS | 1786 | Values: 246.0 243.0 274.0 281.0 358.0 255.0 254.0 256.0 253.0 346.0 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | [#3] audio-fft Cycles:10 Average:115.80 Median:145.50 stddev:23.34 (16.0%) stddev-sans-first:24.61 05:13:08 INFO - PROCESS | 1786 | Values: 123.0 98.0 107.0 150.0 79.0 125.0 84.0 123.0 141.0 128.0 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | [#4] audio-oscillator Cycles:10 Average:112.70 Median:115.50 stddev:10.85 (9.4%) stddev-sans-first:10.20 05:13:08 INFO - PROCESS | 1786 | Values: 127.0 102.0 131.0 113.0 102.0 103.0 118.0 108.0 103.0 120.0 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | [#5] imaging-gaussian-blur Cycles:10 Average:153.60 Median:145.50 stddev:27.93 (19.2%) stddev-sans-first:29.19 05:13:08 INFO - PROCESS | 1786 | Values: 140.0 134.0 130.0 151.0 138.0 131.0 138.0 171.0 202.0 201.0 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | [#6] imaging-darkroom Cycles:10 Average:162.00 Median:159.00 stddev:24.77 (15.6%) stddev-sans-first:25.68 05:13:08 INFO - PROCESS | 1786 | Values: 147.0 143.0 143.0 154.0 212.0 146.0 139.0 192.0 180.0 164.0 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | [#7] imaging-desaturate Cycles:10 Average:136.70 Median:135.50 stddev:8.65 (6.4%) stddev-sans-first:7.95 05:13:08 INFO - PROCESS | 1786 | Values: 149.0 136.0 123.0 130.0 133.0 146.0 135.0 149.0 135.0 131.0 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | [#8] json-parse-financial Cycles:10 Average:86.90 Median:90.00 stddev:5.36 (6.0%) stddev-sans-first:5.07 05:13:08 INFO - PROCESS | 1786 | Values: 80.0 84.0 78.0 90.0 91.0 91.0 90.0 90.0 82.0 93.0 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | [#9] json-stringify-tinderbox Cycles:10 Average:107.00 Median:111.50 stddev:9.31 (8.3%) stddev-sans-first:9.52 05:13:08 INFO - PROCESS | 1786 | Values: 100.0 107.0 104.0 103.0 111.0 111.0 99.0 95.0 112.0 128.0 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | [#10] stanford-crypto-aes Cycles:10 Average:141.50 Median:153.00 stddev:17.41 (11.4%) stddev-sans-first:17.53 05:13:08 INFO - PROCESS | 1786 | Values: 126.0 166.0 155.0 155.0 129.0 122.0 130.0 160.0 151.0 121.0 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | [#11] stanford-crypto-ccm Cycles:10 Average:239.10 Median:238.50 stddev:34.75 (14.6%) stddev-sans-first:36.84 05:13:08 INFO - PROCESS | 1786 | Values: 236.0 239.0 209.0 201.0 226.0 214.0 249.0 238.0 325.0 254.0 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:277.80 Median:272.50 stddev:34.62 (12.7%) stddev-sans-first:32.63 05:13:08 INFO - PROCESS | 1786 | Values: 323.0 349.0 269.0 249.0 254.0 276.0 297.0 260.0 254.0 247.0 05:13:08 INFO - PROCESS | 1786 | 05:13:08 INFO - PROCESS | 1786 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:103.60 Median:92.00 stddev:11.99 (13.0%) stddev-sans-first:11.82 05:13:08 INFO - PROCESS | 1786 | Values: 91.0 97.0 109.0 105.0 94.0 109.0 93.0 95.0 114.0 129.0 05:13:08 INFO - PROCESS | 1786 | -------- Summary: end -------- 05:13:08 INFO - PROCESS | 1786 | 05:13:09 INFO - TEST-INFO | 1786: exit 0 05:13:09 INFO - TEST-OK | kraken | took 75471ms 05:13:09 INFO - SUITE-END | took 1194s 05:13:09 INFO - Completed test suite (00:19:54) 05:13:09 INFO - javascript benchmark 05:13:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 4995.983073384071, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2032.6950957356394, 2252.324302709188, 2261.525079733257, 2306.527161931804, 2317.8261630377724, 3696.4085746280603, 3738.6074462181487, 3741.1021354874806, 3761.5298087739034, 3764.5482542094987, 3727.384981478053, 3734.0, 3750.512433383656, 3787.8674246401383, 3806.2006978534473, 3357.9926022193367, 3428.4000299947534, 3470.2192006798523, 3494.615592284853, 3597.3165098631175, 3463.6882680558747, 3478.9737027576925, 3486.738494612914, 3508.9298214035703, 3587.905688764926, 3413.9146521337043, 3448.758586898918, 3485.7734247273975, 3524.841382137797, 3531.434970404736, 3351.128706536318, 3385.5598772159583, 3435.9656403435897, 3443.0, 3446.207372304359, 2667.2531691126514, 2935.398243360106, 2950.9114726558237, 3369.898903032913, 3445.1214940190343, 3763.209725957552, 3806.8286927088348, 3818.996806759339, 3934.9016274593077, 3936.114374265796, 3263.608366995952, 4038.7778672173017, 4072.859599312197, 4159.376093585956, 4189.811458484345, 4089.284375234352, 4090.1615168890603, 4137.524184718765, 4165.854195103157, 4197.895052623679, 3808.219315040438, 3828.2917660232947, 3859.9771060668945, 3878.127421330178, 3884.2814079395403, 3477.8608855646, 3830.889042177793, 3939.980300098481, 4092.7748973806133, 4351.912021994502, 3323.900282991514, 3337.0489410517885, 3957.703172262092, 3997.8800635980965, 3999.5400528939545, 3516.701080408143, 4007.957930937977, 4063.2889244382113, 4075.673946084306, 4184.958150418518, 3900.1224724436775, 3966.2464131814863, 3966.8413263469715, 3969.5236571611013, 4025.657819085352, 3902.9609703902597, 4036.798160091984, 4083.591640835952, 4122.91754164915, 4197.8320867165585, 3157.778955473095, 3189.728873045817, 3212.3093534890113, 3249.8862539811466, 3382.6279109297957, 2807.4385122975486, 2906.7182261924286, 2922.5616157576533, 2946.9705302946695, 2965.303153758865, 3198.2484116232667, 3673.94489082664, 3704.759190652599, 3717.5724791648768, 3747.737658363943, 4083.97958010214, 4197.664186865043, 4233.872983810491, 4283.571642835692, 4293.592108749664, 3696.2053158571034, 3703.5555733312176, 3795.753276037104, 3835.2521258354354, 3886.4753258309765, 2908.2438565972575, 3084.6915308468974, 3464.532288141069, 3541.8488991078, 3596.8381422836037, 3619.601843797233, 4261.403403523509, 4300.4194433751045, 4317.942104184495, 4369.300911854088, 4043.474348334698, 4086.2644723949975, 4177.707560470738, 4230.365445183247, 4234.724742891703, 4100.835966561304, 4125.50493940729, 4272.487301523837, 4276.31578947367, 4315.115401342655, 4014.7791871446184, 4066.877993660195, 4069.4099355593867, 4078.6533144683035, 4128.195001974706, 3430.4168291390024, 3882.398228274622, 4035.616616421435, 4187.811548480387, 4199.097194103282, 4076.8776936691947, 4214.7471151731, 4250.341197114452, 4276.037891474393, 4302.935455968226, 4310.633596144237, 4316.985508405585, 4385.364122202327, 4397.626201772885, 4425.468943726773, 3681.4477828325967, 4147.97926010368, 4224.725392849455, 4311.3964045034345, 4383.561643835591, 3542.220711443478, 4299.312110062375, 4303.569643035797, 4348.9130217396105, 4506.819727211006, 4104.507459104927, 4157.9376309354775, 4161.8959526012095, 4349.543297953669, 4382.693211475166], "value": 3754.743910894086, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.73209602078293, 333.6713337362698, 343.3116601214564, 363.4765937050647, 373.96073412291724, 25284.49431011381, 28455.288617784565, 29614.407711845775, 30092.0, 30554.69445305546, 24612.630810537856, 30839.537406938915, 31540.684593154063, 32139.83930080346, 32179.195520112007, 5185.818496352628, 5611.9438805612035, 6409.743610255584, 6421.518386121036, 6527.216733991928, 5755.366909639936, 7439.814004649873, 7762.184970578092, 7866.724664636738, 8064.233897779702, 7729.961350193241, 7730.806729831743, 7764.417668674843, 7854.900313956051, 8027.558484283362, 7770.650320735552, 7851.45039847211, 7996.800079997988, 8011.958445402119, 8053.395995300376, 7784.688612455495, 7816.570088645122, 7897.052353717533, 7902.446828721992, 7954.642041108106, 28393.432131357466, 28829.56755648667, 29161.854190729122, 29187.124386268446, 29631.0, 2601.2456387647558, 2657.720939301384, 2785.7632101271415, 2785.874635641401, 2808.648918885139, 20840.791592084188, 21026.0, 21377.67933480999, 21462.819544925023, 21625.24311649085, 2156.2345367394505, 2197.2419515267206, 2203.537257175995, 2219.0125394199586, 2257.7290725113094, 267931.0, 325138.0, 329772.0, 332536.99999999756, 341233.0, 45557.54442455533, 46016.0, 46852.7657361711, 46958.76520617375, 47839.282410763866, 47483.81290467766, 47793.80515487141, 48280.5171948283, 48437.757811210715, 48468.0, 11134.832977505344, 11386.975172234539, 11625.18623696333, 11717.88282117168, 11738.178327517162, 7779.57212353326, 8233.0, 8650.87023694645, 8792.692255771017, 8898.510581917928, 10920.945395272973, 11479.426028698532, 12377.938110309391, 12765.297908615115, 13215.008874334271, 4527.7509736964785, 4607.18669234535, 4994.850154495371, 5017.648764586444, 5156.1750119980625, 560.9186667933127, 584.2967749011584, 589.837794606478, 604.727872457396, 628.5097623853402, 19937.302194423126, 20137.49656258576, 20751.481212969797, 21535.0, 21984.780152198593, 584.5177728374108, 584.5878655547829, 604.4952464691944, 743.5947408662292, 847.1824689174975, 8094.028716553933, 9573.18627916621, 9944.552495137736, 9978.0, 10075.697729068139, 407141.0, 416149.0, 416496.0, 418790.0, 432603.0, 30709.846450767604, 36743.081422964104, 37012.259754804756, 38008.0, 38687.6131238684, 944.5324564340696, 958.9547393341292, 959.9280053995839, 962.993671613152, 986.7483791633088, 15607.649293465538, 17047.48857534276, 18383.0, 18656.0, 19913.0, 29088.7091129086, 31840.885569004975, 32334.83832580822, 32691.673083268863, 32913.34173316568, 349731.0, 354151.0, 364951.0, 365702.0, 370706.0, 14477.855221447651, 17336.133193340534, 17403.21685524129, 17420.651586968444, 17430.73853892218, 28711.282217944718, 28991.710082898902, 29768.255793605334, 30109.548356774227, 30234.84882575573, 21372.89313553422, 23375.7143357117, 24525.632115517925, 24739.25782226536, 25719.614205786555, 25454.109106181193, 26632.60051099274, 27737.583936241375, 28709.569356460088, 29061.41877162487, 27389.726102739514, 27792.16623501298, 27944.16167514978, 28169.71830281671, 28570.14289571316, 23254.0, 23774.88112559426, 23995.880020599787, 24432.14487492929, 25074.122405715712, 16333.101679407271, 17448.91275543614, 17693.911530442267, 17962.820371796115, 18287.994160320777], "value": 13745.480730042855, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [241.66720536242624, 257.2385738214884, 269.53101603210416, 282.7229315271034, 290.74850254529827, 10116.39301641902, 10472.424016679079, 13177.736445271088, 14406.279686015696, 14586.197759123244, 13337.799933001013, 13858.93070534646, 14540.200288984102, 14651.047681900669, 14805.3337599808, 3815.9809200953955, 4145.917081658365, 4151.0, 4151.252774500589, 4151.854685086023, 3888.2223555288915, 3927.7054220933546, 4021.1957608478274, 4054.493188351456, 4112.588741125894, 4048.3725022621543, 4093.079057212118, 4169.72896761711, 4188.97905510472, 4290.828366865321, 4008.8396464141547, 4067.7966101694797, 4071.491063617048, 4132.772697501636, 4159.292920203573, 3922.235164142993, 3930.4693866327966, 3967.2462232175794, 3973.0, 4074.4295798587923, 8989.865152022725, 8989.921209454842, 8993.910060899372, 9007.0, 9021.864672029924, 1556.8598826105592, 1675.2628843308905, 1740.3647668600995, 1785.2412724592127, 1804.2692709452692, 6351.951927931932, 7125.7149714011375, 8156.0, 8222.917770822274, 10713.982171693677, 1194.0268681024936, 1223.5167108991898, 1241.7268200995766, 1290.5096063495905, 1312.212672396564, 90092.0, 91259.54370228107, 91994.0, 92474.0, 94987.0501294992, 6839.768841608558, 7652.770416887391, 8131.18688131194, 8218.424710270223, 8506.36202284831, 10535.736606584744, 10635.20235982289, 10772.515236814363, 10790.460476976277, 10938.906109388998, 5046.697198168122, 5474.835754927359, 5590.189422533791, 5914.822555323347, 5958.672273025025, 5789.45000224978, 5904.7933322334375, 5905.881882362329, 5926.377730338252, 5993.280806303184, 2710.891564337444, 3261.0, 3265.591801024872, 3267.1015470745733, 3295.307985323061, 1206.2943178240828, 1332.1274565159836, 1368.3773882883265, 1373.642852858264, 1399.0696187035508, 632.895572230586, 646.2890820097855, 717.1859938969308, 724.6014691919423, 737.1559564298955, 13853.653658658613, 14401.135931844123, 14406.0, 14592.343344549521, 14667.97324187318, 549.0939949083963, 611.6483022262217, 631.7157279224367, 659.8911179655303, 663.9336066393322, 4311.159323931866, 4696.976515117471, 7258.673359698826, 7260.346568808833, 8430.662773488992, 509209.0, 519335.80664192874, 532226.0, 541307.0, 541384.0, 56281.0, 77227.227727722, 99371.00628993762, 104478.43282350618, 110897.44551277193, 660.5442244851038, 698.6646409723361, 700.6461736822973, 713.6966789114606, 715.5456285258794, 12719.93640031794, 12873.712628737427, 12890.806637900627, 13772.724545508834, 14059.578212653636, 23622.0, 32085.03744887657, 39030.41454378243, 40261.0, 45846.54153458423, 50143.24785128153, 53916.46083539271, 56056.318310450755, 57877.71061144667, 58906.0, 4277.95722042788, 4880.219164933594, 5072.340595722532, 5132.358455193101, 5779.219805326227, 8401.873971890549, 8608.827823443622, 8900.42147260426, 8919.79175505774, 9173.35786494939, 6217.813465596039, 6615.0, 7522.322990930843, 7621.428392870446, 7757.883631745415, 6703.29615390377, 7833.412494062854, 8016.318612917968, 8110.432269741062, 8685.261752751087, 7822.295993360397, 8381.539015354214, 8514.829703405774, 8926.69805645181, 9214.44713317203, 5947.10793380979, 6008.279006519071, 6271.3415091414745, 6277.874442511215, 6366.840828979127, 7392.260773922565, 7525.473216874766, 7825.0, 7975.2423519765525, 8076.111627720828], "value": 6602.517702738576, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [869.9739007829766, 960.2653969713166, 964.1948972607872, 979.9612410844505, 1014.8782146142464, 4795.544423279783, 5427.538659213967, 5488.012157811592, 5732.369439361672, 5795.362510123889, 5380.273663055486, 5755.482006619403, 5784.739686714098, 5841.415858414157, 5957.374475680045, 1569.1840243073623, 1598.3526671697923, 1601.175394671745, 1607.4373969110777, 1607.517744676598, 1545.3895711194073, 1582.7309357409267, 1586.8095828500595, 1588.317023679817, 1588.3884704388836, 1575.149419313575, 1587.984120158801, 1605.702944955187, 1609.89535680181, 1614.7093523165825, 1362.7070179911268, 1479.186447453896, 1554.5880341709455, 1555.9611009724679, 1574.4568123997203, 1432.6633241188313, 1450.927453627325, 1529.915854627995, 1595.2502323907745, 1610.0742073307779, 5406.405295417501, 5606.299212598426, 5754.935336962633, 5808.651480911159, 5873.295204575478, 3410.6759857813468, 3524.312759011994, 3582.695470884978, 3587.65917237862, 3644.2711457708565, 2300.976990230093, 2376.2871138658334, 2394.97605023951, 2416.4804567018177, 2420.1529464687396, 659.0147729144959, 778.9766307010742, 796.9003874515686, 830.5016989806068, 850.3154960256991, 7300.817479563053, 7304.415646748247, 7422.294881986203, 7464.850702985964, 7468.2531746825425, 2982.522796352573, 3224.5485632011537, 3268.3168168393295, 3318.6515415881468, 3322.1362445764275, 6410.903836442457, 6511.186101737283, 6561.540692151599, 6585.242697089896, 6594.439472644783, 3031.7423019043626, 3588.6052534221217, 3592.550931133608, 3603.0992251937014, 3652.160003199226, 2769.9307517312227, 2840.403515261818, 3310.1393637654382, 3352.379809735207, 3377.7297816174646, 2464.8644324561965, 2604.231751633273, 2726.2230264374557, 2752.8348299102117, 2773.764230040469, 766.5094339622646, 867.96528138875, 881.3257857738835, 903.3811838890381, 921.2860033474111, 532.6803917649457, 535.1712152928658, 544.901917654818, 552.9972350138223, 557.417498713837, 3455.87904423344, 3646.908827279339, 3841.5966323535627, 3874.3994680824517, 3957.2085582883537, 600.4775845014777, 681.1247546902306, 687.9518433709692, 703.525120543631, 706.4348521183135, 2536.1884197056766, 2537.3022418834967, 2551.045829139733, 2589.2491177558672, 2601.648777415063, 6706.698198580978, 6730.764423245163, 7009.579425234502, 7038.472114591324, 7068.363847253772, 6816.250212476623, 6834.863302733917, 6902.275261097613, 6955.89566156508, 6985.301469852974, 5132.409772876093, 5341.0, 5342.706151161723, 5358.544523715528, 5450.264214331094, 3268.362669279563, 3363.276895467486, 3453.430184019709, 3739.2708421858565, 3779.093017675793, 4481.7535035572055, 6313.463355614824, 6326.778562750282, 6426.132472116392, 6571.441427478718, 5911.231539899785, 6315.842103947438, 6409.519286053658, 6464.095026696173, 6468.191476065492, 4537.274036154199, 4726.220173671306, 4930.901381972412, 4988.950110498994, 5003.224500202475, 5273.762680679302, 5399.973000134974, 5454.618176727591, 5547.362053363915, 5569.303837020372, 4813.7593120343145, 4928.088303663691, 4955.479674634112, 4959.156943319716, 4967.801287948441, 3777.490038844721, 4886.462489126265, 4892.902141957212, 4904.5340692634145, 4940.036692845005, 4269.7224680395675, 4355.825766969286, 4373.671974601982, 4380.335472520422, 4429.335599659948, 3325.2518183409506, 3356.6978971892645, 3376.4260075786683, 3377.4764911438765, 3500.1074725944723, 3326.617438994547, 3358.6809253120914, 3496.1434448560267, 3502.614712381687, 3503.3343664703625], "value": 3001.781842813054, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [216.2776327067594, 236.44553521990926, 244.278158042983, 265.21760805623404, 267.46240057484454, 3513.631068737784, 3918.8628398006076, 3924.980375098124, 4047.8178481968307, 4083.5712250213746, 3199.0882598459443, 3649.8540058397634, 3704.3147017801725, 3776.565694945082, 3909.2767837949928, 2643.3788059805884, 2934.5598160275918, 3025.0924722583245, 3033.7572994160523, 3043.1022848259818, 3003.8735474197174, 3015.251154330494, 3068.5090385538206, 3083.3062560923836, 3134.952975705366, 2556.440571251535, 2623.6720409948757, 2937.251000994754, 2971.0789655206845, 2989.805662631933, 2826.18040768177, 2900.4772494440376, 2968.827313211291, 3016.3816417634334, 3025.7276845084048, 2206.393241858486, 2583.6641236639302, 2632.7893768498475, 2667.9733202668112, 2725.74105459981, 3376.8649254030056, 3540.406816932365, 3763.5295588051495, 3793.696504279651, 3868.5551161616495, 2907.8368652538943, 3410.198603328216, 3411.3966435775146, 3412.607550131742, 3498.2129020970333, 3152.8738850445957, 3645.90360542689, 3986.54154772202, 4038.878833634966, 4151.6886233532305, 2478.392793765539, 2849.8173258097863, 2946.0867131189398, 2955.88176472943, 2968.5992391027153, 4100.753954762724, 4177.895552611179, 4207.666103912975, 4294.5490723473895, 4363.759993200372, 2502.224310463762, 2788.6235358226786, 2866.813657112281, 2880.6399200099986, 2902.753265972374, 2867.913962581126, 2945.774377057119, 2950.6164198654037, 2986.596809430699, 3011.2020314616643, 2773.7503624673877, 2846.34534057168, 3398.9490157647656, 3456.0150357148596, 3572.6070132285427, 3995.2009598079917, 4047.392891066305, 4056.817443215062, 4091.6522095622204, 4117.423560701504, 967.5355829202023, 988.2884323287221, 988.4810474500945, 996.4071377530358, 1009.3194830607104, 629.298332359416, 651.2998526583941, 669.7287598522606, 675.5305062981279, 690.4752388184916, 3409.8465569049454, 3638.452508803895, 3731.8017230615555, 3760.7367484276383, 3876.96123038766, 3934.8229329680235, 4250.404943307939, 4256.467401895867, 4292.18448494785, 4316.352547117895, 3751.643593858579, 3789.5073640427117, 3801.650414103009, 3840.8655697050467, 3865.43951127085, 1773.973390399145, 1849.557345270691, 1986.9965667337901, 1991.6912878503856, 2066.772655007948, 3815.931539169037, 3978.5822488638855, 4012.879613611596, 4159.417681524589, 4211.894702632398, 3497.3070735533497, 3734.402495600691, 3972.5431575368625, 4065.4833568962636, 4111.650509706648, 3764.7364684471822, 3976.562578116405, 4040.313146765057, 4142.0, 4262.957370426256, 3316.3864685033345, 3542.4509201073874, 3552.5845995160116, 3885.048163200034, 3969.4839670842607, 3549.8112227571714, 3911.8630847920185, 3988.4814974054348, 4050.6354428100435, 4065.4714887065675, 4096.283150448719, 4106.3635136553885, 4110.445089912823, 4183.477065366829, 4186.330187170038, 2384.8211384146607, 2450.375154335633, 2679.4775018871915, 2773.70876058011, 2808.2308145867974, 3410.356973345677, 4032.0726232967168, 4153.300354769424, 4229.809658565434, 4248.723832950848, 3257.7393808495735, 3487.9476807847395, 3920.902487035324, 3970.3448930926074, 3990.3615421532418, 3757.576363245746, 3984.187194826289, 4029.2948733972025, 4075.4090656853996, 4080.877758616404, 3354.2285274387514, 3460.6193318735423, 3757.643023912724, 3812.561555421162, 4041.1513582146904, 3418.418868792361, 3935.822887969991, 3990.4014397840556, 4038.788363491, 4116.958830411658, 3283.917902052468, 3384.3738908302043, 3444.896653100411, 3744.831482583345, 3781.7920014399474], "value": 2956.706267559288, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.7047160289017, 514.5214950096408, 548.909429944059, 552.5358698693097, 561.5292712066906, 9510.952445237774, 10388.0, 10455.68632941013, 10466.895331046688, 10537.683869483919, 7952.483088599237, 8072.313853322475, 9602.807943841137, 9743.853842192355, 9792.559334829932, 557.559527972902, 569.163329905039, 581.8479410766685, 585.9014348097318, 587.4830149468462, 448.10379241516966, 460.21303570893645, 539.1293061705337, 563.4787821265334, 566.8639526513628, 612.4396177497584, 615.0743131586955, 617.5522746009152, 622.1880446017944, 624.8937680594287, 596.090961284041, 617.2716194890028, 617.9721912513925, 621.8476473264056, 624.1636207481996, 456.34058785055606, 456.3702091114274, 460.94238220222474, 463.7820224494482, 464.23633123511974, 8731.476111433334, 10220.540075696537, 10587.682369528926, 10633.415162166079, 10856.565737370496, 8315.667373305061, 9453.385529940531, 9462.0, 9817.116459518606, 10390.948045259802, 6902.968454602138, 7783.494072885244, 7957.196997844976, 8154.0, 8192.467489613156, 2297.5290065536524, 2470.3330100872845, 2475.801935845128, 2490.1657944588583, 2520.395105174763, 53994.46005539973, 69240.30759692389, 71251.57496850034, 71659.0, 73776.0, 6529.444997175198, 7287.890681639779, 7598.886016709644, 7727.343175830118, 7763.92236077632, 15530.9223453882, 15646.608834779221, 15847.049177049414, 16116.919415402848, 16177.94843335203, 5047.343845300087, 5072.974635126875, 5088.465711100355, 5230.503102205284, 5235.476452354734, 2538.8222824402483, 2649.417128231786, 2659.175655546787, 2664.5869890167055, 2755.8070935034357, 2206.5476577301615, 2379.3218932604123, 2412.963805542918, 2432.6959130108735, 2483.2177863972793, 476.09066682636, 490.2694984473109, 493.6371766751435, 499.81007217257934, 506.1319836480454, 470.6493662221598, 477.0435277269053, 482.2188055350355, 484.23974360254766, 490.93617829681915, 6808.2170550386345, 7115.53749006313, 7153.034340364088, 7241.384482319062, 7283.417326613858, 426.76741176059124, 440.44283980764357, 474.6629892776099, 475.5078493758943, 486.76148687143046, 2942.9264268393463, 3113.548535462345, 3235.902922912316, 3457.5505184325875, 3534.54050973377, 63235.36764632295, 68523.65738171278, 68665.65667171733, 70470.942935856, 71756.0, 47216.0, 47582.048359032626, 56635.716821415626, 59531.10703339453, 62538.0, 51941.0, 51959.0, 54501.1824822628, 56637.0, 57104.0, 5397.055515284731, 6214.161088253028, 6408.455281301141, 6900.516963812485, 6936.895946560907, 32570.0, 33963.4338394465, 35055.12362190965, 35935.0, 36144.638553614124, 48792.53622391334, 51500.48499514957, 52993.7350313246, 53178.67053323543, 53793.462065378844, 8852.760613514241, 9347.0, 9434.448830612355, 9920.305578609428, 10116.08955194036, 9919.900800992187, 10229.74425639365, 10304.587816487257, 10479.161667066464, 10517.10604598587, 9398.20115290208, 9531.261681078466, 9575.23398128162, 9648.0, 9684.564194611401, 9156.908430915872, 9712.562934667816, 9816.011934361015, 10060.0, 10319.535620896928, 9399.906000940176, 9570.425774453555, 9570.95214523923, 9703.854442183232, 9714.465704386044, 6817.0, 7686.154523002353, 7778.961105194664, 8161.632726527439, 8252.711155109542, 6815.352541508549, 7501.962490187514, 8187.181281871765, 8208.917910820815, 8390.136877010098], "value": 5140.686596653621, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 2275.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 165.0, 183.0, 178.0, 167.0, 161.0, 169.0, 178.0, 173.0], "value": 171.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 173.0, 166.0, 213.0, 171.0, 179.0, 206.0, 203.0, 199.0, 194.0], "value": 191.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [246.0, 243.0, 274.0, 281.0, 358.0, 255.0, 254.0, 256.0, 253.0, 346.0], "value": 276.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 98.0, 107.0, 150.0, 79.0, 125.0, 84.0, 123.0, 141.0, 128.0], "value": 115.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 102.0, 131.0, 113.0, 102.0, 103.0, 118.0, 108.0, 103.0, 120.0], "value": 112.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 134.0, 130.0, 151.0, 138.0, 131.0, 138.0, 171.0, 202.0, 201.0], "value": 153.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 143.0, 143.0, 154.0, 212.0, 146.0, 139.0, 192.0, 180.0, 164.0], "value": 162.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 136.0, 123.0, 130.0, 133.0, 146.0, 135.0, 149.0, 135.0, 131.0], "value": 136.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 84.0, 78.0, 90.0, 91.0, 91.0, 90.0, 90.0, 82.0, 93.0], "value": 86.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 107.0, 104.0, 103.0, 111.0, 111.0, 99.0, 95.0, 112.0, 128.0], "value": 107.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 166.0, 155.0, 155.0, 129.0, 122.0, 130.0, 160.0, 151.0, 121.0], "value": 141.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [236.0, 239.0, 209.0, 201.0, 226.0, 214.0, 249.0, 238.0, 325.0, 254.0], "value": 239.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [323.0, 349.0, 269.0, 249.0, 254.0, 276.0, 297.0, 260.0, 254.0, 247.0], "value": 277.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 97.0, 109.0, 105.0, 94.0, 109.0, 93.0, 95.0, 114.0, 129.0], "value": 103.6, "unit": "score"}]}]} 05:13:09 INFO - Return code: 0 05:13:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:13:09 ERROR - # TBPL SUCCESS # 05:13:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:13:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:13:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:13:09 INFO - 2017-02-10 05:13:09.593 screenresolution[1798:10700] starting screenresolution argv=screenresolution get 05:13:09 INFO - 2017-02-10 05:13:09.596 screenresolution[1798:10700] Display 0: 1600x1200x32@60 05:13:09 INFO - 2017-02-10 05:13:09.603 screenresolution[1799:10703] starting screenresolution argv=screenresolution list 05:13:09 INFO - Available Modes on Display 0 05:13:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:13:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:13:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:13:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:13:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:13:09 INFO - Intel Iris: 05:13:09 INFO - Chipset Model: Intel Iris 05:13:09 INFO - Type: GPU 05:13:09 INFO - Bus: Built-In 05:13:09 INFO - VRAM (Dynamic, Max): 1536 MB 05:13:09 INFO - Vendor: Intel (0x8086) 05:13:09 INFO - Device ID: 0x0a2e 05:13:09 INFO - Revision ID: 0x0009 05:13:09 INFO - Displays: 05:13:09 INFO - Display: 05:13:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:13:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:13:09 INFO - Display Serial Number: 1600x1200 60 05:13:09 INFO - Main Display: Yes 05:13:09 INFO - Mirror: Off 05:13:09 INFO - Online: Yes 05:13:09 INFO - Rotation: Supported 05:13:09 INFO - Return code: 0 05:13:09 INFO - Running post-action listener: _resource_record_post_action 05:13:09 INFO - [mozharness: 2017-02-10 13:13:09.895560Z] Finished run-tests step (success) 05:13:09 INFO - Running post-run listener: _resource_record_post_run 05:13:09 INFO - Total resource usage - Wall time: 1225s; CPU: 55.0%; Read bytes: 38853120; Write bytes: 515008000; Read time: 262; Write time: 1688 05:13:09 INFO - TinderboxPrint: CPU usage
55.2% 05:13:09 INFO - TinderboxPrint: I/O read bytes / time
38,853,120 / 262 05:13:09 INFO - TinderboxPrint: I/O write bytes / time
515,008,000 / 1,688 05:13:09 INFO - TinderboxPrint: CPU idle
2,193.4 (44.8%) 05:13:09 INFO - TinderboxPrint: CPU system
1,007.4 (20.6%) 05:13:09 INFO - TinderboxPrint: CPU user
1,697.4 (34.7%) 05:13:09 INFO - TinderboxPrint: Swap in / out
246,173,696 / 0 05:13:09 INFO - install - Wall time: 29s; CPU: 55.0%; Read bytes: 214094336; Write bytes: 210733056; Read time: 23620; Write time: 437 05:13:10 INFO - run-tests - Wall time: 1196s; CPU: 55.0%; Read bytes: 31437312; Write bytes: 303021568; Read time: 196; Write time: 1245 05:13:10 INFO - Running post-run listener: _upload_blobber_files 05:13:10 INFO - Blob upload gear active. 05:13:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:13:10 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:13:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:13:11 INFO - (blobuploader) - INFO - Open directory for files ... 05:13:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:13:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:13:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:12 INFO - (blobuploader) - INFO - Done attempting. 05:13:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:13:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:13:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:13 INFO - (blobuploader) - INFO - Done attempting. 05:13:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:13:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:13:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:14 INFO - (blobuploader) - INFO - Done attempting. 05:13:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:13:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:13:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:13:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:13:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:13:16 INFO - (blobuploader) - INFO - Done attempting. 05:13:16 INFO - (blobuploader) - INFO - Iteration through files over. 05:13:16 INFO - Return code: 0 05:13:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:13:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:13:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0057649031fe5d1348d7e5b3a3d85447f7213586573d88c62ba1bd2386e8a48852540720adc6f15fc79d5043983b3f924fb70f64f144ac22b2c2a62d66961ac0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/44867e6032390f8bcea0c08bf7180c41d911b3fae64ad6b5e7c7c1f309da0dcb684495867c7b947388935f70234abe122de0816aa66eb11e993255750aa6fcb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/991eb7888c694783998c4e83ff7e5821e8810c767a3849a7edaaece27d8d43d61a1150dc69090db0542fa068f9dfdefbd2079d1983cab146ccf400af858288c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8443d44ff06eeaadc9427da91d1bea1c613957fcd482366bbcdf95d0906bfcbe9504022feb01b13b7ca741aa52da1555c2a2f17f1143383e199872cabe05a49"} 05:13:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:13:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:13:16 INFO - Contents: 05:13:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0057649031fe5d1348d7e5b3a3d85447f7213586573d88c62ba1bd2386e8a48852540720adc6f15fc79d5043983b3f924fb70f64f144ac22b2c2a62d66961ac0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/44867e6032390f8bcea0c08bf7180c41d911b3fae64ad6b5e7c7c1f309da0dcb684495867c7b947388935f70234abe122de0816aa66eb11e993255750aa6fcb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/991eb7888c694783998c4e83ff7e5821e8810c767a3849a7edaaece27d8d43d61a1150dc69090db0542fa068f9dfdefbd2079d1983cab146ccf400af858288c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8443d44ff06eeaadc9427da91d1bea1c613957fcd482366bbcdf95d0906bfcbe9504022feb01b13b7ca741aa52da1555c2a2f17f1143383e199872cabe05a49"} 05:13:16 INFO - Running post-run listener: copy_logs_to_upload_dir 05:13:16 INFO - Copying logs to upload dir... 05:13:16 INFO - mkdir: /builds/slave/test/build/upload/logs 05:13:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1307.254960 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 47 secs) (at 2017-02-10 05:13:16.895135) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-10 05:13:16.899246) ========= 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.9TUJl7S2A6/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.8gnxpa219T/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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/Mozilla-Aurora/sha512/0057649031fe5d1348d7e5b3a3d85447f7213586573d88c62ba1bd2386e8a48852540720adc6f15fc79d5043983b3f924fb70f64f144ac22b2c2a62d66961ac0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/44867e6032390f8bcea0c08bf7180c41d911b3fae64ad6b5e7c7c1f309da0dcb684495867c7b947388935f70234abe122de0816aa66eb11e993255750aa6fcb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/991eb7888c694783998c4e83ff7e5821e8810c767a3849a7edaaece27d8d43d61a1150dc69090db0542fa068f9dfdefbd2079d1983cab146ccf400af858288c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8443d44ff06eeaadc9427da91d1bea1c613957fcd482366bbcdf95d0906bfcbe9504022feb01b13b7ca741aa52da1555c2a2f17f1143383e199872cabe05a49"} build_url:https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.012630 build_url: 'https://queue.taskcluster.net/v1/task/Q5WgZTakTdiySX9nclANBA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0057649031fe5d1348d7e5b3a3d85447f7213586573d88c62ba1bd2386e8a48852540720adc6f15fc79d5043983b3f924fb70f64f144ac22b2c2a62d66961ac0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/44867e6032390f8bcea0c08bf7180c41d911b3fae64ad6b5e7c7c1f309da0dcb684495867c7b947388935f70234abe122de0816aa66eb11e993255750aa6fcb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/991eb7888c694783998c4e83ff7e5821e8810c767a3849a7edaaece27d8d43d61a1150dc69090db0542fa068f9dfdefbd2079d1983cab146ccf400af858288c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8443d44ff06eeaadc9427da91d1bea1c613957fcd482366bbcdf95d0906bfcbe9504022feb01b13b7ca741aa52da1555c2a2f17f1143383e199872cabe05a49"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-10 05:13:16.945239) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 05:13:16.945811) ========= 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.9TUJl7S2A6/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.8gnxpa219T/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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.006346 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-10 05:13:16.998107) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-10 05:13:16.998706) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-10 05:13:16.999337) ========= ========= Total master_lag: 0.25 =========