builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-009 starttime: 1482851577.53 results: success (0) buildid: 20161227061937 builduid: b527b91f0455464da9f82baf68493d95 revision: 9ab4320b60d3b40b7936bc50b6b53c0e6a5d70c7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 07:12:57.530749) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 07:12:57.531183) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 07:12:57.531507) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 07:12:57.582296) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 07:12:57.582608) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-27 07:12:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (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% 46.8M=0s 2016-12-27 07:12:57 (46.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.210105 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 07:12:57.835877) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 07:12:57.836554) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.061793 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 07:12:57.912861) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 07:12:57.913651) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 9ab4320b60d3b40b7936bc50b6b53c0e6a5d70c7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 9ab4320b60d3b40b7936bc50b6b53c0e6a5d70c7 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-27 07:12:57,985 truncating revision to first 12 chars 2016-12-27 07:12:57,985 Setting DEBUG logging. 2016-12-27 07:12:57,985 attempt 1/10 2016-12-27 07:12:57,985 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/9ab4320b60d3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-27 07:12:58,421 unpacking tar archive at: graphics-9ab4320b60d3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.791810 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 07:12:58.722171) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 07:12:58.722941) ========= script_repo_revision: 9ab4320b60d3b40b7936bc50b6b53c0e6a5d70c7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 07:12:58.723943) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 07:12:58.724660) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 07:12:58.732593) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-12-27 07:12:58.732909) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:12:58 INFO - MultiFileLogger online at 20161227 07:12:58 in /builds/slave/test 07:12:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 07:12:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:12:58 INFO - {'append_to_log': False, 07:12:58 INFO - 'base_work_dir': '/builds/slave/test', 07:12:58 INFO - 'blob_upload_branch': 'Graphics', 07:12:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:12:58 INFO - 'branch': 'Graphics', 07:12:58 INFO - 'buildbot_json_path': 'buildprops.json', 07:12:58 INFO - 'config_files': ('talos/linux_config.py',), 07:12:58 INFO - 'default_actions': ('clobber', 07:12:58 INFO - 'read-buildbot-config', 07:12:58 INFO - 'download-and-extract', 07:12:58 INFO - 'populate-webroot', 07:12:58 INFO - 'create-virtualenv', 07:12:58 INFO - 'install', 07:12:58 INFO - 'run-tests'), 07:12:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:12:58 INFO - 'download_minidump_stackwalk': True, 07:12:58 INFO - 'download_symbols': 'ondemand', 07:12:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:12:58 INFO - 'tooltool.py': '/tools/tooltool.py', 07:12:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:12:58 INFO - '/tools/misc-python/virtualenv.py')}, 07:12:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:12:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:12:58 INFO - 'installer_path': 'installer.exe', 07:12:58 INFO - 'log_level': 'info', 07:12:58 INFO - 'log_name': 'talos', 07:12:58 INFO - 'log_to_console': True, 07:12:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:12:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:12:58 INFO - 'opt_config_files': (), 07:12:58 INFO - 'pip_index': False, 07:12:58 INFO - 'sps_profile': False, 07:12:58 INFO - 'sps_profile_interval': 0, 07:12:58 INFO - 'suite': 'dromaeojs', 07:12:58 INFO - 'system_bits': '32', 07:12:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:12:58 INFO - 'title': 'talos-linux64-ix-009', 07:12:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:12:58 INFO - 'use_talos_json': True, 07:12:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:12:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:12:59 INFO - 'work_dir': 'build'} 07:12:59 INFO - [mozharness: 2016-12-27 15:12:59.000332Z] Running clobber step. 07:12:59 INFO - Running pre-action listener: _resource_record_pre_action 07:12:59 INFO - Running main action method: clobber 07:12:59 INFO - rmtree: /builds/slave/test/build 07:12:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:13:03 INFO - Running post-action listener: _resource_record_post_action 07:13:03 INFO - [mozharness: 2016-12-27 15:13:03.580424Z] Finished clobber step (success) 07:13:03 INFO - [mozharness: 2016-12-27 15:13:03.580524Z] Running read-buildbot-config step. 07:13:03 INFO - Running pre-action listener: _resource_record_pre_action 07:13:03 INFO - Running main action method: read_buildbot_config 07:13:03 INFO - Using buildbot properties: 07:13:03 INFO - { 07:13:03 INFO - "project": "", 07:13:03 INFO - "product": "firefox", 07:13:03 INFO - "script_repo_revision": "default", 07:13:03 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 07:13:03 INFO - "repository": "", 07:13:03 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 07:13:03 INFO - "buildid": "20161227061937", 07:13:03 INFO - "pgo_build": "False", 07:13:03 INFO - "basedir": "/builds/slave/test", 07:13:03 INFO - "buildnumber": 40, 07:13:03 INFO - "slavename": "talos-linux64-ix-009", 07:13:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:13:03 INFO - "platform": "ubuntu64_hw", 07:13:03 INFO - "scheduler": "tests-graphics-linux64-talos", 07:13:03 INFO - "branch": "graphics", 07:13:03 INFO - "repo_path": "projects/graphics", 07:13:03 INFO - "revision": "9ab4320b60d3b40b7936bc50b6b53c0e6a5d70c7", 07:13:03 INFO - "stage_platform": "linux64", 07:13:03 INFO - "builduid": "b527b91f0455464da9f82baf68493d95", 07:13:03 INFO - "slavebuilddir": "test" 07:13:03 INFO - } 07:13:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 07:13:03 INFO - Running post-action listener: _resource_record_post_action 07:13:03 INFO - [mozharness: 2016-12-27 15:13:03.583009Z] Finished read-buildbot-config step (success) 07:13:03 INFO - [mozharness: 2016-12-27 15:13:03.583099Z] Running download-and-extract step. 07:13:03 INFO - Running pre-action listener: _resource_record_pre_action 07:13:03 INFO - Running main action method: download_and_extract 07:13:03 INFO - mkdir: /builds/slave/test/build/tests 07:13:03 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')]} 07:13:03 INFO - trying https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:13:03 INFO - Downloading https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:13:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:13:04 INFO - Downloaded 1361 bytes. 07:13:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 07:13:04 INFO - Using the following test package requirements: 07:13:04 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:13:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:13:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:13:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:04 INFO - u'jsshell-linux-x86_64.zip'], 07:13:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:13:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 07:13:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:13:04 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:13:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:13:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 07:13:04 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:13:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:13:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:13:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:13:04 INFO - Fetch https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:13:04 INFO - Expected file size: 20236321 07:13:04 INFO - Obtained file size: 20236321 07:13:08 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:13:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 07:13:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:13:08 INFO - Fetch https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 07:13:09 INFO - Expected file size: 20236321 07:13:09 INFO - Obtained file size: 20236321 07:13:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 07:13:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:13:14 INFO - Fetch https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 07:13:15 INFO - Expected file size: 14409776 07:13:15 INFO - Obtained file size: 14409776 07:13:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:15 INFO - trying https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:13:15 INFO - Downloading https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:13:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:13:16 INFO - Downloaded 55926577 bytes. 07:13:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:13:16 INFO - mkdir: /builds/slave/test/properties 07:13:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:13:16 INFO - Writing to file /builds/slave/test/properties/build_url 07:13:16 INFO - Contents: 07:13:16 INFO - build_url:https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 07:13:17 INFO - Running post-action listener: _resource_record_post_action 07:13:17 INFO - Running post-action listener: set_extra_try_arguments 07:13:17 INFO - [mozharness: 2016-12-27 15:13:17.169407Z] Finished download-and-extract step (success) 07:13:17 INFO - [mozharness: 2016-12-27 15:13:17.169636Z] Running populate-webroot step. 07:13:17 INFO - Running pre-action listener: _resource_record_pre_action 07:13:17 INFO - Running main action method: populate_webroot 07:13:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:13:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:13:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:13:17 INFO - '--tppagecycles', 07:13:17 INFO - '7'], 07:13:17 INFO - 'tests': ['tsvgm']}}, 07:13:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:13:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:13:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:13:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:13:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:13:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:13:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:13:17 INFO - 'talos_options': ['--disable-e10s'], 07:13:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:13:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:13:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:13:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:13:17 INFO - 'talos_options': ['--disable-e10s'], 07:13:17 INFO - 'tests': ['damp', 'tps']}, 07:13:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:13:17 INFO - 'tests': ['damp', 'tps']}, 07:13:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:13:17 INFO - 'tests': ['dromaeo_dom']}, 07:13:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:13:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:13:17 INFO - 'tests': ['basic_compositor_video']}, 07:13:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:13:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:13:17 INFO - 'tests': ['a11yr', 07:13:17 INFO - 'ts_paint', 07:13:17 INFO - 'tpaint', 07:13:17 INFO - 'sessionrestore', 07:13:17 INFO - 'sessionrestore_no_auto_restore', 07:13:17 INFO - 'tabpaint']}, 07:13:17 INFO - 'other-e10s': {'tests': ['a11yr', 07:13:17 INFO - 'ts_paint', 07:13:17 INFO - 'tpaint', 07:13:17 INFO - 'sessionrestore', 07:13:17 INFO - 'sessionrestore_no_auto_restore', 07:13:17 INFO - 'tabpaint']}, 07:13:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:13:17 INFO - 'ts_paint', 07:13:17 INFO - 'tpaint', 07:13:17 INFO - 'sessionrestore', 07:13:17 INFO - 'sessionrestore_no_auto_restore', 07:13:17 INFO - 'tabpaint']}, 07:13:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:13:17 INFO - 'ts_paint', 07:13:17 INFO - 'tpaint', 07:13:17 INFO - 'sessionrestore', 07:13:17 INFO - 'sessionrestore_no_auto_restore', 07:13:17 INFO - 'tabpaint']}, 07:13:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:13:17 INFO - 'tests': ['a11yr', 07:13:17 INFO - 'ts_paint', 07:13:17 INFO - 'tpaint', 07:13:17 INFO - 'sessionrestore', 07:13:17 INFO - 'sessionrestore_no_auto_restore', 07:13:17 INFO - 'tabpaint']}, 07:13:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:13:17 INFO - 'tests': ['a11yr', 07:13:17 INFO - 'ts_paint', 07:13:17 INFO - 'tpaint', 07:13:17 INFO - 'sessionrestore', 07:13:17 INFO - 'sessionrestore_no_auto_restore', 07:13:17 INFO - 'tabpaint']}, 07:13:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:13:17 INFO - 'tests': ['tsvgx', 07:13:17 INFO - 'tsvgr_opacity', 07:13:17 INFO - 'tart', 07:13:17 INFO - 'tscrollx', 07:13:17 INFO - 'cart', 07:13:17 INFO - 'tsvg_static']}, 07:13:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:13:17 INFO - 'tsvgr_opacity', 07:13:17 INFO - 'tart', 07:13:17 INFO - 'tscrollx', 07:13:17 INFO - 'cart', 07:13:17 INFO - 'tsvg_static']}, 07:13:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:13:17 INFO - 'talos_options': ['--disable-e10s'], 07:13:17 INFO - 'tests': ['tp5o']}, 07:13:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:13:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:13:17 INFO - 'talos_options': ['--disable-e10s', 07:13:17 INFO - '--xperf_path', 07:13:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:13:17 INFO - 'tests': ['tp5n']}, 07:13:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:13:17 INFO - 'talos_options': ['--xperf_path', 07:13:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:13:17 INFO - 'tests': ['tp5n']}}, 07:13:17 INFO - 'talos.zip': {'path': '', 07:13:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:13:17 INFO - Running post-action listener: _resource_record_post_action 07:13:17 INFO - [mozharness: 2016-12-27 15:13:17.187361Z] Finished populate-webroot step (success) 07:13:17 INFO - [mozharness: 2016-12-27 15:13:17.187449Z] Running create-virtualenv step. 07:13:17 INFO - Running pre-action listener: _resource_record_pre_action 07:13:17 INFO - Running main action method: create_virtualenv 07:13:17 INFO - Creating virtualenv /builds/slave/test/build/venv 07:13:17 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 07:13:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:13:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:13:17 INFO - Using real prefix '/usr' 07:13:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:13:17 INFO - Installing distribute.............................................................................................................................................................................................done. 07:13:19 INFO - Installing pip.................done. 07:13:19 INFO - Return code: 0 07:13:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:13:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:13:20 INFO - Reading from file tmpfile_stdout 07:13:20 INFO - Output received: 07:13:20 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:13:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:13:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:20 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 0x266e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29dfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3f0>, '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 0x29ddcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:13:20 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 07:13:20 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 07:13:20 INFO - Using env: {'DISPLAY': ':0', 07:13:20 INFO - 'HOME': '/home/cltbld', 07:13:20 INFO - 'LANG': 'en_US.UTF-8', 07:13:20 INFO - 'LANGUAGE': 'en_US:en', 07:13:20 INFO - 'LOGNAME': 'cltbld', 07:13:20 INFO - 'MAIL': '/var/mail/cltbld', 07:13:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:20 INFO - 'MOZ_NO_REMOTE': '1', 07:13:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:20 INFO - 'NO_EM_RESTART': '1', 07:13:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:20 INFO - 'PWD': '/builds/slave/test', 07:13:20 INFO - 'SHELL': '/bin/bash', 07:13:20 INFO - 'SHLVL': '1', 07:13:20 INFO - 'TERM': 'linux', 07:13:20 INFO - 'TMOUT': '86400', 07:13:20 INFO - 'USER': 'cltbld', 07:13:20 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 07:13:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:20 INFO - '_': '/tools/buildbot/bin/python'} 07:13:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:20 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 07:13:20 INFO - Cleaning up... 07:13:20 INFO - Return code: 0 07:13:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:13:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:20 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 0x266e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29dfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3f0>, '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 0x29ddcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:13:20 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 07:13:20 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 07:13:20 INFO - Using env: {'DISPLAY': ':0', 07:13:20 INFO - 'HOME': '/home/cltbld', 07:13:20 INFO - 'LANG': 'en_US.UTF-8', 07:13:20 INFO - 'LANGUAGE': 'en_US:en', 07:13:20 INFO - 'LOGNAME': 'cltbld', 07:13:20 INFO - 'MAIL': '/var/mail/cltbld', 07:13:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:20 INFO - 'MOZ_NO_REMOTE': '1', 07:13:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:20 INFO - 'NO_EM_RESTART': '1', 07:13:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:20 INFO - 'PWD': '/builds/slave/test', 07:13:20 INFO - 'SHELL': '/bin/bash', 07:13:20 INFO - 'SHLVL': '1', 07:13:20 INFO - 'TERM': 'linux', 07:13:20 INFO - 'TMOUT': '86400', 07:13:20 INFO - 'USER': 'cltbld', 07:13:20 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 07:13:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:20 INFO - '_': '/tools/buildbot/bin/python'} 07:13:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:20 INFO - Downloading/unpacking psutil>=3.1.1 07:13:20 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 07:13:20 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 07:13:20 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 07:13:20 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 07:13:20 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 07:13:20 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 07:13:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:13:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:13:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:13:22 INFO - Installing collected packages: psutil 07:13:22 INFO - Running setup.py install for psutil 07:13:22 INFO - building 'psutil._psutil_linux' extension 07:13:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:13:24 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:13:24 INFO - building 'psutil._psutil_posix' extension 07:13:24 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:13:24 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:13:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:13:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:13:24 INFO - Successfully installed psutil 07:13:24 INFO - Cleaning up... 07:13:24 INFO - Return code: 0 07:13:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:13:24 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')]} 07:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:24 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')]} 07:13:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:24 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 0x266e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29dfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3f0>, '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 0x29ddcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:13:24 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 07:13:24 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 07:13:24 INFO - Using env: {'DISPLAY': ':0', 07:13:24 INFO - 'HOME': '/home/cltbld', 07:13:24 INFO - 'LANG': 'en_US.UTF-8', 07:13:24 INFO - 'LANGUAGE': 'en_US:en', 07:13:24 INFO - 'LOGNAME': 'cltbld', 07:13:24 INFO - 'MAIL': '/var/mail/cltbld', 07:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:24 INFO - 'MOZ_NO_REMOTE': '1', 07:13:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:24 INFO - 'NO_EM_RESTART': '1', 07:13:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:24 INFO - 'PWD': '/builds/slave/test', 07:13:24 INFO - 'SHELL': '/bin/bash', 07:13:24 INFO - 'SHLVL': '1', 07:13:24 INFO - 'TERM': 'linux', 07:13:24 INFO - 'TMOUT': '86400', 07:13:24 INFO - 'USER': 'cltbld', 07:13:24 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 07:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:24 INFO - '_': '/tools/buildbot/bin/python'} 07:13:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:13:24 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 07:13:24 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 07:13:24 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 07:13:24 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 07:13:24 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 07:13:24 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 07:13:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:13:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:13:26 INFO - Installing collected packages: mozsystemmonitor 07:13:26 INFO - Running setup.py install for mozsystemmonitor 07:13:26 INFO - Successfully installed mozsystemmonitor 07:13:26 INFO - Cleaning up... 07:13:26 INFO - Return code: 0 07:13:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x266e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29dfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3f0>, '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 0x29ddcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:13:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:13:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:13:26 INFO - Using env: {'DISPLAY': ':0', 07:13:26 INFO - 'HOME': '/home/cltbld', 07:13:26 INFO - 'LANG': 'en_US.UTF-8', 07:13:26 INFO - 'LANGUAGE': 'en_US:en', 07:13:26 INFO - 'LOGNAME': 'cltbld', 07:13:26 INFO - 'MAIL': '/var/mail/cltbld', 07:13:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:26 INFO - 'MOZ_NO_REMOTE': '1', 07:13:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:26 INFO - 'NO_EM_RESTART': '1', 07:13:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:26 INFO - 'PWD': '/builds/slave/test', 07:13:26 INFO - 'SHELL': '/bin/bash', 07:13:26 INFO - 'SHLVL': '1', 07:13:26 INFO - 'TERM': 'linux', 07:13:26 INFO - 'TMOUT': '86400', 07:13:26 INFO - 'USER': 'cltbld', 07:13:26 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 07:13:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:26 INFO - '_': '/tools/buildbot/bin/python'} 07:13:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:27 INFO - Downloading/unpacking jsonschema==2.5.1 07:13: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 07:13: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 07:13: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 07:13: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 07:13: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 07:13: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 07:13:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:13:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:13:29 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 07:13:29 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 07:13:29 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 07:13:29 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 07:13:29 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 07:13:29 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 07:13:29 INFO - Downloading functools32-3.2.3-2.tar.gz 07:13:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:13:29 INFO - warning: no files found matching '*.txt' 07:13:29 INFO - no previously-included directories found matching 'build' 07:13:29 INFO - no previously-included directories found matching 'dist' 07:13:29 INFO - no previously-included directories found matching '.git*' 07:13:29 INFO - Installing collected packages: jsonschema, functools32 07:13:29 INFO - Running setup.py install for functools32 07:13:29 INFO - warning: no files found matching '*.txt' 07:13:29 INFO - no previously-included directories found matching 'build' 07:13:29 INFO - no previously-included directories found matching 'dist' 07:13:29 INFO - no previously-included directories found matching '.git*' 07:13:29 INFO - Successfully installed jsonschema functools32 07:13:29 INFO - Cleaning up... 07:13:29 INFO - Return code: 0 07:13:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:13:29 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')]} 07:13:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:29 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')]} 07:13:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:29 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 0x266e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29dfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3f0>, '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 0x29ddcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:13:29 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 07:13:29 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 07:13:29 INFO - Using env: {'DISPLAY': ':0', 07:13:29 INFO - 'HOME': '/home/cltbld', 07:13:29 INFO - 'LANG': 'en_US.UTF-8', 07:13:29 INFO - 'LANGUAGE': 'en_US:en', 07:13:29 INFO - 'LOGNAME': 'cltbld', 07:13:29 INFO - 'MAIL': '/var/mail/cltbld', 07:13:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:29 INFO - 'MOZ_NO_REMOTE': '1', 07:13:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:29 INFO - 'NO_EM_RESTART': '1', 07:13:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:29 INFO - 'PWD': '/builds/slave/test', 07:13:29 INFO - 'SHELL': '/bin/bash', 07:13:29 INFO - 'SHLVL': '1', 07:13:29 INFO - 'TERM': 'linux', 07:13:29 INFO - 'TMOUT': '86400', 07:13:29 INFO - 'USER': 'cltbld', 07:13:29 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 07:13:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:29 INFO - '_': '/tools/buildbot/bin/python'} 07:13:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:13:29 INFO - Cleaning up... 07:13:29 INFO - Return code: 0 07:13:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:13:29 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')]} 07:13:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:29 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')]} 07:13:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:29 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 0x266e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29dfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3f0>, '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 0x29ddcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:13:29 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 07:13:29 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 07:13:29 INFO - Using env: {'DISPLAY': ':0', 07:13:29 INFO - 'HOME': '/home/cltbld', 07:13:29 INFO - 'LANG': 'en_US.UTF-8', 07:13:29 INFO - 'LANGUAGE': 'en_US:en', 07:13:29 INFO - 'LOGNAME': 'cltbld', 07:13:29 INFO - 'MAIL': '/var/mail/cltbld', 07:13:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:29 INFO - 'MOZ_NO_REMOTE': '1', 07:13:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:29 INFO - 'NO_EM_RESTART': '1', 07:13:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:29 INFO - 'PWD': '/builds/slave/test', 07:13:29 INFO - 'SHELL': '/bin/bash', 07:13:29 INFO - 'SHLVL': '1', 07:13:29 INFO - 'TERM': 'linux', 07:13:29 INFO - 'TMOUT': '86400', 07:13:29 INFO - 'USER': 'cltbld', 07:13:29 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 07:13:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:29 INFO - '_': '/tools/buildbot/bin/python'} 07:13:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:30 INFO - Downloading/unpacking blobuploader==1.2.4 07:13:30 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 07:13:30 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 07:13:30 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 07:13:30 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 07:13:30 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 07:13:30 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 07:13:32 INFO - Downloading blobuploader-1.2.4.tar.gz 07:13:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:13:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:13:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:32 INFO - Downloading docopt-0.6.1.tar.gz 07:13:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:13:32 INFO - Installing collected packages: blobuploader, requests, docopt 07:13:32 INFO - Running setup.py install for blobuploader 07:13:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:13:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:13:33 INFO - Running setup.py install for requests 07:13:33 INFO - Running setup.py install for docopt 07:13:33 INFO - Successfully installed blobuploader requests docopt 07:13:33 INFO - Cleaning up... 07:13:33 INFO - Return code: 0 07:13:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:13:33 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')]} 07:13:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:33 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')]} 07:13:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:33 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 0x266e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29dfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3f0>, '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 0x29ddcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:13:33 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 07:13:33 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 07:13:33 INFO - Using env: {'DISPLAY': ':0', 07:13:33 INFO - 'HOME': '/home/cltbld', 07:13:33 INFO - 'LANG': 'en_US.UTF-8', 07:13:33 INFO - 'LANGUAGE': 'en_US:en', 07:13:33 INFO - 'LOGNAME': 'cltbld', 07:13:33 INFO - 'MAIL': '/var/mail/cltbld', 07:13:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:33 INFO - 'MOZ_NO_REMOTE': '1', 07:13:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:33 INFO - 'NO_EM_RESTART': '1', 07:13:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:33 INFO - 'PWD': '/builds/slave/test', 07:13:33 INFO - 'SHELL': '/bin/bash', 07:13:33 INFO - 'SHLVL': '1', 07:13:33 INFO - 'TERM': 'linux', 07:13:33 INFO - 'TMOUT': '86400', 07:13:33 INFO - 'USER': 'cltbld', 07:13:33 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 07:13:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:33 INFO - '_': '/tools/buildbot/bin/python'} 07:13:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:13:33 INFO - Running setup.py (path:/tmp/pip-vd4TO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:13:33 INFO - Running setup.py (path:/tmp/pip-1UKfq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:13:33 INFO - Running setup.py (path:/tmp/pip-RB9nF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:13:34 INFO - Running setup.py (path:/tmp/pip-VVB73R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:13:34 INFO - Running setup.py (path:/tmp/pip-Pn8Xk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:13:34 INFO - Running setup.py (path:/tmp/pip-Roz82R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:13:34 INFO - Running setup.py (path:/tmp/pip-N7ACTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:13:34 INFO - Running setup.py (path:/tmp/pip-1DdXBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:13:34 INFO - Running setup.py (path:/tmp/pip-w9zU2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:13:34 INFO - Running setup.py (path:/tmp/pip-cDVO6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:13:34 INFO - Running setup.py (path:/tmp/pip-O1kHMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:13:34 INFO - Running setup.py (path:/tmp/pip-rH6l0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:13:34 INFO - Running setup.py (path:/tmp/pip-M2rdNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:13:34 INFO - Running setup.py (path:/tmp/pip-JADWNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:13:34 INFO - Running setup.py (path:/tmp/pip-FS_Q23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:13:34 INFO - Running setup.py (path:/tmp/pip-wNQQ5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:13:34 INFO - Running setup.py (path:/tmp/pip-8Fz_ui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:13:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:13:34 INFO - Running setup.py install for manifestparser 07:13:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:13:35 INFO - Running setup.py install for mozcrash 07:13:35 INFO - Running setup.py install for mozdebug 07:13:35 INFO - Running setup.py install for mozdevice 07:13:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:13:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:13:35 INFO - Running setup.py install for mozfile 07:13:35 INFO - Running setup.py install for mozhttpd 07:13:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:13:35 INFO - Running setup.py install for mozinfo 07:13:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:13:35 INFO - Running setup.py install for mozInstall 07:13:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:13:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:13:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:13:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:13:35 INFO - Running setup.py install for mozleak 07:13:35 INFO - Running setup.py install for mozlog 07:13:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:13:36 INFO - Running setup.py install for moznetwork 07:13:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:13:36 INFO - Running setup.py install for mozprocess 07:13:36 INFO - Running setup.py install for mozprofile 07:13:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:13:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:13:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:13:36 INFO - Running setup.py install for mozrunner 07:13:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:13:36 INFO - Running setup.py install for mozscreenshot 07:13:36 INFO - Running setup.py install for moztest 07:13:36 INFO - Running setup.py install for mozversion 07:13:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:13:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:13:36 INFO - Cleaning up... 07:13:36 INFO - Return code: 0 07:13:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:13: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')]} 07:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13: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')]} 07:13:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13: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 0x266e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29dfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3f0>, '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 0x29ddcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:13: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 07:13: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 07:13:36 INFO - Using env: {'DISPLAY': ':0', 07:13:36 INFO - 'HOME': '/home/cltbld', 07:13:36 INFO - 'LANG': 'en_US.UTF-8', 07:13:36 INFO - 'LANGUAGE': 'en_US:en', 07:13:36 INFO - 'LOGNAME': 'cltbld', 07:13:36 INFO - 'MAIL': '/var/mail/cltbld', 07:13:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:36 INFO - 'MOZ_NO_REMOTE': '1', 07:13:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:36 INFO - 'NO_EM_RESTART': '1', 07:13:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:36 INFO - 'PWD': '/builds/slave/test', 07:13:36 INFO - 'SHELL': '/bin/bash', 07:13:36 INFO - 'SHLVL': '1', 07:13:36 INFO - 'TERM': 'linux', 07:13:36 INFO - 'TMOUT': '86400', 07:13:36 INFO - 'USER': 'cltbld', 07:13:36 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 07:13:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:36 INFO - '_': '/tools/buildbot/bin/python'} 07:13:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:13:37 INFO - Running setup.py (path:/tmp/pip-gSEyhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:13: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)) 07:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:13:37 INFO - Running setup.py (path:/tmp/pip-BiKcL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:13: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)) 07:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:13:37 INFO - Running setup.py (path:/tmp/pip-PscDeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:13: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)) 07:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:13:37 INFO - Running setup.py (path:/tmp/pip-FjY0ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:13: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)) 07:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:13:37 INFO - Running setup.py (path:/tmp/pip-LPXhqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:13: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)) 07:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:13:37 INFO - Running setup.py (path:/tmp/pip-mVanbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:13: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)) 07:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:13:37 INFO - Running setup.py (path:/tmp/pip-Hp4bft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:13:37 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)) 07:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:13:37 INFO - Running setup.py (path:/tmp/pip-U253M7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:13:37 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)) 07:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:13:37 INFO - Running setup.py (path:/tmp/pip-owbd0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:13:37 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)) 07:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:13:37 INFO - Running setup.py (path:/tmp/pip-VTFPeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:13:37 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)) 07:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:13:37 INFO - Running setup.py (path:/tmp/pip-dp4cZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:13:37 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)) 07:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:13:37 INFO - Running setup.py (path:/tmp/pip-aOhWVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:13:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:13:37 INFO - Running setup.py (path:/tmp/pip-FEwxMY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:13:37 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)) 07:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:13:37 INFO - Running setup.py (path:/tmp/pip-_CCZS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:13:38 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)) 07:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:13:38 INFO - Running setup.py (path:/tmp/pip-rsLTy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:13:38 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)) 07:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:13:38 INFO - Running setup.py (path:/tmp/pip-d8xI_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:13:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:13:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:13:38 INFO - Running setup.py (path:/tmp/pip-ivGBwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:13:38 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)) 07:13:38 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)) 07:13:38 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)) 07:13:38 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)) 07:13:38 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)) 07:13:38 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)) 07:13:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:40 INFO - Downloading blessings-1.6.tar.gz 07:13:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:13:40 INFO - Installing collected packages: blessings 07:13:40 INFO - Running setup.py install for blessings 07:13:40 INFO - Successfully installed blessings 07:13:40 INFO - Cleaning up... 07:13:40 INFO - Return code: 0 07:13:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:13:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:13:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:13:40 INFO - Reading from file tmpfile_stdout 07:13:40 INFO - Current package versions: 07:13:40 INFO - argparse == 1.2.1 07:13:40 INFO - blessings == 1.6 07:13:40 INFO - blobuploader == 1.2.4 07:13:40 INFO - docopt == 0.6.1 07:13:40 INFO - functools32 == 3.2.3-2 07:13:40 INFO - jsonschema == 2.5.1 07:13:40 INFO - manifestparser == 1.1 07:13:40 INFO - mozInstall == 1.12 07:13:40 INFO - mozcrash == 1.0 07:13:40 INFO - mozdebug == 0.1 07:13:40 INFO - mozdevice == 0.49 07:13:40 INFO - mozfile == 1.2 07:13:40 INFO - mozhttpd == 0.7 07:13:40 INFO - mozinfo == 0.9 07:13:40 INFO - mozleak == 0.1 07:13:40 INFO - mozlog == 3.4 07:13:40 INFO - moznetwork == 0.27 07:13:40 INFO - mozprocess == 0.23 07:13:40 INFO - mozprofile == 0.28 07:13:40 INFO - mozrunner == 6.13 07:13:40 INFO - mozscreenshot == 0.1 07:13:40 INFO - mozsystemmonitor == 0.3 07:13:40 INFO - moztest == 0.7 07:13:40 INFO - mozversion == 1.4 07:13:40 INFO - psutil == 3.1.1 07:13:40 INFO - requests == 1.2.3 07:13:40 INFO - wsgiref == 0.1.2 07:13:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:13:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:40 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 0x266e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29dfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3f0>, '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 0x29ddcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:13:40 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 07:13:40 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 07:13:40 INFO - Using env: {'DISPLAY': ':0', 07:13:40 INFO - 'HOME': '/home/cltbld', 07:13:40 INFO - 'LANG': 'en_US.UTF-8', 07:13:40 INFO - 'LANGUAGE': 'en_US:en', 07:13:40 INFO - 'LOGNAME': 'cltbld', 07:13:40 INFO - 'MAIL': '/var/mail/cltbld', 07:13:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:40 INFO - 'MOZ_NO_REMOTE': '1', 07:13:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:40 INFO - 'NO_EM_RESTART': '1', 07:13:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:40 INFO - 'PWD': '/builds/slave/test', 07:13:40 INFO - 'SHELL': '/bin/bash', 07:13:40 INFO - 'SHLVL': '1', 07:13:40 INFO - 'TERM': 'linux', 07:13:40 INFO - 'TMOUT': '86400', 07:13:40 INFO - 'USER': 'cltbld', 07:13:40 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 07:13:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:40 INFO - '_': '/tools/buildbot/bin/python'} 07:13:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:40 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)) 07:13:40 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)) 07:13:40 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)) 07:13:40 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)) 07:13:40 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)) 07:13:40 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)) 07:13:40 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)) 07:13:40 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)) 07:13:40 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)) 07:13:40 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)) 07:13:40 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)) 07:13:40 INFO - Cleaning up... 07:13:41 INFO - Return code: 0 07:13:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:13:41 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')]} 07:13:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:41 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')]} 07:13:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x266e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x283aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29dfc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3f0>, '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 0x29ddcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de1a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 07:13:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:13:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:13:41 INFO - Using env: {'DISPLAY': ':0', 07:13:41 INFO - 'HOME': '/home/cltbld', 07:13:41 INFO - 'LANG': 'en_US.UTF-8', 07:13:41 INFO - 'LANGUAGE': 'en_US:en', 07:13:41 INFO - 'LOGNAME': 'cltbld', 07:13:41 INFO - 'MAIL': '/var/mail/cltbld', 07:13:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:41 INFO - 'MOZ_NO_REMOTE': '1', 07:13:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:41 INFO - 'NO_EM_RESTART': '1', 07:13:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:41 INFO - 'PWD': '/builds/slave/test', 07:13:41 INFO - 'SHELL': '/bin/bash', 07:13:41 INFO - 'SHLVL': '1', 07:13:41 INFO - 'TERM': 'linux', 07:13:41 INFO - 'TMOUT': '86400', 07:13:41 INFO - 'USER': 'cltbld', 07:13:41 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 07:13:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:41 INFO - '_': '/tools/buildbot/bin/python'} 07:13:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:13:41 INFO - Cleaning up... 07:13:41 INFO - Return code: 0 07:13:41 INFO - Running post-action listener: _resource_record_post_action 07:13:41 INFO - Running post-action listener: _start_resource_monitoring 07:13:41 INFO - Starting resource monitoring. 07:13:41 INFO - [mozharness: 2016-12-27 15:13:41.267084Z] Finished create-virtualenv step (success) 07:13:41 INFO - [mozharness: 2016-12-27 15:13:41.268044Z] Running install step. 07:13:41 INFO - Running pre-action listener: _resource_record_pre_action 07:13:41 INFO - Running main action method: install 07:13:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:13:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:13:41 INFO - Reading from file tmpfile_stdout 07:13:41 INFO - Detecting whether we're running mozinstall >=1.0... 07:13:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:13:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:13:41 INFO - Reading from file tmpfile_stdout 07:13:41 INFO - Output received: 07:13:41 INFO - Usage: mozinstall [options] installer 07:13:41 INFO - Options: 07:13:41 INFO - -h, --help show this help message and exit 07:13:41 INFO - -d DEST, --destination=DEST 07:13:41 INFO - Directory to install application into. [default: 07:13:41 INFO - "/builds/slave/test"] 07:13:41 INFO - --app=APP Application being installed. [default: firefox] 07:13:41 INFO - mkdir: /builds/slave/test/build/application 07:13:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:13:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:13:57 INFO - Reading from file tmpfile_stdout 07:13:57 INFO - Output received: 07:13:57 INFO - /builds/slave/test/build/application/firefox/firefox 07:13:57 INFO - Running post-action listener: _resource_record_post_action 07:13:57 INFO - [mozharness: 2016-12-27 15:13:57.686101Z] Finished install step (success) 07:13:57 INFO - [mozharness: 2016-12-27 15:13:57.686350Z] Running run-tests step. 07:13:57 INFO - Running pre-action listener: _resource_record_pre_action 07:13:57 INFO - Running main action method: run_tests 07:13:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:13:57 INFO - Python 2.7.3 07:13:57 INFO - Return code: 0 07:13:57 INFO - grabbing minidump binary from tooltool 07:13:57 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')]} 07:13:57 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/linux64/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 0x29dd3f0>, '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 0x29ddcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:13:57 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:13:57 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/linux64/releng.manifest -o -c /builds/tooltool_cache 07:13:57 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:13:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:13:57 INFO - Return code: 0 07:13:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:13:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:13:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:13:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:13:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:13:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:13:57 INFO - Using env: {'DISPLAY': ':0', 07:13:57 INFO - 'HOME': '/home/cltbld', 07:13:57 INFO - 'LANG': 'en_US.UTF-8', 07:13:57 INFO - 'LANGUAGE': 'en_US:en', 07:13:57 INFO - 'LOGNAME': 'cltbld', 07:13:57 INFO - 'MAIL': '/var/mail/cltbld', 07:13:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:13:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:13:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:57 INFO - 'MOZ_NO_REMOTE': '1', 07:13:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:13:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:57 INFO - 'NO_EM_RESTART': '1', 07:13:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:57 INFO - 'PWD': '/builds/slave/test', 07:13:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:13:57 INFO - 'SHELL': '/bin/bash', 07:13:57 INFO - 'SHLVL': '1', 07:13:57 INFO - 'TERM': 'linux', 07:13:57 INFO - 'TMOUT': '86400', 07:13:57 INFO - 'USER': 'cltbld', 07:13:57 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312', 07:13:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:57 INFO - '_': '/tools/buildbot/bin/python'} 07:13:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:13:58 INFO - mozversion application_buildid: 20161227061937 07:13:58 INFO - mozversion application_changeset: 9ab4320b60d3b40b7936bc50b6b53c0e6a5d70c7 07:13:58 INFO - mozversion application_display_name: Nightly 07:13:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:13:58 INFO - mozversion application_name: Firefox 07:13:58 INFO - mozversion application_remotingname: firefox 07:13:58 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 07:13:58 INFO - mozversion application_vendor: Mozilla 07:13:58 INFO - mozversion application_version: 53.0a1 07:13:58 INFO - mozversion platform_buildid: 20161227061937 07:13:58 INFO - mozversion platform_changeset: 9ab4320b60d3b40b7936bc50b6b53c0e6a5d70c7 07:13:58 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 07:13:58 INFO - mozversion platform_version: 53.0a1 07:13:58 INFO - using testdate: 1482851638 07:13:58 INFO - actual date: 1482851638 07:13:58 INFO - starting webserver on 'localhost:33147' 07:13:58 INFO - SUITE-START | Running 2 tests 07:13:58 INFO - TEST-START | dromaeo_css 07:13:58 INFO - operating with platform_type : linux_ 07:13:58 INFO - Initialising browser for dromaeo_css test... 07:13:58 INFO - TEST-INFO | started process 3320 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ00P1L/profile http://localhost:33147/getInfo.html) 07:13:59 INFO - PROCESS | 3320 | 1482851639190 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 07:14:02 INFO - PROCESS | 3320 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:14:02 INFO - PROCESS | 3320 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:14:05 INFO - PROCESS | 3320 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:14:05 INFO - PROCESS | 3320 | __metrics Screen width/height:1600/1200 07:14:05 INFO - PROCESS | 3320 | colorDepth:24 07:14:05 INFO - PROCESS | 3320 | Browser inner width/height: 1024/697 07:14:05 INFO - PROCESS | 3320 | __metrics 07:14:06 INFO - PROCESS | 3320 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:14:06 INFO - PROCESS | 3320 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:14:06 INFO - PROCESS | 3320 | console.error: PushService: 07:14:06 INFO - PROCESS | 3320 | stateChangeProcessEnqueue: Error transitioning state 07:14:06 INFO - PROCESS | 3320 | UnknownError 07:14:07 INFO - TEST-INFO | 3320: exit 0 07:14:07 INFO - Browser initialized. 07:14:07 INFO - Running cycle 1/1 for dromaeo_css test... 07:14:07 INFO - TEST-INFO | started process 3403 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ00P1L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:14:19 INFO - PROCESS | 3403 | RSS: Main: 151945216 07:14:19 INFO - PROCESS | 3403 | 07:15:10 INFO - PROCESS | 3403 | 1482851710254 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:17:24 INFO - PROCESS | 3403 | Cycle 1(1): loaded http://localhost:33147/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33147/tests/dromaeo/cssquery-ext.html) 07:17:25 INFO - PROCESS | 3403 | RSS: Main: 232919040 07:17:25 INFO - PROCESS | 3403 | 07:19:08 INFO - PROCESS | 3403 | ************************* 07:19:08 INFO - PROCESS | 3403 | A coding exception was thrown and uncaught in a Task. 07:19:08 INFO - PROCESS | 3403 | 07:19:08 INFO - PROCESS | 3403 | Full message: ReferenceError: fetch is not defined 07:19:08 INFO - PROCESS | 3403 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 07:19:08 INFO - PROCESS | 3403 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 07:19:08 INFO - PROCESS | 3403 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 07:19:08 INFO - PROCESS | 3403 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:19:08 INFO - PROCESS | 3403 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:19:08 INFO - PROCESS | 3403 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:19:08 INFO - PROCESS | 3403 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 07:19:08 INFO - PROCESS | 3403 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:19:08 INFO - PROCESS | 3403 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:19:08 INFO - PROCESS | 3403 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:19:08 INFO - PROCESS | 3403 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 07:19:08 INFO - PROCESS | 3403 | 07:19:08 INFO - PROCESS | 3403 | ************************* 07:20:28 INFO - PROCESS | 3403 | Cycle 1(1): loaded http://localhost:33147/tests/dromaeo/cssquery-ext.html (next: http://localhost:33147/tests/dromaeo/cssquery-jquery.html) 07:20:28 INFO - PROCESS | 3403 | RSS: Main: 236646400 07:20:28 INFO - PROCESS | 3403 | 07:23:31 INFO - PROCESS | 3403 | Cycle 1(1): loaded http://localhost:33147/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33147/tests/dromaeo/cssquery-mootools.html) 07:23:31 INFO - PROCESS | 3403 | RSS: Main: 186449920 07:23:31 INFO - PROCESS | 3403 | 07:26:34 INFO - PROCESS | 3403 | Cycle 1(1): loaded http://localhost:33147/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33147/tests/dromaeo/cssquery-prototype.html) 07:26:34 INFO - PROCESS | 3403 | RSS: Main: 187727872 07:26:34 INFO - PROCESS | 3403 | 07:29:38 INFO - PROCESS | 3403 | Cycle 1(1): loaded http://localhost:33147/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33147/tests/dromaeo/cssquery-yui.html) 07:29:38 INFO - PROCESS | 3403 | RSS: Main: 188026880 07:29:38 INFO - PROCESS | 3403 | 07:32:41 INFO - PROCESS | 3403 | Cycle 1(1): loaded http://localhost:33147/tests/dromaeo/cssquery-yui.html (next: http://localhost:33147/tests/dromaeo/cssquery-dojo.html) 07:32:41 INFO - PROCESS | 3403 | RSS: Main: 255721472 07:32:41 INFO - PROCESS | 3403 | 07:32:41 INFO - PROCESS | 3403 | __start_tp_report 07:32:41 INFO - PROCESS | 3403 | _x_x_mozilla_page_load 07:32:41 INFO - PROCESS | 3403 | _x_x_mozilla_page_load_details 07:32:41 INFO - PROCESS | 3403 | |i|pagename|runs| 07:32:41 INFO - PROCESS | 3403 | |0;dojo.html;2579.8065145114115;2610.9477810443805;2626.448445826377;2640.8283461574997;2644.4711057788445;4576.748278844662;4617.584417402433;4720.362751028611;4767.5470830271115;4771.856844294673;4693.953060469395;4717.504662010491;4748.477667456586;4763.737994410306;4822.879428014293;4261.552536983619;4301.096769678371;4308.095299987007;4387.122575484916;4410.206162890694;4272.87181384559;4296.291111966521;4362.432883725111;4394.384786129945;4464.374987501752;4381.715188512737;4415.425994620711;4425.048614547872;4446.244138496463;4473.709208901428;4256.2551553478015;4283.222462677985;4283.7858107094835;4308.310670292738;4348.086901750637;4323.546027667081;4342.739435633841;4352.804123814436;4390.363397307373;4413.823447062114;4718.127146477913;4722.268048452461;4725.125851717409;4731.219348807443;4748.335233067373;4975.800967961277;5033;5108.974455127738;5111.05445492585;5111.872203194913;4994.850154495371;5000.474950130257;5003.774830132653;5064.797408103672;5103.591712663015;4737.360456338419;4738.6209103272;4743.407074115736;4745.454272758608;4786.593139583105;5064.063148317573;5169.017886601609;5173.120569503193;5179.45615710345;5227.817026404134;4911.557959783565;4922.680025798311;4988.226824842155;4989.276554899519;5044.243363495505;5066.695998240118;5069.239614057921;5073.822416215415;5083.491650834887;5123.231515272664;4863.5622793948005;4911.656184067152;4914.017196560702;4977.452480227172;5035.320231768665;4999.900001999939;5021.698698078127;5024.572911302507;5072.847814565569;5074.086664400444;4280.614744672995;4280.850170244026;4282.421873047098;4321.0709697415205;4328.653707703376;3684.115812205105;3691.5570131583845;3701.9629803702155;3714.257148570297;3768.8304026318883;4339.305711086274;4354.912901742011;4363.890902727458;4375.234333991539;4420.292753159543;5084.745762711849;5088.236764485357;5098.1588037973315;5110.054639891558;5138.229265610188;4257.318828987347;4385.386045953569;4386.100849325944;4389.034412429261;4393.846215382447;4470.150671372428;4528;4533.048059907391;4534.546545345505;4558.33904083906;4737.739424331695;4764.4282686077895;4781.904361912672;4782.378290822171;4784.736839473723;5069.569086627678;5095.261187127771;5108.821191258288;5116.513931176532;5147.2536482209125;4580.65645076614;4716.457607375196;4735.905281894274;4742.715437073786;4779.235322348408;4887.535684110004;4888.242322440027;4901.362822833009;4916.434610019894;4997.950020499894;5024;5033.144365457954;5035.823746168867;5039.420466646383;5069.9746501267255;4889.3888263967;4899.730514821719;4903.705777653352;4907.190313598217;4920.311156438173;4727.7163370197895;4763.571278584944;4905.5094490550655;4928.482509336612;4960.330355401975;4719.480857105647;4763.666543341933;4926.211806111005;4927.704337739747;4940.184869496637;4825.879353016203;4856.247281671346;4884.92672609904;4902.2156454967035;4902.313676085422;4774.188387974123;4777.355057067391;4781.545753153445;4785.353977213032;4820.927686084781 07:32:41 INFO - PROCESS | 3403 | |1;ext.html;514.5009340939289;546.8824202796397;556.1185520949292;558.349522805931;568.343563184522;28310.292242693944;41096.38355424671;43156.92107697305;43182.56817431832;43255.56744432555;39175;39690;39745;39801.20397592046;39837.402438963436;7411.925880741204;7622.237776222389;7845.764627061198;7850.960745196266;7859.135495095535;7416.962915185417;9847.950760246189;10092;10092.990700929922;10399.84400233997;9996.400215987027;10084.949575252114;10085.344452610558;10088.243381746363;10093.747656308615;9847.60609575616;9904.059114384121;9905.306628536042;9906.157976572;9913.454759988197;9929.950350248275;9985.80028399428;10002.749931251705;10003.549840257207;10014.749631259205;36293.81853090744;36530.8173459131;36568.63431365706;36625.81687091548;36640.45039324412;3068.6777888321853;3146.1348129264634;3152.180433087391;3194.025822124251;3208.855601497915;28117.718822811716;28249.15252542427;28316;28730.856345718348;28845.855770721013;3180.236743181643;3226.7418606511424;3249.723773479257;3253.0891350421684;3327.184839714261;233773;234212.8680697892;234451;234523;234860;47725.761371193614;47831.760841196265;48129.51870481321;48268.7586562065;48308.516914830405;56520.71739641358;56728.14907776387;56868.43131568714;56902.14646780302;56925.430745692014;15073.095614263179;15265.847341526443;15306;15312.234388280542;15326.923365383102;10606.310589811637;10763.892361076289;11429.714257143472;11614.825777613343;11701;16433.260503277383;17176.484705458857;17195.742063869053;17279.308827647004;17389.739153912702;6069.848253793691;6122.632642041492;6193.597416167935;6195.349488303754;6278.623282603058;772.5828052851398;857.4683696108328;867.9088695686988;887.2946007923716;950.8954015058476;27377.86311068432;27918.83207045665;27938.580921286197;28038.439231215263;28107;921.760342311004;957.7270477913901;975.2149519636761;995.6564985080136;1025.5436330832856;12891.677708057186;13217.575479049663;13238.933805330911;13305.201687898758;13341.733165336638;356218;356799;358132;358949;359208;43357;43624.78187609105;43633.56366436359;43651;43676.344854827206;1201.5073819733868;1234.8579913309904;1242.0870660064845;1248.687828042971;1249.6688377579949;22293.219737309115;22452.663210051534;23621.52756944886;23653.645195322428;23724;36968.26063478769;37092.629073708915;37147.81426092852;37260.391772576746;37310.440343395414;285141;285773;287570;288359;288569;23311.41721456977;23867.64198537058;24211;24231.273061808173;24346.26961191167;37164.07089822776;37666;37881.43177852279;38251.42622860603;38896.80551597224;31139.377212455172;31831.363372732878;31881.521777173824;31984.520232197003;32551.837240813646;30304.84847575748;31298;32196.517052243766;32206.19484512906;32725.50911736278;27939.860300698365;28338;28404.28989275202;28839.423211536072;28932.855335723187;27150.592741108503;28105.43789124247;28775.85612071926;29233.123006309845;29275.7072429273;22497.43756406103;22713.88643056774;22924.19765308206;23033.424164396023;23264.302070937898 07:32:41 INFO - PROCESS | 3403 | |2;jquery.html;357.4834364343525;372.69066674660036;374.81446683891477;378.41912664060675;403.15941262467754;12784.55254066108;13276.668083297922;13532.864671353283;16154.434594789185;16986.235619397125;16231.675366492664;16668.166591670397;16858.409955651554;16993.15034248286;17030.23363948622;5107.821226257091;5298;5312.946870531303;5318.867028324304;5323.334583177103;3904.1801221743326;5188.195829646411;5224.399194092671;5276.868078298035;5280.867978300554;4014.437978682987;4076.4700588923397;5878.706064696769;5956.791512297071;6002.609830361034;5883.588148829541;5937.732802023919;5948.494377977877;5954.761809527614;5956.19591355168;5362.892742145135;5388.78444862205;5389.8113566025395;5393.703346315951;5395.0558652236095;18518.35185768505;19050.428487145407;19499.220031198736;19618.80381196198;19641.705374419395;1926.6146770645787;1967.645823751724;1974.5359840437486;2064.4116426818437;2457.840240384387;11790.587329443428;12573.874261257362;12837.679058023625;13017.739645207042;13178.275194864278;1709.8204688507776;1732.7054400751902;1753.105915982845;1764.7264673975553;1787.258287810557;43666.78166609147;54377.1843422349;54713.452865471634;63346.36653633497;63494.36505634931;9521.23830093591;9576.23390128788;9763.218942484726;9845.16316113124;9883.505824708736;13059;13527.526536571173;13529.45882164722;13561;13582.524611638546;7387.4090072795125;7482.625868706652;7512.774616761506;7535.8116047098165;7592.696292148363;7085.89371159433;7162.212156662763;7178.600172966221;7271.527350722186;7320.816979575447;6003.879922401527;6021.60859544137;6021.759129634853;6086;6140.17107690456;1741.2947756158776;1764.5921054589137;1799.739037839506;1800.873938824296;1820.9680881332433;792.0556815144114;806.9757907262791;818.9140140285303;850.4642075492524;948.4356807699407;16075.839241607668;16480.505584832474;16817.663646726996;16924.323027079026;17248.2034162578;873.3799002708022;904.3759805734022;942.1661829281136;942.6088173407959;989.1888651306004;9445.52772361393;9939.304248702667;9969.102780749767;10008.599656013823;10075;328989;338028;338095;339135;339340;46237.76881115573;59603.701981489816;63002;63096.36903630905;63220;818.7953011747063;873.1835733589114;891.5185799668236;910.253592054509;920.2959735802117;15216.163111029;15369.538913832155;16033.518994430186;16130.838691612933;16214.918925405298;32458.188545286557;32937.50593741048;33223.16942076467;33609.831950841064;33900.66099339074;42334.78832605817;42904.35643465288;43219.78390108029;43718.56281437272;43802.34296485491;7153.785386438415;7153.928460715326;7462.029936108272;7602.847943040998;7611.733589324569;10389.896101038892;11115.888841111486;11294.887051129384;11439.485223165144;11847;9393.154616084585;9680.661176858775;10057.748556286151;10073.798524029626;10250.077493025663;9660.3237773358;9696.9030309696;9816.705498835046;10209.693709188736;10238.385696858213;5308.787648494172;7089.82275443118;7746.767596972099;9773.755656108655;9925.95037024839;7387.704491820267;7487.288707572772;7514.586697731677;7770.261825126604;7940.523568585903;5844.912326315024;8209.35812837423;9830.705078847646;10062.39625622465;10267.897321026694 07:32:41 INFO - PROCESS | 3403 | |3;mootools.html;1334.2328161307248;1368.842583102943;1481.9777703334453;1671.6573102513985;1735.2885317020025;7211.278872112793;7625.771226863203;7632.427567932406;7633.580153091578;7636.427267954912;6590.868182636356;6841.315868413156;7211.675474603642;7364.631768411571;7388.187299397062;2157.2126173946513;2201.3726088064905;2206.249875042485;2208.558288342338;2209.9668504972437;2183.661532462463;2219.966700499494;2251.7297924249115;2257.142285931344;2268;1878.821511956362;1935.235581945131;2050.712900193974;2110.103206137393;2110.261408507018;2155.924542641008;2163.610550100981;2175.7497887742875;2185.3334732906455;2192.1012384922105;2200.460887082659;2215.966760498594;2220.911163553456;2224.5439684864727;2225.7329120505483;7488.3260506554125;7622.161562228151;7645.197254288275;7645.694172233104;7653.770386888402;4569.588737013652;4702.788374523155;4703.082898834728;4711.45818230901;4721.976390118062;2890.7109289070922;3125.1874512626655;3132.7023932726356;3144.779865409422;3147.118806734118;879.5294517433207;940.7883226274103;943.0239701908561;1677.9244933977877;1709.623882745794;9973.451460169688;10005.549750261256;10055.396676199378;10057.798844023151;10134.189264858793;3906.882793516199;4092.263392589303;4180.456540649696;4183.518895327076;4211.831526738957;8070.071941726659;8092.635831387602;8093.392995525359;8093.595320233965;8100;4615.030843522898;4622.791974361162;4624.722516649012;4631.073785242965;4635.745034023161;4162.209180255742;4311.245532031882;4314.094040251583;4339.067100573392;4342.066455712037;3227.790193637406;3245.8052516848593;3364.327134573095;3364.8485818138242;3379.1889946413175;1021.4331046269243;1022.0954455307107;1028.4189432970347;1035.1253191053547;1053.3100818963592;541.2449632762354;632.9461995730322;741.7515132430697;753.5516367761173;759.9848003039908;4655.557722016403;4743.620510359163;4758.690685105457;4996.575291100225;5053.418856831511;868.201254845531;873.1399571422144;878.6353663229816;900.2842740021698;914.6707185413379;3158.9052328429725;3198.4722520784276;3227.790193637406;3304.619968703582;3421.4867769834227;8637.740867773977;9092.818143637223;9136.177744003071;9215.49314787693;9217.308701847389;9054.728358149267;9168.862467063;9174.816503669888;9175.908240917639;9177.587008584629;7086.39765619928;7221.169565499929;7282.96358518204;7284.854302913912;7296.671649775667;4751.786169622306;4827.468978412302;4941.728204948762;4943.233798761198;4978.27814966835;8069.919300806917;8241.752747417588;8242.793930151794;8243;8365;8523.616437260214;8537.487750734976;8548.273396761097;8611.397202195787;8708.912910871064;6574.901376479261;6687.699053542505;6722.764703235363;6723.7646682365885;6726.39462448382;7113.928860711534;7133.607651579213;7162.104736907887;7201.279872012757;7228.63856807168;6422.710978005907;6489.513286503437;6509.8698026040165;6551.21385433751;6558.213014438107;6239.812805615657;6470.870582588228;6478.060681201294;6513.674316284072;6748.628825414649;5847.210626565359;5852.326982397079;5902.173695682522;5906.40935906406;5907.4683278505145;4118.711690181659;4122.525909520444;4213.620774130343;4264.936025959551;4271.188474189894;4045.170739998352;4142.233686767958;4173.207090652766;4216.072464057902;4345.652347812231 07:32:41 INFO - PROCESS | 3403 | |4;prototype.html;341.62250712962174;345.4093500114805;345.877213589176;347.86433291016493;351.9630438803926;4795.952040479594;4813.56677898989;4840.830570930018;4846.927296090562;4868.975655121724;4760.2383618621025;4771.498992605779;4772.236442169254;4779.259214821708;4787.928181077286;3524.665156810112;3606.711463082947;3657.7988210648405;3766.3973764197735;3852.2873268445287;3804.8478060877533;3805.915314135472;3811.942820857689;3814.2180852925085;3823.9426408603895;3803.6766874815676;3806.3719486284867;3809.9903525565746;3819.427085937104;3836.616338366155;3664.5419322584676;3771.7171212159196;3820.36963900956;3833.6007357314656;3839.558450778168;3314.486254630536;3390.3727810354917;3397.3884700753856;3400.914977125567;3419.8290085495873;4660.464046634646;4668.696534725232;4668.71233095784;4669.299605059235;4679.15775160471;4355.586219309161;4356.629686476654;4358.346248062784;4389;4399.252127138362;4838.516148385169;4951.282064100721;4960.900781984376;4970.589264206857;4994.87512812179;3713.0135377573138;3720.4047352423754;3725.2735716535285;3736.1406876418578;3737.8691745788765;5000.1999680051385;5141.897162056813;5221.530062294374;5229.921551176735;5257.579393648499;3601.171730501947;3739.3269211541638;3787.9242415151543;3798.848046078126;3886.553046399644;3914.236723838881;3934.2524920265632;3943.2902077626304;3944.7041471889725;3962.385830196324;4330.956690433119;4338.327559228324;4353.238183317907;4364.10535840152;4417.801198946056;4878.0729825606195;5006.299118123466;5015.297858299841;5041.949580504148;5061.544460998454;1299.909006369564;1303.270168705509;1305.262526672427;1306.863029164633;1316.1773891317926;877.9385443018928;925.9490728009889;929.990700093004;940.3981451870808;940.8400571902793;4258.190943720683;4268.253055715238;4319.373690814814;4321.351797230378;4323.740575565476;4989.775460104232;5010.017036456026;5010.423801262901;5059.139946209153;5059.974700126476;4304.870853874389;4305.84929527465;4319.956800432018;4320.027993701392;4323.978380108079;2562.372218806367;2696.220494473638;2702.3109107177543;2712.3083613678386;2714.8914043438435;4692.296155576622;4696.506866778939;4728.456227533878;4729.881752956204;4742.135159844715;4931.235658472942;5171.534561889448;5195.350581177353;5201.323827902348;5210.192420174879;4650.883727906829;4688.3436318916065;4694.145812404081;4703.2004559225015;4716.174669432836;5027.949720502748;5032.547070763649;5065.974670126626;5103.209002604603;5124.590032797293;4828.855134345975;4943.5797957174045;4949.183384741477;5032.521910418504;5048.318477005704;4639.768011599339;4746.762661866824;4792;4830.9275360870315;4841.830535931225;3494.7728397654073;3620.746547741738;3624.3838547447526;3630.7640003399692;3638.163222458902;5099.847004589868;5133.8716532087;5152.124138896322;5167.733905193251;5183.9113786105345;4933.827316043921;5004.499550044967;5041.294218809443;5044.9243261350375;5055.873603159804;4918.95081049185;4953.380827396575;4972.477889821516;4985.850424487271;5017.84946451607;4951.603871690184;4965;4972.875678107932;4988.775505102207;5002.399712034579;4884.3650325457465;5023.372078490189;5071.340725705634;5086.440491546001;5092.668976516515;4901.681390709592;4913.855132715136;4924.975375123101;4925.975370123269;4929.507049295042 07:32:41 INFO - PROCESS | 3403 | |5;yui.html;667.2026927821256;675.3921470676393;688.5971706551667;738.4203400330741;768.3238749900089;12601.369931503423;13492.527761528348;13506.72986540271;13517;13541.593752187438;13364.19814811112;13429;13481.59555213345;13487.258200798977;13492.66268343292;681.3186813186813;695.7808290388537;800.8918795962529;818.4639061414783;832.2094010689868;825.2531459029558;833.4249367936135;835.6699103853974;839.9874001889976;840.2060053249681;872.9301655867547;873.3406278259905;873.6636394987944;873.7946582553095;874.6632546469623;714.7569826259097;731.2249016043012;853.3642436384933;867.280157469299;870.3689824876946;639.4564620072947;639.4788247578209;639.5171645407734;640.0207682246171;641.2049059166646;12685.74628507434;12718.745625087446;12720.109592328541;12726.363681815872;12737.681557961032;12902.225866447949;12904.419301131471;13011.21932684042;13065.608031759062;13133.408996595175;11969.880301196963;12011.279323240633;12058.336791476466;12312.876871231263;12380.752384952251;4135.958640413587;4159.08500129974;4171.290880550283;4209.34755112958;4211.452511173558;65911.340886591;66369.66815165941;67251;67514.3248567513;68290.975635366;10211.897881021094;10264.589416423409;10352.792944141074;10375.481225938824;10461.163106951426;20253;20350.491237718878;20374.898125509277;20497.795022049588;20788.272410465863;6597.868042639217;6620.172478440195;6654.567453115532;6866.896996545056;6997.370236678622;3566.32239874423;3584.354816133069;3587.192881601671;3649.799261040615;3717.1078941054493;2953.2469220348676;3016.3364059906785;3017.7736669749856;3019.667836537979;3038.9696103039128;496.4588598427731;526.4893053737868;541.4991133201772;546.0744038854183;553.92522009529;499.9375078115236;523.9633225674166;532.8836088394806;562.7720773086907;563.4872266237704;7564.659590318449;7668.003159589218;7865.213478652089;8136.796580085545;8253.669853205925;465.3113392179593;495.2521692244736;596.4661627843143;598.3776872053016;599.3137857153534;4027.375756757707;4062.654674352654;4074.6536544393466;4095.2014357200655;4254.829921771538;56644.43355566474;64693.353066469674;65007.67496162489;65760;67776;56988.71505642445;57163.42836571581;57491.425085748604;59218.70390648105;61104.38895611076;52514;52545.999999999236;52959.20561191585;52971.73514132405;53257.467425326024;8543.786405339917;8848.247898928663;8910.108989101038;8998;9073.77315567116;35821;36827.26345473022;37201;37362.25275494529;38106.23787524289;45615.54384456113;50078;50816;51066;51506;11644.35956022393;11645.708857278636;11650.30098194111;11755.05959523219;11849.229800062969;12364.505419783109;12866.678333041375;13302.201867887958;13529.323533823466;14740.557783266519;10390.272680912263;10827.729306767395;11691.473883675426;11730;11894.34581098048;11537.653870383901;12359.938200308941;12477.188982716094;12487.687807804878;13020.674483137997;11753.294802311888;12404.875951240372;12447.439865206268;12850.228986260481;12983.221006739626;10135.442550659489;10604.469776511298;10760.3543787373;10862.293950893167;10936.45317734126;9139.314551408805;9591.712248632552;10565.894341056799;10804.027637512661;10844.132469402586 07:32:41 INFO - PROCESS | 3403 | __end_tp_report 07:32:41 INFO - PROCESS | 3403 | __start_cc_report 07:32:41 INFO - PROCESS | 3403 | _x_x_mozilla_cycle_collect,955 07:32:41 INFO - PROCESS | 3403 | __end_cc_report 07:32:41 INFO - PROCESS | 3403 | __startTimestamp1482852761938__endTimestamp 07:32:41 INFO - PROCESS | 3403 | 07:32:41 INFO - PROCESS | 3403 | ------- Summary: start ------- 07:32:41 INFO - PROCESS | 3403 | Number of tests: 6 07:32:41 INFO - PROCESS | 3403 | 07:32:41 INFO - PROCESS | 3403 | [#0] dojo.html Cycles:165 Average:4661.13 Median:4771.86 stddev:488.24 (10.2%) stddev-sans-first:461.63 07:32:41 INFO - PROCESS | 3403 | Values: 2579.8 2610.9 2626.4 2640.8 2644.5 4576.7 4617.6 4720.4 4767.5 4771.9 4694.0 4717.5 4748.5 4763.7 4822.9 4261.6 4301.1 4308.1 4387.1 4410.2 4272.9 4296.3 4362.4 4394.4 4464.4 4381.7 4415.4 4425.0 4446.2 4473.7 4256.3 4283.2 4283.8 4308.3 4348.1 4323.5 4342.7 4352.8 4390.4 4413.8 4718.1 4722.3 4725.1 4731.2 4748.3 4975.8 5033.0 5109.0 5111.1 5111.9 4994.9 5000.5 5003.8 5064.8 5103.6 4737.4 4738.6 4743.4 4745.5 4786.6 5064.1 5169.0 5173.1 5179.5 5227.8 4911.6 4922.7 4988.2 4989.3 5044.2 5066.7 5069.2 5073.8 5083.5 5123.2 4863.6 4911.7 4914.0 4977.5 5035.3 4999.9 5021.7 5024.6 5072.8 5074.1 4280.6 4280.9 4282.4 4321.1 4328.7 3684.1 3691.6 3702.0 3714.3 3768.8 4339.3 4354.9 4363.9 4375.2 4420.3 5084.7 5088.2 5098.2 5110.1 5138.2 4257.3 4385.4 4386.1 4389.0 4393.8 4470.2 4528.0 4533.0 4534.5 4558.3 4737.7 4764.4 4781.9 4782.4 4784.7 5069.6 5095.3 5108.8 5116.5 5147.3 4580.7 4716.5 4735.9 4742.7 4779.2 4887.5 4888.2 4901.4 4916.4 4998.0 5024.0 5033.1 5035.8 5039.4 5070.0 4889.4 4899.7 4903.7 4907.2 4920.3 4727.7 4763.6 4905.5 4928.5 4960.3 4719.5 4763.7 4926.2 4927.7 4940.2 4825.9 4856.2 4884.9 4902.2 4902.3 4774.2 4777.4 4781.5 4785.4 4820.9 07:32:41 INFO - PROCESS | 3403 | 07:32:41 INFO - PROCESS | 3403 | [#1] ext.html Cycles:180 Average:44056.73 Median:17537.77 stddev:78129.03 (445.5%) stddev-sans-first:78279.80 07:32:41 INFO - PROCESS | 3403 | Values: 514.5 546.9 556.1 558.3 568.3 28310.3 41096.4 43156.9 43182.6 43255.6 39175.0 39690.0 39745.0 39801.2 39837.4 7411.9 7622.2 7845.8 7851.0 7859.1 7417.0 9848.0 10092.0 10093.0 10399.8 9996.4 10084.9 10085.3 10088.2 10093.7 9847.6 9904.1 9905.3 9906.2 9913.5 9930.0 9985.8 10002.7 10003.5 10014.7 36293.8 36530.8 36568.6 36625.8 36640.5 3068.7 3146.1 3152.2 3194.0 3208.9 28117.7 28249.2 28316.0 28730.9 28845.9 3180.2 3226.7 3249.7 3253.1 3327.2 233773.0 234212.9 234451.0 234523.0 234860.0 47725.8 47831.8 48129.5 48268.8 48308.5 56520.7 56728.1 56868.4 56902.1 56925.4 15073.1 15265.8 15306.0 15312.2 15326.9 10606.3 10763.9 11429.7 11614.8 11701.0 16433.3 17176.5 17195.7 17279.3 17389.7 6069.8 6122.6 6193.6 6195.3 6278.6 772.6 857.5 867.9 887.3 950.9 27377.9 27918.8 27938.6 28038.4 28107.0 921.8 957.7 975.2 995.7 1025.5 12891.7 13217.6 13238.9 13305.2 13341.7 356218.0 356799.0 358132.0 358949.0 359208.0 43357.0 43624.8 43633.6 43651.0 43676.3 1201.5 1234.9 1242.1 1248.7 1249.7 22293.2 22452.7 23621.5 23653.6 23724.0 36968.3 37092.6 37147.8 37260.4 37310.4 285141.0 285773.0 287570.0 288359.0 288569.0 23311.4 23867.6 24211.0 24231.3 24346.3 37164.1 37666.0 37881.4 38251.4 38896.8 31139.4 31831.4 31881.5 31984.5 32551.8 30304.8 31298.0 32196.5 32206.2 32725.5 27939.9 28338.0 28404.3 28839.4 28932.9 27150.6 28105.4 28775.9 29233.1 29275.7 22497.4 22713.9 22924.2 23033.4 23264.3 07:32:41 INFO - PROCESS | 3403 | 07:32:41 INFO - PROCESS | 3403 | [#2] jquery.html Cycles:180 Average:21553.44 Median:5206.30 stddev:55220.19 (1060.6%) stddev-sans-first:55352.16 07:32:41 INFO - PROCESS | 3403 | Values: 357.5 372.7 374.8 378.4 403.2 12784.6 13276.7 13532.9 16154.4 16986.2 16231.7 16668.2 16858.4 16993.2 17030.2 5107.8 5298.0 5312.9 5318.9 5323.3 3904.2 5188.2 5224.4 5276.9 5280.9 4014.4 4076.5 5878.7 5956.8 6002.6 5883.6 5937.7 5948.5 5954.8 5956.2 5362.9 5388.8 5389.8 5393.7 5395.1 18518.4 19050.4 19499.2 19618.8 19641.7 1926.6 1967.6 1974.5 2064.4 2457.8 11790.6 12573.9 12837.7 13017.7 13178.3 1709.8 1732.7 1753.1 1764.7 1787.3 43666.8 54377.2 54713.5 63346.4 63494.4 9521.2 9576.2 9763.2 9845.2 9883.5 13059.0 13527.5 13529.5 13561.0 13582.5 7387.4 7482.6 7512.8 7535.8 7592.7 7085.9 7162.2 7178.6 7271.5 7320.8 6003.9 6021.6 6021.8 6086.0 6140.2 1741.3 1764.6 1799.7 1800.9 1821.0 792.1 807.0 818.9 850.5 948.4 16075.8 16480.5 16817.7 16924.3 17248.2 873.4 904.4 942.2 942.6 989.2 9445.5 9939.3 9969.1 10008.6 10075.0 328989.0 338028.0 338095.0 339135.0 339340.0 46237.8 59603.7 63002.0 63096.4 63220.0 818.8 873.2 891.5 910.3 920.3 15216.2 15369.5 16033.5 16130.8 16214.9 32458.2 32937.5 33223.2 33609.8 33900.7 42334.8 42904.4 43219.8 43718.6 43802.3 7153.8 7153.9 7462.0 7602.8 7611.7 10389.9 11115.9 11294.9 11439.5 11847.0 9393.2 9680.7 10057.7 10073.8 10250.1 9660.3 9696.9 9816.7 10209.7 10238.4 5308.8 7089.8 7746.8 9773.8 9926.0 7387.7 7487.3 7514.6 7770.3 7940.5 5844.9 8209.4 9830.7 10062.4 10267.9 07:32:41 INFO - PROCESS | 3403 | 07:32:41 INFO - PROCESS | 3403 | [#3] mootools.html Cycles:180 Average:4918.40 Median:4884.60 stddev:2683.69 (54.9%) stddev-sans-first:2677.70 07:32:41 INFO - PROCESS | 3403 | Values: 1334.2 1368.8 1482.0 1671.7 1735.3 7211.3 7625.8 7632.4 7633.6 7636.4 6590.9 6841.3 7211.7 7364.6 7388.2 2157.2 2201.4 2206.2 2208.6 2210.0 2183.7 2220.0 2251.7 2257.1 2268.0 1878.8 1935.2 2050.7 2110.1 2110.3 2155.9 2163.6 2175.7 2185.3 2192.1 2200.5 2216.0 2220.9 2224.5 2225.7 7488.3 7622.2 7645.2 7645.7 7653.8 4569.6 4702.8 4703.1 4711.5 4722.0 2890.7 3125.2 3132.7 3144.8 3147.1 879.5 940.8 943.0 1677.9 1709.6 9973.5 10005.5 10055.4 10057.8 10134.2 3906.9 4092.3 4180.5 4183.5 4211.8 8070.1 8092.6 8093.4 8093.6 8100.0 4615.0 4622.8 4624.7 4631.1 4635.7 4162.2 4311.2 4314.1 4339.1 4342.1 3227.8 3245.8 3364.3 3364.8 3379.2 1021.4 1022.1 1028.4 1035.1 1053.3 541.2 632.9 741.8 753.6 760.0 4655.6 4743.6 4758.7 4996.6 5053.4 868.2 873.1 878.6 900.3 914.7 3158.9 3198.5 3227.8 3304.6 3421.5 8637.7 9092.8 9136.2 9215.5 9217.3 9054.7 9168.9 9174.8 9175.9 9177.6 7086.4 7221.2 7283.0 7284.9 7296.7 4751.8 4827.5 4941.7 4943.2 4978.3 8069.9 8241.8 8242.8 8243.0 8365.0 8523.6 8537.5 8548.3 8611.4 8708.9 6574.9 6687.7 6722.8 6723.8 6726.4 7113.9 7133.6 7162.1 7201.3 7228.6 6422.7 6489.5 6509.9 6551.2 6558.2 6239.8 6470.9 6478.1 6513.7 6748.6 5847.2 5852.3 5902.2 5906.4 5907.5 4118.7 4122.5 4213.6 4264.9 4271.2 4045.2 4142.2 4173.2 4216.1 4345.7 07:32:41 INFO - PROCESS | 3403 | 07:32:41 INFO - PROCESS | 3403 | [#4] prototype.html Cycles:180 Average:4164.96 Median:4699.85 stddev:1175.17 (25.0%) stddev-sans-first:1142.89 07:32:41 INFO - PROCESS | 3403 | Values: 341.6 345.4 345.9 347.9 352.0 4796.0 4813.6 4840.8 4846.9 4869.0 4760.2 4771.5 4772.2 4779.3 4787.9 3524.7 3606.7 3657.8 3766.4 3852.3 3804.8 3805.9 3811.9 3814.2 3823.9 3803.7 3806.4 3810.0 3819.4 3836.6 3664.5 3771.7 3820.4 3833.6 3839.6 3314.5 3390.4 3397.4 3400.9 3419.8 4660.5 4668.7 4668.7 4669.3 4679.2 4355.6 4356.6 4358.3 4389.0 4399.3 4838.5 4951.3 4960.9 4970.6 4994.9 3713.0 3720.4 3725.3 3736.1 3737.9 5000.2 5141.9 5221.5 5229.9 5257.6 3601.2 3739.3 3787.9 3798.8 3886.6 3914.2 3934.3 3943.3 3944.7 3962.4 4331.0 4338.3 4353.2 4364.1 4417.8 4878.1 5006.3 5015.3 5041.9 5061.5 1299.9 1303.3 1305.3 1306.9 1316.2 877.9 925.9 930.0 940.4 940.8 4258.2 4268.3 4319.4 4321.4 4323.7 4989.8 5010.0 5010.4 5059.1 5060.0 4304.9 4305.8 4320.0 4320.0 4324.0 2562.4 2696.2 2702.3 2712.3 2714.9 4692.3 4696.5 4728.5 4729.9 4742.1 4931.2 5171.5 5195.4 5201.3 5210.2 4650.9 4688.3 4694.1 4703.2 4716.2 5027.9 5032.5 5066.0 5103.2 5124.6 4828.9 4943.6 4949.2 5032.5 5048.3 4639.8 4746.8 4792.0 4830.9 4841.8 3494.8 3620.7 3624.4 3630.8 3638.2 5099.8 5133.9 5152.1 5167.7 5183.9 4933.8 5004.5 5041.3 5044.9 5055.9 4919.0 4953.4 4972.5 4985.9 5017.8 4951.6 4965.0 4972.9 4988.8 5002.4 4884.4 5023.4 5071.3 5086.4 5092.7 4901.7 4913.9 4925.0 4926.0 4929.5 07:32:41 INFO - PROCESS | 3403 | 07:32:41 INFO - PROCESS | 3403 | [#5] yui.html Cycles:180 Average:15315.91 Median:4210.40 stddev:18915.86 (449.3%) stddev-sans-first:18936.94 07:32:41 INFO - PROCESS | 3403 | Values: 667.2 675.4 688.6 738.4 768.3 12601.4 13492.5 13506.7 13517.0 13541.6 13364.2 13429.0 13481.6 13487.3 13492.7 681.3 695.8 800.9 818.5 832.2 825.3 833.4 835.7 840.0 840.2 872.9 873.3 873.7 873.8 874.7 714.8 731.2 853.4 867.3 870.4 639.5 639.5 639.5 640.0 641.2 12685.7 12718.7 12720.1 12726.4 12737.7 12902.2 12904.4 13011.2 13065.6 13133.4 11969.9 12011.3 12058.3 12312.9 12380.8 4136.0 4159.1 4171.3 4209.3 4211.5 65911.3 66369.7 67251.0 67514.3 68291.0 10211.9 10264.6 10352.8 10375.5 10461.2 20253.0 20350.5 20374.9 20497.8 20788.3 6597.9 6620.2 6654.6 6866.9 6997.4 3566.3 3584.4 3587.2 3649.8 3717.1 2953.2 3016.3 3017.8 3019.7 3039.0 496.5 526.5 541.5 546.1 553.9 499.9 524.0 532.9 562.8 563.5 7564.7 7668.0 7865.2 8136.8 8253.7 465.3 495.3 596.5 598.4 599.3 4027.4 4062.7 4074.7 4095.2 4254.8 56644.4 64693.4 65007.7 65760.0 67776.0 56988.7 57163.4 57491.4 59218.7 61104.4 52514.0 52546.0 52959.2 52971.7 53257.5 8543.8 8848.2 8910.1 8998.0 9073.8 35821.0 36827.3 37201.0 37362.3 38106.2 45615.5 50078.0 50816.0 51066.0 51506.0 11644.4 11645.7 11650.3 11755.1 11849.2 12364.5 12866.7 13302.2 13529.3 14740.6 10390.3 10827.7 11691.5 11730.0 11894.3 11537.7 12359.9 12477.2 12487.7 13020.7 11753.3 12404.9 12447.4 12850.2 12983.2 10135.4 10604.5 10760.4 10862.3 10936.5 9139.3 9591.7 10565.9 10804.0 10844.1 07:32:41 INFO - PROCESS | 3403 | -------- Summary: end -------- 07:32:41 INFO - PROCESS | 3403 | 07:32:42 INFO - TEST-INFO | 3403: exit 0 07:32:42 INFO - TEST-OK | dromaeo_css | took 1124737ms 07:32:42 INFO - TEST-START | kraken 07:32:42 INFO - operating with platform_type : linux_ 07:32:42 INFO - Initialising browser for kraken test... 07:32:42 INFO - TEST-INFO | started process 3713 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9W1PuX/profile http://localhost:33147/getInfo.html) 07:32:43 INFO - PROCESS | 3713 | 1482852763322 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 07:32:45 INFO - PROCESS | 3713 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:32:45 INFO - PROCESS | 3713 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:32:46 INFO - PROCESS | 3713 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 07:32:47 INFO - PROCESS | 3713 | __metrics Screen width/height:1600/1200 07:32:47 INFO - PROCESS | 3713 | colorDepth:24 07:32:47 INFO - PROCESS | 3713 | Browser inner width/height: 1024/697 07:32:47 INFO - PROCESS | 3713 | __metrics 07:32:47 INFO - PROCESS | 3713 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:32:47 INFO - PROCESS | 3713 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:32:48 INFO - PROCESS | 3713 | console.error: PushService: 07:32:48 INFO - PROCESS | 3713 | stateChangeProcessEnqueue: Error transitioning state 07:32:48 INFO - PROCESS | 3713 | UnknownError 07:32:49 INFO - TEST-INFO | 3713: exit 0 07:32:49 INFO - Browser initialized. 07:32:49 INFO - Running cycle 1/1 for kraken test... 07:32:49 INFO - TEST-INFO | started process 3797 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9W1PuX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:33:01 INFO - PROCESS | 3797 | RSS: Main: 151699456 07:33:01 INFO - PROCESS | 3797 | 07:33:03 INFO - PROCESS | 3797 | Cycle 1(1): loaded http://localhost:33147/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33147/tests/kraken/driver.html?testName=audio-beat-detection) 07:33:04 INFO - PROCESS | 3797 | RSS: Main: 206041088 07:33:04 INFO - PROCESS | 3797 | 07:33:07 INFO - PROCESS | 3797 | Cycle 1(1): loaded http://localhost:33147/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33147/tests/kraken/driver.html?testName=audio-dft) 07:33:08 INFO - PROCESS | 3797 | RSS: Main: 185004032 07:33:08 INFO - PROCESS | 3797 | 07:33:11 INFO - PROCESS | 3797 | Cycle 1(1): loaded http://localhost:33147/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33147/tests/kraken/driver.html?testName=audio-fft) 07:33:11 INFO - PROCESS | 3797 | RSS: Main: 267128832 07:33:11 INFO - PROCESS | 3797 | 07:33:13 INFO - PROCESS | 3797 | Cycle 1(1): loaded http://localhost:33147/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33147/tests/kraken/driver.html?testName=audio-oscillator) 07:33:14 INFO - PROCESS | 3797 | RSS: Main: 178860032 07:33:14 INFO - PROCESS | 3797 | 07:33:16 INFO - PROCESS | 3797 | Cycle 1(1): loaded http://localhost:33147/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33147/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:33:17 INFO - PROCESS | 3797 | RSS: Main: 181923840 07:33:17 INFO - PROCESS | 3797 | 07:33:20 INFO - PROCESS | 3797 | Cycle 1(1): loaded http://localhost:33147/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33147/tests/kraken/driver.html?testName=imaging-darkroom) 07:33:21 INFO - PROCESS | 3797 | RSS: Main: 221601792 07:33:21 INFO - PROCESS | 3797 | 07:33:24 INFO - PROCESS | 3797 | Cycle 1(1): loaded http://localhost:33147/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33147/tests/kraken/driver.html?testName=imaging-desaturate) 07:33:25 INFO - PROCESS | 3797 | RSS: Main: 221302784 07:33:25 INFO - PROCESS | 3797 | 07:33:27 INFO - PROCESS | 3797 | Cycle 1(1): loaded http://localhost:33147/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33147/tests/kraken/driver.html?testName=json-parse-financial) 07:33:28 INFO - PROCESS | 3797 | RSS: Main: 219676672 07:33:28 INFO - PROCESS | 3797 | 07:33:30 INFO - PROCESS | 3797 | Cycle 1(1): loaded http://localhost:33147/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33147/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:33:30 INFO - PROCESS | 3797 | RSS: Main: 181817344 07:33:30 INFO - PROCESS | 3797 | 07:33:32 INFO - PROCESS | 3797 | Cycle 1(1): loaded http://localhost:33147/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33147/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:33:33 INFO - PROCESS | 3797 | RSS: Main: 190189568 07:33:33 INFO - PROCESS | 3797 | 07:33:35 INFO - PROCESS | 3797 | Cycle 1(1): loaded http://localhost:33147/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33147/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:33:36 INFO - PROCESS | 3797 | RSS: Main: 197545984 07:33:36 INFO - PROCESS | 3797 | 07:33:39 INFO - PROCESS | 3797 | Cycle 1(1): loaded http://localhost:33147/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33147/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:33:39 INFO - PROCESS | 3797 | RSS: Main: 207777792 07:33:39 INFO - PROCESS | 3797 | 07:33:42 INFO - PROCESS | 3797 | Cycle 1(1): loaded http://localhost:33147/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33147/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:33:43 INFO - PROCESS | 3797 | RSS: Main: 203767808 07:33:43 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | Cycle 1(1): loaded http://localhost:33147/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33147/tests/kraken/driver.html?testName=ai-astar) 07:33:45 INFO - PROCESS | 3797 | RSS: Main: 254906368 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | __start_tp_report 07:33:45 INFO - PROCESS | 3797 | _x_x_mozilla_page_load 07:33:45 INFO - PROCESS | 3797 | _x_x_mozilla_page_load_details 07:33:45 INFO - PROCESS | 3797 | |i|pagename|runs| 07:33:45 INFO - PROCESS | 3797 | |0;ai-astar;106;115;114;115;116;115;115;114;114;114 07:33:45 INFO - PROCESS | 3797 | |1;audio-beat-detection;155;125;131;166;125;128;128;125;148;128 07:33:45 INFO - PROCESS | 3797 | |2;audio-dft;160;161;160;160;162;162;162;162;160;160 07:33:45 INFO - PROCESS | 3797 | |3;audio-fft;75;76;76;114;78;77;77;76;93;77 07:33:45 INFO - PROCESS | 3797 | |4;audio-oscillator;84;84;105;85;85;85;96;84;83;113 07:33:45 INFO - PROCESS | 3797 | |5;imaging-gaussian-blur;118;117;117;117;117;117;117;117;117;117 07:33:45 INFO - PROCESS | 3797 | |6;imaging-darkroom;148;150;149;148;149;148;149;148;149;149 07:33:45 INFO - PROCESS | 3797 | |7;imaging-desaturate;95;95;95;96;95;95;96;95;95;95 07:33:45 INFO - PROCESS | 3797 | |8;json-parse-financial;60;59;58;58;60;61;69;60;59;60 07:33:45 INFO - PROCESS | 3797 | |9;json-stringify-tinderbox;68;67;67;68;68;71;68;68;67;68 07:33:45 INFO - PROCESS | 3797 | |10;stanford-crypto-aes;86;94;84;85;85;85;84;85;91;84 07:33:45 INFO - PROCESS | 3797 | |11;stanford-crypto-ccm;135;141;134;134;143;141;133;132;177;144 07:33:45 INFO - PROCESS | 3797 | |12;stanford-crypto-pbkdf2;177;176;181;174;181;177;181;178;181;179 07:33:45 INFO - PROCESS | 3797 | |13;stanford-crypto-sha256-iterative;69;68;67;66;67;68;67;66;67;65 07:33:45 INFO - PROCESS | 3797 | __end_tp_report 07:33:45 INFO - PROCESS | 3797 | __start_cc_report 07:33:45 INFO - PROCESS | 3797 | _x_x_mozilla_cycle_collect,1853 07:33:45 INFO - PROCESS | 3797 | __end_cc_report 07:33:45 INFO - PROCESS | 3797 | __startTimestamp1482852825468__endTimestamp 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | ------- Summary: start ------- 07:33:45 INFO - PROCESS | 3797 | Number of tests: 14 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.00 stddev:2.82 (2.5%) stddev-sans-first:0.71 07:33:45 INFO - PROCESS | 3797 | Values: 106.0 115.0 114.0 115.0 116.0 115.0 115.0 114.0 114.0 114.0 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:129.50 stddev:14.85 (11.5%) stddev-sans-first:14.05 07:33:45 INFO - PROCESS | 3797 | Values: 155.0 125.0 131.0 166.0 125.0 128.0 128.0 125.0 148.0 128.0 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.50 stddev:0.99 (0.6%) stddev-sans-first:1.00 07:33:45 INFO - PROCESS | 3797 | Values: 160.0 161.0 160.0 160.0 162.0 162.0 162.0 162.0 160.0 160.0 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | [#3] audio-fft Cycles:10 Average:81.90 Median:77.00 stddev:12.44 (16.2%) stddev-sans-first:12.94 07:33:45 INFO - PROCESS | 3797 | Values: 75.0 76.0 76.0 114.0 78.0 77.0 77.0 76.0 93.0 77.0 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:84.50 stddev:10.65 (12.6%) stddev-sans-first:11.04 07:33:45 INFO - PROCESS | 3797 | Values: 84.0 84.0 105.0 85.0 85.0 85.0 96.0 84.0 83.0 113.0 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 07:33:45 INFO - PROCESS | 3797 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 07:33:45 INFO - PROCESS | 3797 | Values: 148.0 150.0 149.0 148.0 149.0 148.0 149.0 148.0 149.0 149.0 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:33:45 INFO - PROCESS | 3797 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 95.0 95.0 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:60.00 stddev:3.17 (5.3%) stddev-sans-first:3.36 07:33:45 INFO - PROCESS | 3797 | Values: 60.0 59.0 58.0 58.0 60.0 61.0 69.0 60.0 59.0 60.0 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.00 stddev:1.15 (1.7%) stddev-sans-first:1.22 07:33:45 INFO - PROCESS | 3797 | Values: 68.0 67.0 67.0 68.0 68.0 71.0 68.0 68.0 67.0 68.0 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:85.00 stddev:3.40 (4.0%) stddev-sans-first:3.61 07:33:45 INFO - PROCESS | 3797 | Values: 86.0 94.0 84.0 85.0 85.0 85.0 84.0 85.0 91.0 84.0 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | [#11] stanford-crypto-ccm Cycles:10 Average:141.40 Median:141.00 stddev:13.28 (9.4%) stddev-sans-first:13.88 07:33:45 INFO - PROCESS | 3797 | Values: 135.0 141.0 134.0 134.0 143.0 141.0 133.0 132.0 177.0 144.0 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.50 Median:180.00 stddev:2.51 (1.4%) stddev-sans-first:2.60 07:33:45 INFO - PROCESS | 3797 | Values: 177.0 176.0 181.0 174.0 181.0 177.0 181.0 178.0 181.0 179.0 07:33:45 INFO - PROCESS | 3797 | 07:33:45 INFO - PROCESS | 3797 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.15 (1.7%) stddev-sans-first:0.97 07:33:45 INFO - PROCESS | 3797 | Values: 69.0 68.0 67.0 66.0 67.0 68.0 67.0 66.0 67.0 65.0 07:33:45 INFO - PROCESS | 3797 | -------- Summary: end -------- 07:33:45 INFO - PROCESS | 3797 | 07:33:46 INFO - TEST-INFO | 3797: exit 0 07:33:46 INFO - TEST-OK | kraken | took 63859ms 07:33:46 INFO - SUITE-END | took 1188s 07:33:47 INFO - Completed test suite (00:19:49) 07:33:47 INFO - javascript benchmark 07:33:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2579.8065145114115, 2610.9477810443805, 2626.448445826377, 2640.8283461574997, 2644.4711057788445, 4576.748278844662, 4617.584417402433, 4720.362751028611, 4767.5470830271115, 4771.856844294673, 4693.953060469395, 4717.504662010491, 4748.477667456586, 4763.737994410306, 4822.879428014293, 4261.552536983619, 4301.096769678371, 4308.095299987007, 4387.122575484916, 4410.206162890694, 4272.87181384559, 4296.291111966521, 4362.432883725111, 4394.384786129945, 4464.374987501752, 4381.715188512737, 4415.425994620711, 4425.048614547872, 4446.244138496463, 4473.709208901428, 4256.2551553478015, 4283.222462677985, 4283.7858107094835, 4308.310670292738, 4348.086901750637, 4323.546027667081, 4342.739435633841, 4352.804123814436, 4390.363397307373, 4413.823447062114, 4718.127146477913, 4722.268048452461, 4725.125851717409, 4731.219348807443, 4748.335233067373, 4975.800967961277, 5033.0, 5108.974455127738, 5111.05445492585, 5111.872203194913, 4994.850154495371, 5000.474950130257, 5003.774830132653, 5064.797408103672, 5103.591712663015, 4737.360456338419, 4738.6209103272, 4743.407074115736, 4745.454272758608, 4786.593139583105, 5064.063148317573, 5169.017886601609, 5173.120569503193, 5179.45615710345, 5227.817026404134, 4911.557959783565, 4922.680025798311, 4988.226824842155, 4989.276554899519, 5044.243363495505, 5066.695998240118, 5069.239614057921, 5073.822416215415, 5083.491650834887, 5123.231515272664, 4863.5622793948005, 4911.656184067152, 4914.017196560702, 4977.452480227172, 5035.320231768665, 4999.900001999939, 5021.698698078127, 5024.572911302507, 5072.847814565569, 5074.086664400444, 4280.614744672995, 4280.850170244026, 4282.421873047098, 4321.0709697415205, 4328.653707703376, 3684.115812205105, 3691.5570131583845, 3701.9629803702155, 3714.257148570297, 3768.8304026318883, 4339.305711086274, 4354.912901742011, 4363.890902727458, 4375.234333991539, 4420.292753159543, 5084.745762711849, 5088.236764485357, 5098.1588037973315, 5110.054639891558, 5138.229265610188, 4257.318828987347, 4385.386045953569, 4386.100849325944, 4389.034412429261, 4393.846215382447, 4470.150671372428, 4528.0, 4533.048059907391, 4534.546545345505, 4558.33904083906, 4737.739424331695, 4764.4282686077895, 4781.904361912672, 4782.378290822171, 4784.736839473723, 5069.569086627678, 5095.261187127771, 5108.821191258288, 5116.513931176532, 5147.2536482209125, 4580.65645076614, 4716.457607375196, 4735.905281894274, 4742.715437073786, 4779.235322348408, 4887.535684110004, 4888.242322440027, 4901.362822833009, 4916.434610019894, 4997.950020499894, 5024.0, 5033.144365457954, 5035.823746168867, 5039.420466646383, 5069.9746501267255, 4889.3888263967, 4899.730514821719, 4903.705777653352, 4907.190313598217, 4920.311156438173, 4727.7163370197895, 4763.571278584944, 4905.5094490550655, 4928.482509336612, 4960.330355401975, 4719.480857105647, 4763.666543341933, 4926.211806111005, 4927.704337739747, 4940.184869496637, 4825.879353016203, 4856.247281671346, 4884.92672609904, 4902.2156454967035, 4902.313676085422, 4774.188387974123, 4777.355057067391, 4781.545753153445, 4785.353977213032, 4820.927686084781], "value": 4629.803560553824, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.5009340939289, 546.8824202796397, 556.1185520949292, 558.349522805931, 568.343563184522, 28310.292242693944, 41096.38355424671, 43156.92107697305, 43182.56817431832, 43255.56744432555, 39175.0, 39690.0, 39745.0, 39801.20397592046, 39837.402438963436, 7411.925880741204, 7622.237776222389, 7845.764627061198, 7850.960745196266, 7859.135495095535, 7416.962915185417, 9847.950760246189, 10092.0, 10092.990700929922, 10399.84400233997, 9996.400215987027, 10084.949575252114, 10085.344452610558, 10088.243381746363, 10093.747656308615, 9847.60609575616, 9904.059114384121, 9905.306628536042, 9906.157976572, 9913.454759988197, 9929.950350248275, 9985.80028399428, 10002.749931251705, 10003.549840257207, 10014.749631259205, 36293.81853090744, 36530.8173459131, 36568.63431365706, 36625.81687091548, 36640.45039324412, 3068.6777888321853, 3146.1348129264634, 3152.180433087391, 3194.025822124251, 3208.855601497915, 28117.718822811716, 28249.15252542427, 28316.0, 28730.856345718348, 28845.855770721013, 3180.236743181643, 3226.7418606511424, 3249.723773479257, 3253.0891350421684, 3327.184839714261, 233773.0, 234212.8680697892, 234451.0, 234523.0, 234860.0, 47725.761371193614, 47831.760841196265, 48129.51870481321, 48268.7586562065, 48308.516914830405, 56520.71739641358, 56728.14907776387, 56868.43131568714, 56902.14646780302, 56925.430745692014, 15073.095614263179, 15265.847341526443, 15306.0, 15312.234388280542, 15326.923365383102, 10606.310589811637, 10763.892361076289, 11429.714257143472, 11614.825777613343, 11701.0, 16433.260503277383, 17176.484705458857, 17195.742063869053, 17279.308827647004, 17389.739153912702, 6069.848253793691, 6122.632642041492, 6193.597416167935, 6195.349488303754, 6278.623282603058, 772.5828052851398, 857.4683696108328, 867.9088695686988, 887.2946007923716, 950.8954015058476, 27377.86311068432, 27918.83207045665, 27938.580921286197, 28038.439231215263, 28107.0, 921.760342311004, 957.7270477913901, 975.2149519636761, 995.6564985080136, 1025.5436330832856, 12891.677708057186, 13217.575479049663, 13238.933805330911, 13305.201687898758, 13341.733165336638, 356218.0, 356799.0, 358132.0, 358949.0, 359208.0, 43357.0, 43624.78187609105, 43633.56366436359, 43651.0, 43676.344854827206, 1201.5073819733868, 1234.8579913309904, 1242.0870660064845, 1248.687828042971, 1249.6688377579949, 22293.219737309115, 22452.663210051534, 23621.52756944886, 23653.645195322428, 23724.0, 36968.26063478769, 37092.629073708915, 37147.81426092852, 37260.391772576746, 37310.440343395414, 285141.0, 285773.0, 287570.0, 288359.0, 288569.0, 23311.41721456977, 23867.64198537058, 24211.0, 24231.273061808173, 24346.26961191167, 37164.07089822776, 37666.0, 37881.43177852279, 38251.42622860603, 38896.80551597224, 31139.377212455172, 31831.363372732878, 31881.521777173824, 31984.520232197003, 32551.837240813646, 30304.84847575748, 31298.0, 32196.517052243766, 32206.19484512906, 32725.50911736278, 27939.860300698365, 28338.0, 28404.28989275202, 28839.423211536072, 28932.855335723187, 27150.592741108503, 28105.43789124247, 28775.85612071926, 29233.123006309845, 29275.7072429273, 22497.43756406103, 22713.88643056774, 22924.19765308206, 23033.424164396023, 23264.302070937898], "value": 17004.548553645065, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.4834364343525, 372.69066674660036, 374.81446683891477, 378.41912664060675, 403.15941262467754, 12784.55254066108, 13276.668083297922, 13532.864671353283, 16154.434594789185, 16986.235619397125, 16231.675366492664, 16668.166591670397, 16858.409955651554, 16993.15034248286, 17030.23363948622, 5107.821226257091, 5298.0, 5312.946870531303, 5318.867028324304, 5323.334583177103, 3904.1801221743326, 5188.195829646411, 5224.399194092671, 5276.868078298035, 5280.867978300554, 4014.437978682987, 4076.4700588923397, 5878.706064696769, 5956.791512297071, 6002.609830361034, 5883.588148829541, 5937.732802023919, 5948.494377977877, 5954.761809527614, 5956.19591355168, 5362.892742145135, 5388.78444862205, 5389.8113566025395, 5393.703346315951, 5395.0558652236095, 18518.35185768505, 19050.428487145407, 19499.220031198736, 19618.80381196198, 19641.705374419395, 1926.6146770645787, 1967.645823751724, 1974.5359840437486, 2064.4116426818437, 2457.840240384387, 11790.587329443428, 12573.874261257362, 12837.679058023625, 13017.739645207042, 13178.275194864278, 1709.8204688507776, 1732.7054400751902, 1753.105915982845, 1764.7264673975553, 1787.258287810557, 43666.78166609147, 54377.1843422349, 54713.452865471634, 63346.36653633497, 63494.36505634931, 9521.23830093591, 9576.23390128788, 9763.218942484726, 9845.16316113124, 9883.505824708736, 13059.0, 13527.526536571173, 13529.45882164722, 13561.0, 13582.524611638546, 7387.4090072795125, 7482.625868706652, 7512.774616761506, 7535.8116047098165, 7592.696292148363, 7085.89371159433, 7162.212156662763, 7178.600172966221, 7271.527350722186, 7320.816979575447, 6003.879922401527, 6021.60859544137, 6021.759129634853, 6086.0, 6140.17107690456, 1741.2947756158776, 1764.5921054589137, 1799.739037839506, 1800.873938824296, 1820.9680881332433, 792.0556815144114, 806.9757907262791, 818.9140140285303, 850.4642075492524, 948.4356807699407, 16075.839241607668, 16480.505584832474, 16817.663646726996, 16924.323027079026, 17248.2034162578, 873.3799002708022, 904.3759805734022, 942.1661829281136, 942.6088173407959, 989.1888651306004, 9445.52772361393, 9939.304248702667, 9969.102780749767, 10008.599656013823, 10075.0, 328989.0, 338028.0, 338095.0, 339135.0, 339340.0, 46237.76881115573, 59603.701981489816, 63002.0, 63096.36903630905, 63220.0, 818.7953011747063, 873.1835733589114, 891.5185799668236, 910.253592054509, 920.2959735802117, 15216.163111029, 15369.538913832155, 16033.518994430186, 16130.838691612933, 16214.918925405298, 32458.188545286557, 32937.50593741048, 33223.16942076467, 33609.831950841064, 33900.66099339074, 42334.78832605817, 42904.35643465288, 43219.78390108029, 43718.56281437272, 43802.34296485491, 7153.785386438415, 7153.928460715326, 7462.029936108272, 7602.847943040998, 7611.733589324569, 10389.896101038892, 11115.888841111486, 11294.887051129384, 11439.485223165144, 11847.0, 9393.154616084585, 9680.661176858775, 10057.748556286151, 10073.798524029626, 10250.077493025663, 9660.3237773358, 9696.9030309696, 9816.705498835046, 10209.693709188736, 10238.385696858213, 5308.787648494172, 7089.82275443118, 7746.767596972099, 9773.755656108655, 9925.95037024839, 7387.704491820267, 7487.288707572772, 7514.586697731677, 7770.261825126604, 7940.523568585903, 5844.912326315024, 8209.35812837423, 9830.705078847646, 10062.39625622465, 10267.897321026694], "value": 8013.11599557328, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1334.2328161307248, 1368.842583102943, 1481.9777703334453, 1671.6573102513985, 1735.2885317020025, 7211.278872112793, 7625.771226863203, 7632.427567932406, 7633.580153091578, 7636.427267954912, 6590.868182636356, 6841.315868413156, 7211.675474603642, 7364.631768411571, 7388.187299397062, 2157.2126173946513, 2201.3726088064905, 2206.249875042485, 2208.558288342338, 2209.9668504972437, 2183.661532462463, 2219.966700499494, 2251.7297924249115, 2257.142285931344, 2268.0, 1878.821511956362, 1935.235581945131, 2050.712900193974, 2110.103206137393, 2110.261408507018, 2155.924542641008, 2163.610550100981, 2175.7497887742875, 2185.3334732906455, 2192.1012384922105, 2200.460887082659, 2215.966760498594, 2220.911163553456, 2224.5439684864727, 2225.7329120505483, 7488.3260506554125, 7622.161562228151, 7645.197254288275, 7645.694172233104, 7653.770386888402, 4569.588737013652, 4702.788374523155, 4703.082898834728, 4711.45818230901, 4721.976390118062, 2890.7109289070922, 3125.1874512626655, 3132.7023932726356, 3144.779865409422, 3147.118806734118, 879.5294517433207, 940.7883226274103, 943.0239701908561, 1677.9244933977877, 1709.623882745794, 9973.451460169688, 10005.549750261256, 10055.396676199378, 10057.798844023151, 10134.189264858793, 3906.882793516199, 4092.263392589303, 4180.456540649696, 4183.518895327076, 4211.831526738957, 8070.071941726659, 8092.635831387602, 8093.392995525359, 8093.595320233965, 8100.0, 4615.030843522898, 4622.791974361162, 4624.722516649012, 4631.073785242965, 4635.745034023161, 4162.209180255742, 4311.245532031882, 4314.094040251583, 4339.067100573392, 4342.066455712037, 3227.790193637406, 3245.8052516848593, 3364.327134573095, 3364.8485818138242, 3379.1889946413175, 1021.4331046269243, 1022.0954455307107, 1028.4189432970347, 1035.1253191053547, 1053.3100818963592, 541.2449632762354, 632.9461995730322, 741.7515132430697, 753.5516367761173, 759.9848003039908, 4655.557722016403, 4743.620510359163, 4758.690685105457, 4996.575291100225, 5053.418856831511, 868.201254845531, 873.1399571422144, 878.6353663229816, 900.2842740021698, 914.6707185413379, 3158.9052328429725, 3198.4722520784276, 3227.790193637406, 3304.619968703582, 3421.4867769834227, 8637.740867773977, 9092.818143637223, 9136.177744003071, 9215.49314787693, 9217.308701847389, 9054.728358149267, 9168.862467063, 9174.816503669888, 9175.908240917639, 9177.587008584629, 7086.39765619928, 7221.169565499929, 7282.96358518204, 7284.854302913912, 7296.671649775667, 4751.786169622306, 4827.468978412302, 4941.728204948762, 4943.233798761198, 4978.27814966835, 8069.919300806917, 8241.752747417588, 8242.793930151794, 8243.0, 8365.0, 8523.616437260214, 8537.487750734976, 8548.273396761097, 8611.397202195787, 8708.912910871064, 6574.901376479261, 6687.699053542505, 6722.764703235363, 6723.7646682365885, 6726.39462448382, 7113.928860711534, 7133.607651579213, 7162.104736907887, 7201.279872012757, 7228.63856807168, 6422.710978005907, 6489.513286503437, 6509.8698026040165, 6551.21385433751, 6558.213014438107, 6239.812805615657, 6470.870582588228, 6478.060681201294, 6513.674316284072, 6748.628825414649, 5847.210626565359, 5852.326982397079, 5902.173695682522, 5906.40935906406, 5907.4683278505145, 4118.711690181659, 4122.525909520444, 4213.620774130343, 4264.936025959551, 4271.188474189894, 4045.170739998352, 4142.233686767958, 4173.207090652766, 4216.072464057902, 4345.652347812231], "value": 4006.004144820284, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.62250712962174, 345.4093500114805, 345.877213589176, 347.86433291016493, 351.9630438803926, 4795.952040479594, 4813.56677898989, 4840.830570930018, 4846.927296090562, 4868.975655121724, 4760.2383618621025, 4771.498992605779, 4772.236442169254, 4779.259214821708, 4787.928181077286, 3524.665156810112, 3606.711463082947, 3657.7988210648405, 3766.3973764197735, 3852.2873268445287, 3804.8478060877533, 3805.915314135472, 3811.942820857689, 3814.2180852925085, 3823.9426408603895, 3803.6766874815676, 3806.3719486284867, 3809.9903525565746, 3819.427085937104, 3836.616338366155, 3664.5419322584676, 3771.7171212159196, 3820.36963900956, 3833.6007357314656, 3839.558450778168, 3314.486254630536, 3390.3727810354917, 3397.3884700753856, 3400.914977125567, 3419.8290085495873, 4660.464046634646, 4668.696534725232, 4668.71233095784, 4669.299605059235, 4679.15775160471, 4355.586219309161, 4356.629686476654, 4358.346248062784, 4389.0, 4399.252127138362, 4838.516148385169, 4951.282064100721, 4960.900781984376, 4970.589264206857, 4994.87512812179, 3713.0135377573138, 3720.4047352423754, 3725.2735716535285, 3736.1406876418578, 3737.8691745788765, 5000.1999680051385, 5141.897162056813, 5221.530062294374, 5229.921551176735, 5257.579393648499, 3601.171730501947, 3739.3269211541638, 3787.9242415151543, 3798.848046078126, 3886.553046399644, 3914.236723838881, 3934.2524920265632, 3943.2902077626304, 3944.7041471889725, 3962.385830196324, 4330.956690433119, 4338.327559228324, 4353.238183317907, 4364.10535840152, 4417.801198946056, 4878.0729825606195, 5006.299118123466, 5015.297858299841, 5041.949580504148, 5061.544460998454, 1299.909006369564, 1303.270168705509, 1305.262526672427, 1306.863029164633, 1316.1773891317926, 877.9385443018928, 925.9490728009889, 929.990700093004, 940.3981451870808, 940.8400571902793, 4258.190943720683, 4268.253055715238, 4319.373690814814, 4321.351797230378, 4323.740575565476, 4989.775460104232, 5010.017036456026, 5010.423801262901, 5059.139946209153, 5059.974700126476, 4304.870853874389, 4305.84929527465, 4319.956800432018, 4320.027993701392, 4323.978380108079, 2562.372218806367, 2696.220494473638, 2702.3109107177543, 2712.3083613678386, 2714.8914043438435, 4692.296155576622, 4696.506866778939, 4728.456227533878, 4729.881752956204, 4742.135159844715, 4931.235658472942, 5171.534561889448, 5195.350581177353, 5201.323827902348, 5210.192420174879, 4650.883727906829, 4688.3436318916065, 4694.145812404081, 4703.2004559225015, 4716.174669432836, 5027.949720502748, 5032.547070763649, 5065.974670126626, 5103.209002604603, 5124.590032797293, 4828.855134345975, 4943.5797957174045, 4949.183384741477, 5032.521910418504, 5048.318477005704, 4639.768011599339, 4746.762661866824, 4792.0, 4830.9275360870315, 4841.830535931225, 3494.7728397654073, 3620.746547741738, 3624.3838547447526, 3630.7640003399692, 3638.163222458902, 5099.847004589868, 5133.8716532087, 5152.124138896322, 5167.733905193251, 5183.9113786105345, 4933.827316043921, 5004.499550044967, 5041.294218809443, 5044.9243261350375, 5055.873603159804, 4918.95081049185, 4953.380827396575, 4972.477889821516, 4985.850424487271, 5017.84946451607, 4951.603871690184, 4965.0, 4972.875678107932, 4988.775505102207, 5002.399712034579, 4884.3650325457465, 5023.372078490189, 5071.340725705634, 5086.440491546001, 5092.668976516515, 4901.681390709592, 4913.855132715136, 4924.975375123101, 4925.975370123269, 4929.507049295042], "value": 3808.678878091291, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.2026927821256, 675.3921470676393, 688.5971706551667, 738.4203400330741, 768.3238749900089, 12601.369931503423, 13492.527761528348, 13506.72986540271, 13517.0, 13541.593752187438, 13364.19814811112, 13429.0, 13481.59555213345, 13487.258200798977, 13492.66268343292, 681.3186813186813, 695.7808290388537, 800.8918795962529, 818.4639061414783, 832.2094010689868, 825.2531459029558, 833.4249367936135, 835.6699103853974, 839.9874001889976, 840.2060053249681, 872.9301655867547, 873.3406278259905, 873.6636394987944, 873.7946582553095, 874.6632546469623, 714.7569826259097, 731.2249016043012, 853.3642436384933, 867.280157469299, 870.3689824876946, 639.4564620072947, 639.4788247578209, 639.5171645407734, 640.0207682246171, 641.2049059166646, 12685.74628507434, 12718.745625087446, 12720.109592328541, 12726.363681815872, 12737.681557961032, 12902.225866447949, 12904.419301131471, 13011.21932684042, 13065.608031759062, 13133.408996595175, 11969.880301196963, 12011.279323240633, 12058.336791476466, 12312.876871231263, 12380.752384952251, 4135.958640413587, 4159.08500129974, 4171.290880550283, 4209.34755112958, 4211.452511173558, 65911.340886591, 66369.66815165941, 67251.0, 67514.3248567513, 68290.975635366, 10211.897881021094, 10264.589416423409, 10352.792944141074, 10375.481225938824, 10461.163106951426, 20253.0, 20350.491237718878, 20374.898125509277, 20497.795022049588, 20788.272410465863, 6597.868042639217, 6620.172478440195, 6654.567453115532, 6866.896996545056, 6997.370236678622, 3566.32239874423, 3584.354816133069, 3587.192881601671, 3649.799261040615, 3717.1078941054493, 2953.2469220348676, 3016.3364059906785, 3017.7736669749856, 3019.667836537979, 3038.9696103039128, 496.4588598427731, 526.4893053737868, 541.4991133201772, 546.0744038854183, 553.92522009529, 499.9375078115236, 523.9633225674166, 532.8836088394806, 562.7720773086907, 563.4872266237704, 7564.659590318449, 7668.003159589218, 7865.213478652089, 8136.796580085545, 8253.669853205925, 465.3113392179593, 495.2521692244736, 596.4661627843143, 598.3776872053016, 599.3137857153534, 4027.375756757707, 4062.654674352654, 4074.6536544393466, 4095.2014357200655, 4254.829921771538, 56644.43355566474, 64693.353066469674, 65007.67496162489, 65760.0, 67776.0, 56988.71505642445, 57163.42836571581, 57491.425085748604, 59218.70390648105, 61104.38895611076, 52514.0, 52545.999999999236, 52959.20561191585, 52971.73514132405, 53257.467425326024, 8543.786405339917, 8848.247898928663, 8910.108989101038, 8998.0, 9073.77315567116, 35821.0, 36827.26345473022, 37201.0, 37362.25275494529, 38106.23787524289, 45615.54384456113, 50078.0, 50816.0, 51066.0, 51506.0, 11644.35956022393, 11645.708857278636, 11650.30098194111, 11755.05959523219, 11849.229800062969, 12364.505419783109, 12866.678333041375, 13302.201867887958, 13529.323533823466, 14740.557783266519, 10390.272680912263, 10827.729306767395, 11691.473883675426, 11730.0, 11894.34581098048, 11537.653870383901, 12359.938200308941, 12477.188982716094, 12487.687807804878, 13020.674483137997, 11753.294802311888, 12404.875951240372, 12447.439865206268, 12850.228986260481, 12983.221006739626, 10135.442550659489, 10604.469776511298, 10760.3543787373, 10862.293950893167, 10936.45317734126, 9139.314551408805, 9591.712248632552, 10565.894341056799, 10804.027637512661, 10844.132469402586], "value": 6473.329840969887, "unit": "score"}], "name": "dromaeo_css", "value": 6296.491672338181, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 114.0, 115.0, 116.0, 115.0, 115.0, 114.0, 114.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 125.0, 131.0, 166.0, 125.0, 128.0, 128.0, 125.0, 148.0, 128.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 160.0, 162.0, 162.0, 162.0, 162.0, 160.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 114.0, 78.0, 77.0, 77.0, 76.0, 93.0, 77.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 105.0, 85.0, 85.0, 85.0, 96.0, 84.0, 83.0, 113.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 149.0, 148.0, 149.0, 148.0, 149.0, 148.0, 149.0, 149.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 58.0, 58.0, 60.0, 61.0, 69.0, 60.0, 59.0, 60.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 68.0, 68.0, 71.0, 68.0, 68.0, 67.0, 68.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 94.0, 84.0, 85.0, 85.0, 85.0, 84.0, 85.0, 91.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 141.0, 134.0, 134.0, 143.0, 141.0, 133.0, 132.0, 177.0, 144.0], "value": 141.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 176.0, 181.0, 174.0, 181.0, 177.0, 181.0, 178.0, 181.0, 179.0], "value": 178.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 67.0, 66.0, 67.0, 68.0, 67.0, 66.0, 67.0, 65.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1545.5000000000002, "alertThreshold": 2.0}]} 07:33:47 INFO - Return code: 0 07:33:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:33:47 INFO - # TBPL SUCCESS # 07:33:47 INFO - Running post-action listener: _resource_record_post_action 07:33:47 INFO - [mozharness: 2016-12-27 15:33:47.290900Z] Finished run-tests step (success) 07:33:47 INFO - Running post-run listener: _resource_record_post_run 07:33:47 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 8216576; Write bytes: 420769792; Read time: 2412; Write time: 415520 07:33:47 INFO - TinderboxPrint: CPU usage
13.1% 07:33:47 INFO - TinderboxPrint: I/O read bytes / time
8,216,576 / 2,412 07:33:47 INFO - TinderboxPrint: I/O write bytes / time
420,769,792 / 415,520 07:33:47 INFO - TinderboxPrint: CPU idle
8,356.1 (86.9%) 07:33:47 INFO - TinderboxPrint: CPU user
1,212.4 (12.6%) 07:33:47 INFO - TinderboxPrint: Swap in / out
0 / 0 07:33:47 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 4640768; Read time: 0; Write time: 628 07:33:47 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 6000640; Write bytes: 416129024; Read time: 2216; Write time: 414892 07:33:47 INFO - Running post-run listener: _upload_blobber_files 07:33:47 INFO - Blob upload gear active. 07:33:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:33:47 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 07:33:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:33:48 INFO - (blobuploader) - INFO - Open directory for files ... 07:33:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:33:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:33:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:49 INFO - (blobuploader) - INFO - Done attempting. 07:33:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:33:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:33:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:49 INFO - (blobuploader) - INFO - Done attempting. 07:33:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:33:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:33:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:50 INFO - (blobuploader) - INFO - Done attempting. 07:33:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:33:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:33:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:54 INFO - (blobuploader) - INFO - Done attempting. 07:33:54 INFO - (blobuploader) - INFO - Iteration through files over. 07:33:54 INFO - Return code: 0 07:33:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:33:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:33:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/79f6e534cece5cd07f21c0b310d86c8c7cc9515541ecdfc3e6d58ef0add838b50de169166a78db460883da2d46936edd4e1924910200cb95a7716b7e4bb9b3d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/612f64df115bae1b9ad5b6a43a2e088812da8ea3fda098d8739b2d84794669198b23f6a0c9729154a8e63a88ddf7c4504882b835c257eeaaa3e9509a0e04e6b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/51924de24b2e165b2f247ccd8f51b8529298f425c6a7e2be8405c2986dde689f89db4a8066dda836d222cd78c0c8e116ff0c399b2ffd2d01e1ffe797539f86fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a55557adedf79fe779a5bf5b51bccc2fc07f5eb1178ef8af7906217b8ec1a377409fe804967278a53fec51e076e868cbbabb2b88fbbebc2a40a07cd1b6079b32"} 07:33:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:33:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:33:54 INFO - Contents: 07:33:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/79f6e534cece5cd07f21c0b310d86c8c7cc9515541ecdfc3e6d58ef0add838b50de169166a78db460883da2d46936edd4e1924910200cb95a7716b7e4bb9b3d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/612f64df115bae1b9ad5b6a43a2e088812da8ea3fda098d8739b2d84794669198b23f6a0c9729154a8e63a88ddf7c4504882b835c257eeaaa3e9509a0e04e6b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/51924de24b2e165b2f247ccd8f51b8529298f425c6a7e2be8405c2986dde689f89db4a8066dda836d222cd78c0c8e116ff0c399b2ffd2d01e1ffe797539f86fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a55557adedf79fe779a5bf5b51bccc2fc07f5eb1178ef8af7906217b8ec1a377409fe804967278a53fec51e076e868cbbabb2b88fbbebc2a40a07cd1b6079b32"} 07:33:54 INFO - Running post-run listener: copy_logs_to_upload_dir 07:33:54 INFO - Copying logs to upload dir... 07:33:54 INFO - mkdir: /builds/slave/test/build/upload/logs 07:33:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.555905 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-12-27 07:33:54.309613) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 07:33:54.313051) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/79f6e534cece5cd07f21c0b310d86c8c7cc9515541ecdfc3e6d58ef0add838b50de169166a78db460883da2d46936edd4e1924910200cb95a7716b7e4bb9b3d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/612f64df115bae1b9ad5b6a43a2e088812da8ea3fda098d8739b2d84794669198b23f6a0c9729154a8e63a88ddf7c4504882b835c257eeaaa3e9509a0e04e6b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/51924de24b2e165b2f247ccd8f51b8529298f425c6a7e2be8405c2986dde689f89db4a8066dda836d222cd78c0c8e116ff0c399b2ffd2d01e1ffe797539f86fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a55557adedf79fe779a5bf5b51bccc2fc07f5eb1178ef8af7906217b8ec1a377409fe804967278a53fec51e076e868cbbabb2b88fbbebc2a40a07cd1b6079b32"} build_url:https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011211 build_url: 'https://queue.taskcluster.net/v1/task/D5XVe-BuS82Jxx7MZoF2yw/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/79f6e534cece5cd07f21c0b310d86c8c7cc9515541ecdfc3e6d58ef0add838b50de169166a78db460883da2d46936edd4e1924910200cb95a7716b7e4bb9b3d3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/612f64df115bae1b9ad5b6a43a2e088812da8ea3fda098d8739b2d84794669198b23f6a0c9729154a8e63a88ddf7c4504882b835c257eeaaa3e9509a0e04e6b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/51924de24b2e165b2f247ccd8f51b8529298f425c6a7e2be8405c2986dde689f89db4a8066dda836d222cd78c0c8e116ff0c399b2ffd2d01e1ffe797539f86fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a55557adedf79fe779a5bf5b51bccc2fc07f5eb1178ef8af7906217b8ec1a377409fe804967278a53fec51e076e868cbbabb2b88fbbebc2a40a07cd1b6079b32"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 07:33:54.338614) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 07:33:54.338970) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=17051fcd5208b27b50bedfeb000001dd-1482850432.193547-695500312 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004342 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 07:33:54.422622) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 07:33:54.422996) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 07:33:54.423352) ========= ========= Total master_lag: 0.19 =========