builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-006 starttime: 1482463446.41 results: success (0) buildid: 20161222183754 builduid: dee82441c0744801b44dbb935098a21e revision: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 19:24:06.408187) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 19:24:06.419816) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 19:24:06.420295) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 19:24:06.472242) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 19:24:06.472865) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-22 19:24:06-- 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% 41.6M=0s 2016-12-22 19:24:06 (41.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104856 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 19:24:06.593805) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 19:24:06.594215) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019071 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 19:24:06.651752) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-22 19:24:06.652174) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2785aaf276ba29fb2e1f5607d90d441fee42efb4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 2785aaf276ba29fb2e1f5607d90d441fee42efb4 --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=e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-22 19:24:06,870 truncating revision to first 12 chars 2016-12-22 19:24:06,870 Setting DEBUG logging. 2016-12-22 19:24:06,870 attempt 1/10 2016-12-22 19:24:06,870 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2785aaf276ba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 19:24:07,967 unpacking tar archive at: mozilla-central-2785aaf276ba/testing/mozharness/ program finished with exit code 0 elapsedTime=1.388253 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-22 19:24:08.060348) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 19:24:08.060640) ========= script_repo_revision: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 19:24:08.061033) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 19:24:08.061300) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 19:24:08.071843) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-12-22 19:24:08.072130) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:24:08 INFO - MultiFileLogger online at 20161222 19:24:08 in /builds/slave/test 19:24:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 19:24:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:24:08 INFO - {'append_to_log': False, 19:24:08 INFO - 'base_work_dir': '/builds/slave/test', 19:24:08 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 19:24:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:24:08 INFO - 'branch': 'Firefox-Non-PGO', 19:24:08 INFO - 'buildbot_json_path': 'buildprops.json', 19:24:08 INFO - 'config_files': ('talos/linux_config.py',), 19:24:08 INFO - 'default_actions': ('clobber', 19:24:08 INFO - 'read-buildbot-config', 19:24:08 INFO - 'download-and-extract', 19:24:08 INFO - 'populate-webroot', 19:24:08 INFO - 'create-virtualenv', 19:24:08 INFO - 'install', 19:24:08 INFO - 'run-tests'), 19:24:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:24:08 INFO - 'download_minidump_stackwalk': True, 19:24:08 INFO - 'download_symbols': 'ondemand', 19:24:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:24:08 INFO - 'tooltool.py': '/tools/tooltool.py', 19:24:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:24:08 INFO - '/tools/misc-python/virtualenv.py')}, 19:24:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:24:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:24:08 INFO - 'installer_path': 'installer.exe', 19:24:08 INFO - 'log_level': 'info', 19:24:08 INFO - 'log_name': 'talos', 19:24:08 INFO - 'log_to_console': True, 19:24:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:24:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:24:08 INFO - 'opt_config_files': (), 19:24:08 INFO - 'pip_index': False, 19:24:08 INFO - 'sps_profile': False, 19:24:08 INFO - 'sps_profile_interval': 0, 19:24:08 INFO - 'suite': 'dromaeojs', 19:24:08 INFO - 'system_bits': '32', 19:24:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:24:08 INFO - 'title': 'talos-linux64-ix-006', 19:24:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:24:08 INFO - 'use_talos_json': True, 19:24:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:24:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:24:08 INFO - 'work_dir': 'build'} 19:24:08 INFO - [mozharness: 2016-12-23 03:24:08.384972Z] Running clobber step. 19:24:08 INFO - Running pre-action listener: _resource_record_pre_action 19:24:08 INFO - Running main action method: clobber 19:24:08 INFO - rmtree: /builds/slave/test/build 19:24:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:24:08 INFO - Running post-action listener: _resource_record_post_action 19:24:08 INFO - [mozharness: 2016-12-23 03:24:08.835505Z] Finished clobber step (success) 19:24:08 INFO - [mozharness: 2016-12-23 03:24:08.835605Z] Running read-buildbot-config step. 19:24:08 INFO - Running pre-action listener: _resource_record_pre_action 19:24:08 INFO - Running main action method: read_buildbot_config 19:24:08 INFO - Using buildbot properties: 19:24:08 INFO - { 19:24:08 INFO - "project": "", 19:24:08 INFO - "product": "firefox", 19:24:08 INFO - "script_repo_revision": "production", 19:24:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:24:08 INFO - "repository": "", 19:24:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 19:24:08 INFO - "buildid": "20161222183754", 19:24:08 INFO - "pgo_build": "False", 19:24:08 INFO - "basedir": "/builds/slave/test", 19:24:08 INFO - "buildnumber": 889, 19:24:08 INFO - "slavename": "talos-linux64-ix-006", 19:24:08 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:24:08 INFO - "platform": "ubuntu64_hw", 19:24:08 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 19:24:08 INFO - "branch": "mozilla-central", 19:24:08 INFO - "repo_path": "mozilla-central", 19:24:08 INFO - "revision": "2785aaf276ba29fb2e1f5607d90d441fee42efb4", 19:24:08 INFO - "stage_platform": "linux64", 19:24:08 INFO - "builduid": "dee82441c0744801b44dbb935098a21e", 19:24:08 INFO - "slavebuilddir": "test" 19:24:08 INFO - } 19:24:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 19:24:08 INFO - Running post-action listener: _resource_record_post_action 19:24:08 INFO - [mozharness: 2016-12-23 03:24:08.838061Z] Finished read-buildbot-config step (success) 19:24:08 INFO - [mozharness: 2016-12-23 03:24:08.838151Z] Running download-and-extract step. 19:24:08 INFO - Running pre-action listener: _resource_record_pre_action 19:24:08 INFO - Running main action method: download_and_extract 19:24:08 INFO - mkdir: /builds/slave/test/build/tests 19:24:08 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')]} 19:24:08 INFO - trying https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 19:24:08 INFO - Downloading https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/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 19:24:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/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 19:24:09 INFO - Downloaded 1361 bytes. 19:24:09 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 19:24:09 INFO - Using the following test package requirements: 19:24:09 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 19:24:09 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:24:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:24:09 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:24:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:24:09 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:24:09 INFO - u'jsshell-linux-x86_64.zip'], 19:24:09 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:24:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:24:09 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 19:24:09 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:24:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:24:09 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:24:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:24:09 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:24:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:24:09 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:24:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:24:09 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:24:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 19:24:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:24:09 INFO - Fetch https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:24:10 INFO - Expected file size: 20236300 19:24:10 INFO - Obtained file size: 20236300 19:24:14 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 19:24:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 19:24:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:24:14 INFO - Fetch https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:24:14 INFO - Expected file size: 20236300 19:24:14 INFO - Obtained file size: 20236300 19:24:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 19:24:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:24:19 INFO - Fetch https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 19:24:20 INFO - Expected file size: 14409776 19:24:20 INFO - Obtained file size: 14409776 19:24: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')]} 19:24:20 INFO - trying https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:24:20 INFO - Downloading https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/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 19:24:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/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 19:24:21 INFO - Downloaded 55926542 bytes. 19:24:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:24:21 INFO - mkdir: /builds/slave/test/properties 19:24:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:24:21 INFO - Writing to file /builds/slave/test/properties/build_url 19:24:21 INFO - Contents: 19:24:21 INFO - build_url:https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:24:22 INFO - Running post-action listener: _resource_record_post_action 19:24:22 INFO - Running post-action listener: set_extra_try_arguments 19:24:22 INFO - [mozharness: 2016-12-23 03:24:22.015058Z] Finished download-and-extract step (success) 19:24:22 INFO - [mozharness: 2016-12-23 03:24:22.015356Z] Running populate-webroot step. 19:24:22 INFO - Running pre-action listener: _resource_record_pre_action 19:24:22 INFO - Running main action method: populate_webroot 19:24:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:24:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:24:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:24:22 INFO - '--tppagecycles', 19:24:22 INFO - '7'], 19:24:22 INFO - 'tests': ['tsvgm']}}, 19:24:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:24:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:24:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:24:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:24:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:24:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:24:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:24:22 INFO - 'talos_options': ['--disable-e10s'], 19:24:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:24:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:24:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:24:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:24:22 INFO - 'talos_options': ['--disable-e10s'], 19:24:22 INFO - 'tests': ['damp', 'tps']}, 19:24:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:24:22 INFO - 'tests': ['damp', 'tps']}, 19:24:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:24:22 INFO - 'tests': ['dromaeo_dom']}, 19:24:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:24:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:24:22 INFO - 'tests': ['basic_compositor_video']}, 19:24:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:24:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:24:22 INFO - 'tests': ['a11yr', 19:24:22 INFO - 'ts_paint', 19:24:22 INFO - 'tpaint', 19:24:22 INFO - 'sessionrestore', 19:24:22 INFO - 'sessionrestore_no_auto_restore', 19:24:22 INFO - 'tabpaint']}, 19:24:22 INFO - 'other-e10s': {'tests': ['a11yr', 19:24:22 INFO - 'ts_paint', 19:24:22 INFO - 'tpaint', 19:24:22 INFO - 'sessionrestore', 19:24:22 INFO - 'sessionrestore_no_auto_restore', 19:24:22 INFO - 'tabpaint']}, 19:24:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:24:22 INFO - 'ts_paint', 19:24:22 INFO - 'tpaint', 19:24:22 INFO - 'sessionrestore', 19:24:22 INFO - 'sessionrestore_no_auto_restore', 19:24:22 INFO - 'tabpaint']}, 19:24:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:24:22 INFO - 'ts_paint', 19:24:22 INFO - 'tpaint', 19:24:22 INFO - 'sessionrestore', 19:24:22 INFO - 'sessionrestore_no_auto_restore', 19:24:22 INFO - 'tabpaint']}, 19:24:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:24:22 INFO - 'tests': ['a11yr', 19:24:22 INFO - 'ts_paint', 19:24:22 INFO - 'tpaint', 19:24:22 INFO - 'sessionrestore', 19:24:22 INFO - 'sessionrestore_no_auto_restore', 19:24:22 INFO - 'tabpaint']}, 19:24:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:24:22 INFO - 'tests': ['a11yr', 19:24:22 INFO - 'ts_paint', 19:24:22 INFO - 'tpaint', 19:24:22 INFO - 'sessionrestore', 19:24:22 INFO - 'sessionrestore_no_auto_restore', 19:24:22 INFO - 'tabpaint']}, 19:24:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:24:22 INFO - 'tests': ['tsvgx', 19:24:22 INFO - 'tsvgr_opacity', 19:24:22 INFO - 'tart', 19:24:22 INFO - 'tscrollx', 19:24:22 INFO - 'cart', 19:24:22 INFO - 'tsvg_static']}, 19:24:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:24:22 INFO - 'tsvgr_opacity', 19:24:22 INFO - 'tart', 19:24:22 INFO - 'tscrollx', 19:24:22 INFO - 'cart', 19:24:22 INFO - 'tsvg_static']}, 19:24:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:24:22 INFO - 'talos_options': ['--disable-e10s'], 19:24:22 INFO - 'tests': ['tp5o']}, 19:24:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:24:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:24:22 INFO - 'talos_options': ['--disable-e10s', 19:24:22 INFO - '--xperf_path', 19:24:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:24:22 INFO - 'tests': ['tp5n']}, 19:24:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:24:22 INFO - 'talos_options': ['--xperf_path', 19:24:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:24:22 INFO - 'tests': ['tp5n']}}, 19:24:22 INFO - 'talos.zip': {'path': '', 19:24:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:24:22 INFO - Running post-action listener: _resource_record_post_action 19:24:22 INFO - [mozharness: 2016-12-23 03:24:22.036819Z] Finished populate-webroot step (success) 19:24:22 INFO - [mozharness: 2016-12-23 03:24:22.036913Z] Running create-virtualenv step. 19:24:22 INFO - Running pre-action listener: _resource_record_pre_action 19:24:22 INFO - Running main action method: create_virtualenv 19:24:22 INFO - Creating virtualenv /builds/slave/test/build/venv 19:24:22 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 19:24:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:24:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:24:22 INFO - Using real prefix '/usr' 19:24:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:24:22 INFO - Installing distribute.............................................................................................................................................................................................done. 19:24:24 INFO - Installing pip.................done. 19:24:24 INFO - Return code: 0 19:24:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:24:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:24:24 INFO - Reading from file tmpfile_stdout 19:24:24 INFO - Output received: 19:24:24 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) 19:24:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:24: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')]} 19:24:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:24:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24: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')]} 19:24:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:24:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161850>, '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 0x20d66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d6b60>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', '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 19:24: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', 'pip>=1.5'] in /builds/slave/test/build 19:24: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 pip>=1.5 19:24:24 INFO - Using env: {'DISPLAY': ':0', 19:24:24 INFO - 'HOME': '/home/cltbld', 19:24:24 INFO - 'LANG': 'en_US.UTF-8', 19:24:24 INFO - 'LANGUAGE': 'en_US:en', 19:24:24 INFO - 'LOGNAME': 'cltbld', 19:24:24 INFO - 'MAIL': '/var/mail/cltbld', 19:24:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:24 INFO - 'MOZ_NO_REMOTE': '1', 19:24:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:24 INFO - 'NO_EM_RESTART': '1', 19:24:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:24 INFO - 'PWD': '/builds/slave/test', 19:24:24 INFO - 'SHELL': '/bin/bash', 19:24:24 INFO - 'SHLVL': '1', 19:24:24 INFO - 'TERM': 'linux', 19:24:24 INFO - 'TMOUT': '86400', 19:24:24 INFO - 'USER': 'cltbld', 19:24:24 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', 19:24:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:24 INFO - '_': '/tools/buildbot/bin/python'} 19:24:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:24:25 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 19:24:25 INFO - Cleaning up... 19:24:25 INFO - Return code: 0 19:24:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:24:25 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')]} 19:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:24:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:25 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')]} 19:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:24:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:25 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 0x1d661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161850>, '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 0x20d66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d6b60>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', '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 19:24:25 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 19:24:25 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 19:24:25 INFO - Using env: {'DISPLAY': ':0', 19:24:25 INFO - 'HOME': '/home/cltbld', 19:24:25 INFO - 'LANG': 'en_US.UTF-8', 19:24:25 INFO - 'LANGUAGE': 'en_US:en', 19:24:25 INFO - 'LOGNAME': 'cltbld', 19:24:25 INFO - 'MAIL': '/var/mail/cltbld', 19:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:25 INFO - 'MOZ_NO_REMOTE': '1', 19:24:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:25 INFO - 'NO_EM_RESTART': '1', 19:24:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:25 INFO - 'PWD': '/builds/slave/test', 19:24:25 INFO - 'SHELL': '/bin/bash', 19:24:25 INFO - 'SHLVL': '1', 19:24:25 INFO - 'TERM': 'linux', 19:24:25 INFO - 'TMOUT': '86400', 19:24:25 INFO - 'USER': 'cltbld', 19:24:25 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', 19:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:25 INFO - '_': '/tools/buildbot/bin/python'} 19:24:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:24:25 INFO - Downloading/unpacking psutil>=3.1.1 19:24:25 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 19:24:25 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 19:24:25 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 19:24:25 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 19:24:25 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 19:24:25 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 19:24:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:24:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:24:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:24:27 INFO - Installing collected packages: psutil 19:24:27 INFO - Running setup.py install for psutil 19:24:27 INFO - building 'psutil._psutil_linux' extension 19:24:27 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 19:24:27 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 19:24:27 INFO - building 'psutil._psutil_posix' extension 19:24:27 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 19:24:27 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 19:24:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:24:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:24:27 INFO - Successfully installed psutil 19:24:27 INFO - Cleaning up... 19:24:27 INFO - Return code: 0 19:24:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:24:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:24:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:24:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:24:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161850>, '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 0x20d66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d6b60>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', '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 19:24:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:24:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:24:27 INFO - Using env: {'DISPLAY': ':0', 19:24:27 INFO - 'HOME': '/home/cltbld', 19:24:27 INFO - 'LANG': 'en_US.UTF-8', 19:24:27 INFO - 'LANGUAGE': 'en_US:en', 19:24:27 INFO - 'LOGNAME': 'cltbld', 19:24:27 INFO - 'MAIL': '/var/mail/cltbld', 19:24:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:27 INFO - 'MOZ_NO_REMOTE': '1', 19:24:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:27 INFO - 'NO_EM_RESTART': '1', 19:24:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:27 INFO - 'PWD': '/builds/slave/test', 19:24:27 INFO - 'SHELL': '/bin/bash', 19:24:27 INFO - 'SHLVL': '1', 19:24:27 INFO - 'TERM': 'linux', 19:24:27 INFO - 'TMOUT': '86400', 19:24:27 INFO - 'USER': 'cltbld', 19:24:27 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', 19:24:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:27 INFO - '_': '/tools/buildbot/bin/python'} 19:24:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:24:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:24:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:24:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:24:30 INFO - Installing collected packages: mozsystemmonitor 19:24:30 INFO - Running setup.py install for mozsystemmonitor 19:24:30 INFO - Successfully installed mozsystemmonitor 19:24:30 INFO - Cleaning up... 19:24:30 INFO - Return code: 0 19:24:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:24:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:24:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:24:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:30 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 0x1d661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161850>, '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 0x20d66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d6b60>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', '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 19:24:30 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 19:24:30 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 19:24:30 INFO - Using env: {'DISPLAY': ':0', 19:24:30 INFO - 'HOME': '/home/cltbld', 19:24:30 INFO - 'LANG': 'en_US.UTF-8', 19:24:30 INFO - 'LANGUAGE': 'en_US:en', 19:24:30 INFO - 'LOGNAME': 'cltbld', 19:24:30 INFO - 'MAIL': '/var/mail/cltbld', 19:24:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:30 INFO - 'MOZ_NO_REMOTE': '1', 19:24:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:30 INFO - 'NO_EM_RESTART': '1', 19:24:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:30 INFO - 'PWD': '/builds/slave/test', 19:24:30 INFO - 'SHELL': '/bin/bash', 19:24:30 INFO - 'SHLVL': '1', 19:24:30 INFO - 'TERM': 'linux', 19:24:30 INFO - 'TMOUT': '86400', 19:24:30 INFO - 'USER': 'cltbld', 19:24:30 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', 19:24:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:30 INFO - '_': '/tools/buildbot/bin/python'} 19:24:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:24:30 INFO - Downloading/unpacking jsonschema==2.5.1 19:24: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 19:24: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 19:24: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 19:24: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 19:24: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 19:24: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 19:24:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:24:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:24: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 19:24: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 19:24: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 19:24: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 19:24: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 19:24: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 19:24:32 INFO - Downloading functools32-3.2.3-2.tar.gz 19:24:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:24:32 INFO - warning: no files found matching '*.txt' 19:24:32 INFO - no previously-included directories found matching 'build' 19:24:32 INFO - no previously-included directories found matching 'dist' 19:24:32 INFO - no previously-included directories found matching '.git*' 19:24:32 INFO - Installing collected packages: jsonschema, functools32 19:24:32 INFO - Running setup.py install for functools32 19:24:32 INFO - warning: no files found matching '*.txt' 19:24:32 INFO - no previously-included directories found matching 'build' 19:24:32 INFO - no previously-included directories found matching 'dist' 19:24:32 INFO - no previously-included directories found matching '.git*' 19:24:32 INFO - Successfully installed jsonschema functools32 19:24:32 INFO - Cleaning up... 19:24:33 INFO - Return code: 0 19:24:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:24: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')]} 19:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:24:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24: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')]} 19:24:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:24:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:33 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 0x1d661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161850>, '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 0x20d66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d6b60>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', '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 19:24:33 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 19:24:33 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 19:24:33 INFO - Using env: {'DISPLAY': ':0', 19:24:33 INFO - 'HOME': '/home/cltbld', 19:24:33 INFO - 'LANG': 'en_US.UTF-8', 19:24:33 INFO - 'LANGUAGE': 'en_US:en', 19:24:33 INFO - 'LOGNAME': 'cltbld', 19:24:33 INFO - 'MAIL': '/var/mail/cltbld', 19:24:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:33 INFO - 'MOZ_NO_REMOTE': '1', 19:24:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:33 INFO - 'NO_EM_RESTART': '1', 19:24:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:33 INFO - 'PWD': '/builds/slave/test', 19:24:33 INFO - 'SHELL': '/bin/bash', 19:24:33 INFO - 'SHLVL': '1', 19:24:33 INFO - 'TERM': 'linux', 19:24:33 INFO - 'TMOUT': '86400', 19:24:33 INFO - 'USER': 'cltbld', 19:24:33 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', 19:24:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:33 INFO - '_': '/tools/buildbot/bin/python'} 19:24:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:24:33 INFO - Cleaning up... 19:24:33 INFO - Return code: 0 19:24:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:24: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')]} 19:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:24:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24: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')]} 19:24:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:24:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:33 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 0x1d661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161850>, '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 0x20d66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d6b60>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', '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 19:24:33 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 19:24:33 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 19:24:33 INFO - Using env: {'DISPLAY': ':0', 19:24:33 INFO - 'HOME': '/home/cltbld', 19:24:33 INFO - 'LANG': 'en_US.UTF-8', 19:24:33 INFO - 'LANGUAGE': 'en_US:en', 19:24:33 INFO - 'LOGNAME': 'cltbld', 19:24:33 INFO - 'MAIL': '/var/mail/cltbld', 19:24:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:33 INFO - 'MOZ_NO_REMOTE': '1', 19:24:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:33 INFO - 'NO_EM_RESTART': '1', 19:24:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:33 INFO - 'PWD': '/builds/slave/test', 19:24:33 INFO - 'SHELL': '/bin/bash', 19:24:33 INFO - 'SHLVL': '1', 19:24:33 INFO - 'TERM': 'linux', 19:24:33 INFO - 'TMOUT': '86400', 19:24:33 INFO - 'USER': 'cltbld', 19:24:33 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', 19:24:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:33 INFO - '_': '/tools/buildbot/bin/python'} 19:24:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:24:33 INFO - Downloading/unpacking blobuploader==1.2.4 19:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:24:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:35 INFO - Downloading blobuploader-1.2.4.tar.gz 19:24:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:24:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:24:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:24:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:24:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:24:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:36 INFO - Downloading docopt-0.6.1.tar.gz 19:24:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:24:36 INFO - Installing collected packages: blobuploader, requests, docopt 19:24:36 INFO - Running setup.py install for blobuploader 19:24:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:24:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:24:36 INFO - Running setup.py install for requests 19:24:36 INFO - Running setup.py install for docopt 19:24:36 INFO - Successfully installed blobuploader requests docopt 19:24:36 INFO - Cleaning up... 19:24:36 INFO - Return code: 0 19:24:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:24: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')]} 19:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:24:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24: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')]} 19:24:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:24:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:36 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 0x1d661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161850>, '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 0x20d66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d6b60>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', '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 19:24:36 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 19:24:36 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 19:24:36 INFO - Using env: {'DISPLAY': ':0', 19:24:36 INFO - 'HOME': '/home/cltbld', 19:24:36 INFO - 'LANG': 'en_US.UTF-8', 19:24:36 INFO - 'LANGUAGE': 'en_US:en', 19:24:36 INFO - 'LOGNAME': 'cltbld', 19:24:36 INFO - 'MAIL': '/var/mail/cltbld', 19:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:36 INFO - 'MOZ_NO_REMOTE': '1', 19:24:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:36 INFO - 'NO_EM_RESTART': '1', 19:24:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:36 INFO - 'PWD': '/builds/slave/test', 19:24:36 INFO - 'SHELL': '/bin/bash', 19:24:36 INFO - 'SHLVL': '1', 19:24:36 INFO - 'TERM': 'linux', 19:24:36 INFO - 'TMOUT': '86400', 19:24:36 INFO - 'USER': 'cltbld', 19:24:36 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', 19:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:36 INFO - '_': '/tools/buildbot/bin/python'} 19:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:24:37 INFO - Running setup.py (path:/tmp/pip-ceBfoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:24:37 INFO - Running setup.py (path:/tmp/pip-CQTugT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:24:37 INFO - Running setup.py (path:/tmp/pip-m5YwCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:24:37 INFO - Running setup.py (path:/tmp/pip-OjsEq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:24:37 INFO - Running setup.py (path:/tmp/pip-gB9ko3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:24:37 INFO - Running setup.py (path:/tmp/pip-CmEfqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:24:37 INFO - Running setup.py (path:/tmp/pip-oRtI1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:24:37 INFO - Running setup.py (path:/tmp/pip-fRkdgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:24:37 INFO - Running setup.py (path:/tmp/pip-Jw8Yfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:24:37 INFO - Running setup.py (path:/tmp/pip-CE7Cls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:24:37 INFO - Running setup.py (path:/tmp/pip-Nw22mk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:24:37 INFO - Running setup.py (path:/tmp/pip-ix7u6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:24:37 INFO - Running setup.py (path:/tmp/pip-Styc0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:24:38 INFO - Running setup.py (path:/tmp/pip-65ZXfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:24:38 INFO - Running setup.py (path:/tmp/pip-gF0FeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:24:38 INFO - Running setup.py (path:/tmp/pip-eUp7sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:24:38 INFO - Running setup.py (path:/tmp/pip-aZu7lm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:24:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:24:38 INFO - Running setup.py install for manifestparser 19:24:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:24:38 INFO - Running setup.py install for mozcrash 19:24:38 INFO - Running setup.py install for mozdebug 19:24:38 INFO - Running setup.py install for mozdevice 19:24:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:24:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:24:38 INFO - Running setup.py install for mozfile 19:24:38 INFO - Running setup.py install for mozhttpd 19:24:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:24:38 INFO - Running setup.py install for mozinfo 19:24:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:24:39 INFO - Running setup.py install for mozInstall 19:24:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:24:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:24:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:24:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:24:39 INFO - Running setup.py install for mozleak 19:24:39 INFO - Running setup.py install for mozlog 19:24:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:24:39 INFO - Running setup.py install for moznetwork 19:24:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:24:39 INFO - Running setup.py install for mozprocess 19:24:39 INFO - Running setup.py install for mozprofile 19:24:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:24:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:24:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:24:39 INFO - Running setup.py install for mozrunner 19:24:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:24:39 INFO - Running setup.py install for mozscreenshot 19:24:39 INFO - Running setup.py install for moztest 19:24:40 INFO - Running setup.py install for mozversion 19:24:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:24:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:24:40 INFO - Cleaning up... 19:24:40 INFO - Return code: 0 19:24:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:24: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')]} 19:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:24:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24: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')]} 19:24:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:24:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:40 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 0x1d661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161850>, '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 0x20d66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d6b60>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', '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 19:24:40 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 19:24:40 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 19:24:40 INFO - Using env: {'DISPLAY': ':0', 19:24:40 INFO - 'HOME': '/home/cltbld', 19:24:40 INFO - 'LANG': 'en_US.UTF-8', 19:24:40 INFO - 'LANGUAGE': 'en_US:en', 19:24:40 INFO - 'LOGNAME': 'cltbld', 19:24:40 INFO - 'MAIL': '/var/mail/cltbld', 19:24:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:40 INFO - 'MOZ_NO_REMOTE': '1', 19:24:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:40 INFO - 'NO_EM_RESTART': '1', 19:24:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:40 INFO - 'PWD': '/builds/slave/test', 19:24:40 INFO - 'SHELL': '/bin/bash', 19:24:40 INFO - 'SHLVL': '1', 19:24:40 INFO - 'TERM': 'linux', 19:24:40 INFO - 'TMOUT': '86400', 19:24:40 INFO - 'USER': 'cltbld', 19:24:40 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', 19:24:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:40 INFO - '_': '/tools/buildbot/bin/python'} 19:24:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:24:40 INFO - Running setup.py (path:/tmp/pip-F1dK4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:24:40 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)) 19:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:24:40 INFO - Running setup.py (path:/tmp/pip-Z5rgAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:24:40 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)) 19:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:24:40 INFO - Running setup.py (path:/tmp/pip-CzG24M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:24:40 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)) 19:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:24:40 INFO - Running setup.py (path:/tmp/pip-4jZiPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:24:40 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)) 19:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:24:40 INFO - Running setup.py (path:/tmp/pip-OzQa7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:24:40 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)) 19:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:24:40 INFO - Running setup.py (path:/tmp/pip-0glpzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:24:40 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)) 19:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:24:40 INFO - Running setup.py (path:/tmp/pip-mH2TyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:24:40 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)) 19:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:24:40 INFO - Running setup.py (path:/tmp/pip-ry5bmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:24:40 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)) 19:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:24:40 INFO - Running setup.py (path:/tmp/pip-bx7yzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:24:41 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)) 19:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:24:41 INFO - Running setup.py (path:/tmp/pip-CGU06h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:24:41 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)) 19:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:24:41 INFO - Running setup.py (path:/tmp/pip-xXCadP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:24:41 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)) 19:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:24:41 INFO - Running setup.py (path:/tmp/pip-M5_9Pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:24:41 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)) 19:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:24:41 INFO - Running setup.py (path:/tmp/pip-RrLKcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:24:41 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)) 19:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:24:41 INFO - Running setup.py (path:/tmp/pip-AitZo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:24:41 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)) 19:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:24:41 INFO - Running setup.py (path:/tmp/pip-32cN4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:24:41 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)) 19:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:24:41 INFO - Running setup.py (path:/tmp/pip-Tvtlod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:24:41 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)) 19:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:24:41 INFO - Running setup.py (path:/tmp/pip-0OjPEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:24:41 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)) 19:24:41 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)) 19:24:41 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)) 19:24:41 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)) 19:24:41 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)) 19:24:41 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)) 19:24:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:24:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:24:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:24:43 INFO - Downloading blessings-1.6.tar.gz 19:24:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:24:43 INFO - Installing collected packages: blessings 19:24:43 INFO - Running setup.py install for blessings 19:24:43 INFO - Successfully installed blessings 19:24:43 INFO - Cleaning up... 19:24:43 INFO - Return code: 0 19:24:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:24:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:24:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:24:44 INFO - Reading from file tmpfile_stdout 19:24:44 INFO - Current package versions: 19:24:44 INFO - argparse == 1.2.1 19:24:44 INFO - blessings == 1.6 19:24:44 INFO - blobuploader == 1.2.4 19:24:44 INFO - docopt == 0.6.1 19:24:44 INFO - functools32 == 3.2.3-2 19:24:44 INFO - jsonschema == 2.5.1 19:24:44 INFO - manifestparser == 1.1 19:24:44 INFO - mozInstall == 1.12 19:24:44 INFO - mozcrash == 1.0 19:24:44 INFO - mozdebug == 0.1 19:24:44 INFO - mozdevice == 0.49 19:24:44 INFO - mozfile == 1.2 19:24:44 INFO - mozhttpd == 0.7 19:24:44 INFO - mozinfo == 0.9 19:24:44 INFO - mozleak == 0.1 19:24:44 INFO - mozlog == 3.4 19:24:44 INFO - moznetwork == 0.27 19:24:44 INFO - mozprocess == 0.23 19:24:44 INFO - mozprofile == 0.28 19:24:44 INFO - mozrunner == 6.13 19:24:44 INFO - mozscreenshot == 0.1 19:24:44 INFO - mozsystemmonitor == 0.3 19:24:44 INFO - moztest == 0.7 19:24:44 INFO - mozversion == 1.4 19:24:44 INFO - psutil == 3.1.1 19:24:44 INFO - requests == 1.2.3 19:24:44 INFO - wsgiref == 0.1.2 19:24:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:44 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 0x1d661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161850>, '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 0x20d66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d6b60>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', '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 19:24:44 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 19:24:44 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 19:24:44 INFO - Using env: {'DISPLAY': ':0', 19:24:44 INFO - 'HOME': '/home/cltbld', 19:24:44 INFO - 'LANG': 'en_US.UTF-8', 19:24:44 INFO - 'LANGUAGE': 'en_US:en', 19:24:44 INFO - 'LOGNAME': 'cltbld', 19:24:44 INFO - 'MAIL': '/var/mail/cltbld', 19:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:44 INFO - 'MOZ_NO_REMOTE': '1', 19:24:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:44 INFO - 'NO_EM_RESTART': '1', 19:24:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:44 INFO - 'PWD': '/builds/slave/test', 19:24:44 INFO - 'SHELL': '/bin/bash', 19:24:44 INFO - 'SHLVL': '1', 19:24:44 INFO - 'TERM': 'linux', 19:24:44 INFO - 'TMOUT': '86400', 19:24:44 INFO - 'USER': 'cltbld', 19:24:44 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', 19:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:44 INFO - '_': '/tools/buildbot/bin/python'} 19:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:24:44 INFO - Cleaning up... 19:24:44 INFO - Return code: 0 19:24:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:24:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e76a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2161850>, '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 0x20d66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d6b60>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', '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 19:24:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:24:44 INFO - Using env: {'DISPLAY': ':0', 19:24:44 INFO - 'HOME': '/home/cltbld', 19:24:44 INFO - 'LANG': 'en_US.UTF-8', 19:24:44 INFO - 'LANGUAGE': 'en_US:en', 19:24:44 INFO - 'LOGNAME': 'cltbld', 19:24:44 INFO - 'MAIL': '/var/mail/cltbld', 19:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:24:44 INFO - 'MOZ_NO_REMOTE': '1', 19:24:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:24:44 INFO - 'NO_EM_RESTART': '1', 19:24:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:24:44 INFO - 'PWD': '/builds/slave/test', 19:24:44 INFO - 'SHELL': '/bin/bash', 19:24:44 INFO - 'SHLVL': '1', 19:24:44 INFO - 'TERM': 'linux', 19:24:44 INFO - 'TMOUT': '86400', 19:24:44 INFO - 'USER': 'cltbld', 19:24:44 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', 19:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:24:44 INFO - '_': '/tools/buildbot/bin/python'} 19:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:24:44 INFO - Cleaning up... 19:24:44 INFO - Return code: 0 19:24:44 INFO - Running post-action listener: _resource_record_post_action 19:24:44 INFO - Running post-action listener: _start_resource_monitoring 19:24:44 INFO - Starting resource monitoring. 19:24:44 INFO - [mozharness: 2016-12-23 03:24:44.625458Z] Finished create-virtualenv step (success) 19:24:44 INFO - [mozharness: 2016-12-23 03:24:44.626027Z] Running install step. 19:24:44 INFO - Running pre-action listener: _resource_record_pre_action 19:24:44 INFO - Running main action method: install 19:24:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:24:44 INFO - Reading from file tmpfile_stdout 19:24:44 INFO - Detecting whether we're running mozinstall >=1.0... 19:24:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:24:44 INFO - Reading from file tmpfile_stdout 19:24:44 INFO - Output received: 19:24:44 INFO - Usage: mozinstall [options] installer 19:24:44 INFO - Options: 19:24:44 INFO - -h, --help show this help message and exit 19:24:44 INFO - -d DEST, --destination=DEST 19:24:44 INFO - Directory to install application into. [default: 19:24:44 INFO - "/builds/slave/test"] 19:24:44 INFO - --app=APP Application being installed. [default: firefox] 19:24:44 INFO - mkdir: /builds/slave/test/build/application 19:24:44 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'] 19:24:44 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 19:25:01 INFO - Reading from file tmpfile_stdout 19:25:01 INFO - Output received: 19:25:01 INFO - /builds/slave/test/build/application/firefox/firefox 19:25:01 INFO - Running post-action listener: _resource_record_post_action 19:25:01 INFO - [mozharness: 2016-12-23 03:25:01.024406Z] Finished install step (success) 19:25:01 INFO - [mozharness: 2016-12-23 03:25:01.024652Z] Running run-tests step. 19:25:01 INFO - Running pre-action listener: _resource_record_pre_action 19:25:01 INFO - Running main action method: run_tests 19:25:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:25:01 INFO - Python 2.7.3 19:25:01 INFO - Return code: 0 19:25:01 INFO - grabbing minidump binary from tooltool 19:25:01 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')]} 19:25:01 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 0x2161850>, '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 0x20d66d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20d6b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:25:01 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 19:25:01 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 19:25:01 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 19:25:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:25:01 INFO - Return code: 0 19:25:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:25:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:25:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:25:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:25:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:25:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 19:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --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 19:25:01 INFO - Using env: {'DISPLAY': ':0', 19:25:01 INFO - 'HOME': '/home/cltbld', 19:25:01 INFO - 'LANG': 'en_US.UTF-8', 19:25:01 INFO - 'LANGUAGE': 'en_US:en', 19:25:01 INFO - 'LOGNAME': 'cltbld', 19:25:01 INFO - 'MAIL': '/var/mail/cltbld', 19:25:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:25:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:25:01 INFO - 'MOZ_NO_REMOTE': '1', 19:25:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:25:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:25:01 INFO - 'NO_EM_RESTART': '1', 19:25:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:25:01 INFO - 'PWD': '/builds/slave/test', 19:25:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:25:01 INFO - 'SHELL': '/bin/bash', 19:25:01 INFO - 'SHLVL': '1', 19:25:01 INFO - 'TERM': 'linux', 19:25:01 INFO - 'TMOUT': '86400', 19:25:01 INFO - 'USER': 'cltbld', 19:25:01 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816', 19:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:25:01 INFO - '_': '/tools/buildbot/bin/python'} 19:25:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--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 19:25:01 INFO - mozversion application_buildid: 20161222183754 19:25:01 INFO - mozversion application_changeset: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 19:25:01 INFO - mozversion application_display_name: Nightly 19:25:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:25:01 INFO - mozversion application_name: Firefox 19:25:01 INFO - mozversion application_remotingname: firefox 19:25:01 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:25:01 INFO - mozversion application_vendor: Mozilla 19:25:01 INFO - mozversion application_version: 53.0a1 19:25:01 INFO - mozversion platform_buildid: 20161222183754 19:25:01 INFO - mozversion platform_changeset: 2785aaf276ba29fb2e1f5607d90d441fee42efb4 19:25:01 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:25:01 INFO - mozversion platform_version: 53.0a1 19:25:01 INFO - using testdate: 1482463501 19:25:01 INFO - actual date: 1482463501 19:25:01 INFO - starting webserver on 'localhost:52072' 19:25:01 INFO - SUITE-START | Running 2 tests 19:25:01 INFO - TEST-START | dromaeo_css 19:25:01 INFO - operating with platform_type : linux_ 19:25:01 INFO - Initialising browser for dromaeo_css test... 19:25:01 INFO - TEST-INFO | started process 12937 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOR7xqm/profile http://localhost:52072/getInfo.html) 19:25:01 INFO - PROCESS | 12937 | 1482463501810 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 19:25:04 INFO - PROCESS | 12937 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 19:25:04 INFO - PROCESS | 12937 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 19:25:05 INFO - PROCESS | 12937 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 19:25:05 INFO - PROCESS | 12937 | __metrics Screen width/height:1600/1200 19:25:05 INFO - PROCESS | 12937 | colorDepth:24 19:25:05 INFO - PROCESS | 12937 | Browser inner width/height: 1024/697 19:25:05 INFO - PROCESS | 12937 | __metrics 19:25:05 INFO - PROCESS | 12937 | 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" 19:25:05 INFO - PROCESS | 12937 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:25:06 INFO - PROCESS | 12937 | console.error: PushService: 19:25:06 INFO - PROCESS | 12937 | stateChangeProcessEnqueue: Error transitioning state 19:25:06 INFO - PROCESS | 12937 | UnknownError 19:25:07 INFO - TEST-INFO | 12937: exit 0 19:25:07 INFO - Browser initialized. 19:25:07 INFO - Running cycle 1/1 for dromaeo_css test... 19:25:07 INFO - TEST-INFO | started process 13017 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOR7xqm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:25:19 INFO - PROCESS | 13017 | RSS: Main: 151244800 19:25:19 INFO - PROCESS | 13017 | 19:26:10 INFO - PROCESS | 13017 | 1482463570856 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] 19:28:23 INFO - PROCESS | 13017 | Cycle 1(1): loaded http://localhost:52072/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52072/tests/dromaeo/cssquery-ext.html) 19:28:25 INFO - PROCESS | 13017 | RSS: Main: 265961472 19:28:25 INFO - PROCESS | 13017 | 19:30:07 INFO - PROCESS | 13017 | ************************* 19:30:07 INFO - PROCESS | 13017 | A coding exception was thrown and uncaught in a Task. 19:30:07 INFO - PROCESS | 13017 | 19:30:07 INFO - PROCESS | 13017 | Full message: ReferenceError: fetch is not defined 19:30:07 INFO - PROCESS | 13017 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 19:30:07 INFO - PROCESS | 13017 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 19:30:07 INFO - PROCESS | 13017 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 19:30:07 INFO - PROCESS | 13017 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:30:07 INFO - PROCESS | 13017 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:30:07 INFO - PROCESS | 13017 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:30:07 INFO - PROCESS | 13017 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 19:30:07 INFO - PROCESS | 13017 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:30:07 INFO - PROCESS | 13017 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:30:07 INFO - PROCESS | 13017 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:30:07 INFO - PROCESS | 13017 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 19:30:07 INFO - PROCESS | 13017 | 19:30:07 INFO - PROCESS | 13017 | ************************* 19:31:27 INFO - PROCESS | 13017 | Cycle 1(1): loaded http://localhost:52072/tests/dromaeo/cssquery-ext.html (next: http://localhost:52072/tests/dromaeo/cssquery-jquery.html) 19:31:28 INFO - PROCESS | 13017 | RSS: Main: 231362560 19:31:28 INFO - PROCESS | 13017 | 19:34:30 INFO - PROCESS | 13017 | Cycle 1(1): loaded http://localhost:52072/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52072/tests/dromaeo/cssquery-mootools.html) 19:34:31 INFO - PROCESS | 13017 | RSS: Main: 187015168 19:34:31 INFO - PROCESS | 13017 | 19:37:33 INFO - PROCESS | 13017 | Cycle 1(1): loaded http://localhost:52072/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52072/tests/dromaeo/cssquery-prototype.html) 19:37:34 INFO - PROCESS | 13017 | RSS: Main: 187695104 19:37:34 INFO - PROCESS | 13017 | 19:40:37 INFO - PROCESS | 13017 | Cycle 1(1): loaded http://localhost:52072/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52072/tests/dromaeo/cssquery-yui.html) 19:40:37 INFO - PROCESS | 13017 | RSS: Main: 187764736 19:40:37 INFO - PROCESS | 13017 | 19:43:40 INFO - PROCESS | 13017 | Cycle 1(1): loaded http://localhost:52072/tests/dromaeo/cssquery-yui.html (next: http://localhost:52072/tests/dromaeo/cssquery-dojo.html) 19:43:41 INFO - PROCESS | 13017 | RSS: Main: 282165248 19:43:41 INFO - PROCESS | 13017 | 19:43:41 INFO - PROCESS | 13017 | __start_tp_report 19:43:41 INFO - PROCESS | 13017 | _x_x_mozilla_page_load 19:43:41 INFO - PROCESS | 13017 | _x_x_mozilla_page_load_details 19:43:41 INFO - PROCESS | 13017 | |i|pagename|runs| 19:43:41 INFO - PROCESS | 13017 | |0;dojo.html;2575.639410482532;2597.7012643546;2610.3474131467133;2629.8290611110288;2634.025410598078;4620.5148459411785;4693.037927224919;4696.319033740114;4698.577128058479;4699.0836786826585;4658.045100754346;4701.553352431522;4729.408823897013;4752.738599377033;4785.54537318955;4328.06946506501;4337.652987760987;4386.7367957922625;4419.955800441987;4495.483019452772;4036.555978842325;4358.716683415568;4452.332150177467;4488.124815660947;4542.818287268522;4251.06476575153;4383.780810959471;4387.056782791715;4449.243628583149;4450.354698568705;4272.530021697611;4308.870733877989;4355.346697995326;4385.978070109629;4403.823847046115;4385.912281754379;4395.428594282755;4404.207242696313;4434.512203657628;4448.132614140244;4741.407324084489;4759.714417134983;4759.928601070987;4770.856874293777;4774.7135171889795;4917.114919314522;4929.556339929423;4951.455339912607;4972.154733695281;5079.974600127012;5009.398872135331;5012.548870601627;5014.949850501521;5045.747712614355;5055.848324550269;4684.273937539687;4691.859244222679;4694.765261736899;4697.741624210701;4734.171519984024;5019.799208031711;5086.872828179251;5108.897822043613;5150.407703114115;5151.124308867502;4969.975150124226;4996.3504744383;5000.324956130876;5043.470435604282;5051.419086804991;4925.21196608541;4972.801087956514;5084.491550844886;5089.389273287155;5143.202803565453;4903.5586797188425;4906.779194936236;4949;5000.199968005102;5020.196768517019;4847.660663753503;4912.140375434285;4927.162382395001;4959.876003099951;4974.054929563371;4150.958490415057;4171.6036976487185;4172.311568591209;4184.14225083857;4226.6618670506905;3630.6550877666577;3642.053066202753;3642.0712718257205;3665.2303016366327;3672.081979505124;4264.019275566637;4382.189294980439;4385.802638881258;4386.605205531518;4429.49060857999;4903.362562866804;4983.327250821093;5031.421386540522;5044.974775126101;5097.668651537638;4364.519902810688;4387.14450682114;4388.4733831940375;4396.230659634552;4433.423654924839;4480.32795080734;4484.035932274576;4484.910301793946;4486.102779444124;4525.434320709911;4574.428196475441;4617.422822147231;4637.698549594265;4646.605038571759;4658.720476771405;4947.0353281111275;4950.381202349707;4957.107720610325;4963.230699241623;4976.975115124401;4665.5567721066445;4669.976650116728;4672.76636168197;4707.19977603815;4713.62291016711;4792.520747925179;4842.982973575588;4868.537488938545;4869.147899117711;4900.019996000743;4988.600911926965;5002.749862506933;5007.073691366963;5032.094223039888;5055.266986287042;4772.546608072227;4885.1206782779445;4898.510148985216;4900.90198196027;4945.826896058621;4798.1123492154065;4799.928001080057;4835.516448355136;4852.660313778143;4853.369062022056;4783.856484305476;4783.928241076317;4795.712257264575;4843.0313937211995;4843.95156048435;4880.487548807324;4883.877903052452;4886.877828054327;4889.559939605452;4927.162382395073;4704.388429504143;4725.692829966179;4737.881552961204;4866.878328041686;4900.068986892479 19:43:41 INFO - PROCESS | 13017 | |1;ext.html;532.398389819504;545.7816873250699;557.1447827584658;560.6608002158694;573.2547688005592;31962.200944976386;38659.80670096649;40438.79780601097;40466;44413.77793111038;40419.191616167656;40793.18413631733;40858;40901.18197636046;40968;7284.672189751461;7444.292792184761;7445.329920307171;7451.254874512532;7473.738419155331;7321.487495875291;9492.335536512446;9662.806743865118;9664.081912218327;10016.248781341392;9668.274879384038;9680.935097139345;9723.367981081243;9729.173020293283;9730.464824434654;9906.950465247664;10007.049330313608;10008.449535275557;10084.14284785794;10119.139873110795;9956.900430995742;10013.649522266685;10024.19806415485;10032.046955539212;10044.648437304731;38033.23933521314;38218;38291.23417531662;38341.61658383408;38404.61595384038;2964.970350296491;3073.431415188197;3132.232603012254;3283.868645254187;3413.7269018478464;28921.276968075967;28942.421151577062;29027.274318142216;29086.563701544495;29827;3105.6117985251844;3129.217695576106;3135.6237251529733;3164.4145833020966;3181.7136457718684;245491;245495;246552;246594;246693;52779.47220527822;53242;53270.200946985824;53305.733471332394;53327.46672533225;55692;55709.442905570424;55977.16034259489;55980;56006.43993560094;14462.27688615565;14584.927075364556;14836.480723174853;14929.477468288558;14956.177410242324;9956.651517196864;10157.492125393701;11093.33439993603;11268.943655281835;11312.20814542974;16215.675686486204;16591.336346546002;16603.41888033913;16613.41853035138;16618.75071873923;6105.603135796159;6128.63228206309;6174.320824709278;6239.532035097295;6251.906221406683;780.2938340801584;832.4381042796089;903.1465265324337;994.358638678063;1001.1890368801148;26604.733952660226;27722.5841612376;28012.299692507444;28433.28916777056;28691;939.2861425316672;965.4931161140457;977.1498796047484;989.8911119776819;998.8451789972565;14814.629634259014;14838.777418338734;14860.62848428798;14883.925580372246;15143.545693629208;359256;361077;362641;365401;366579;50447.74776126096;50529.747351263744;50588.747056265216;50668;50718.74640626773;1209.52223871572;1228.3858070964518;1243.1670780577215;1246.7132559511545;1253.4422182129047;23944.429041074076;24255;25025.374365640277;25127.371815704755;25336.74663253344;43667.78166109149;44178;44233.11533769371;44244;44374.778126109166;251297;252457;252553;252908;253069;34432;35495;36176.45735314025;37089.44365834461;37270.44094338641;41899.7905010473;41925.371119432624;42137.789311053246;42738.78630606827;43388.34917476177;35290.82354588211;35547;35621.28757424785;35813.46279805857;36219.45670814887;35511;35644.28711425705;35707;36101.81949090238;36258;34193.31613367768;34929;35142.648573513936;35226.47160292649;35776;30827.383452331276;32650.83674581707;32883.34233315368;33004.83497582497;33246;23059.077636894337;23918.760812391654;24093.879530602233;24139.637905431082;25581.872090639426 19:43:41 INFO - PROCESS | 13017 | |2;jquery.html;377.4526935942883;378.2397381263661;379.79870668545664;395.7348576453776;397.7553804410289;15869.603259918507;16704.749428758547;17222.56943576411;17310.221040053195;17489.82510174898;15998.440054598092;16074.276657550408;16187.35250589975;16194;16392.09843458612;4538.659600529965;4743.075100355432;4746.738929358884;4752.572268495835;4753.120672675549;3610.368185567515;3857.691384689232;3893.7274390792654;4698.929516057245;4753.382060332152;5872.412758724115;5880.794172203974;5974.4324289192455;5979.551533634973;5981.730822112993;5483.424240454775;5536.058869992071;5701.401352857932;5979.043353063511;5984.162217289583;5358.490943360374;5363.115086010804;5367.731613419313;5369;5374.301340825707;12290.631281061582;12322.199057061262;12326.137170398075;12400.255984640951;12409.503619855195;1814.355903654197;1851;1933.1783991803568;2106.231225602644;2341.2156927429332;9004.954975225148;9005.549722513848;9120;9423.528823558863;9580.13778759908;1679.202378870027;1731.9567010824703;1746.0309528211837;1753.815849335827;1768.5755418699418;65956;66528;67070;67098.3290167092;68449.3155068448;9064.320175986828;9072.54637268134;9147.908520914705;9374.484403357745;9398.859017114748;12362.75274494505;12386.81419778704;12391.132620716644;12397.62807115788;12417.813732794015;6966;7050.4007159391;7227;7298.708051677873;7321.487495875344;6890.793276201722;6943.6875340609795;6988.650567471708;7132.786016419515;7145.1425828900865;3825.3879379299733;3862.748921320105;3866.188100498927;3917.3536366499206;3955.4660120883314;1658.7428948512759;1741.4688520001268;1759.3138675916402;1861.2927087706855;1882.905854707259;716.8888822232458;783.0916137280841;866.9653213871375;876.8334016536837;881.3301890563218;15474.381024758884;15640.92179539095;15698.447450239302;16087.115208663401;16745;838.0990435281974;845.9323254139654;850.4259624753266;851.8040850604395;882.1222883231097;5728.541716662657;9476;9587.089226523469;9610.279229057849;9842.852357214648;350909;352435;355086;356645;360217;53203.73398133062;56215.43784562184;69468.84295248412;73579.63210183915;74711;910.4764760262875;923.1230331185401;931.0596297739332;932.6595792535676;936.3773090894479;14532.491362802251;14724.852751472348;15107.54677359681;15145.848541514444;15375.461858834888;34679.65320346764;35641.82179089088;36411.27177456383;36502.634973649925;36684.81657591695;46546.018097285676;47008.52991470179;47275.76362118167;47471.28793068037;47763.88014037553;5896;6026.638401695912;6977.790666279817;7004.124484439445;7236.312550307713;9665.516724163905;10021;10131.189504839447;10491.79016419652;10590.735231619272;8756;8779.517126558101;9024;9097.226735727538;9150.267978561566;9584.712458626253;9849;9962.651307204213;10039.4478303694;10217.591296348062;9579.377340472847;9625.56684949165;9669.9033009669;10143.543540540546;10411;7179.138503379628;7567.432442566719;7604.42966777505;7711.691532338644;7898.249666281694;9146.496942668216;9239.538023098683;9366.344355895022;9398.248140148908;9561.426314420878 19:43:41 INFO - PROCESS | 13017 | |3;mootools.html;1351.3581049001723;1373.6222538801828;1488.1591900576173;1698.2273065755082;1745.397837745978;7173.533720308183;7597.962010189955;7612.69549218032;7613.2386761323905;7615.428842836789;6698.3971442570155;6952.756653517128;7215.675294611742;7415.443841711866;7440;2123.4266747978036;2163.3077415227135;2163.7511686156136;2164.8701077935375;2167.6315026445463;2142.1324363632752;2172.478605134764;2173.847830651855;2180.869147851134;2181.9781802182015;2142.2180903970093;2146.087912637123;2152.7416709994823;2182.5962196993605;2183.4213933307688;2083.322920050982;2105.6630939049833;2110.757262914769;2111.651577489705;2112.3134981131193;2125.9574808503744;2152.483403983033;2152.720146380976;2155;2156.33153722345;7414.369778568775;7442.590657513834;7493;7509.887351689729;7542.849143017163;4232.703710740249;4343.370211319372;4344.847930322424;4347.500037495697;4357.281048626973;2794.259521226856;2906.070057581575;2941.352902361477;2958.76329893608;2960.718731720483;804.927556519916;847.254416113822;849.2144766091402;853.3216093205916;1745.9301627934867;9806.9509652452;9928.652497162564;9934.25493088014;9934.354266972696;9936.354136981074;3854.2676891390547;4002.519697636302;4033.556308806029;4058.7564746115327;4166.854160104382;8043.075046369742;8046.235607617267;8050.11448740638;8052.5571093590415;8058.556779377073;4630.120277147331;4631.698939568917;4634.83778067766;4638.049199914077;4638.095571363552;3748.493953316322;3850.7882066486613;4130.380442933524;4314.633256173261;4319.97840010798;3258.6904244096772;3264.281857991238;3339.4656854903583;3345.1469875181674;3377.324535092991;1060.4590371705226;1078.8489611454402;1079.4063265204106;1084.4848696868924;1099.4392859641641;695.4193248637343;784.5488843914771;790.6481615680967;806.1817255485687;820.1716266570807;4684.531546845288;4839.540243676845;4888.584470320063;4919.729414882144;5181.4041385240425;388.2138669194903;396.43310066604454;398.44218094667116;399.37497815917993;404.9473568436084;3200.8079515228696;3344.3144155448076;3355.094124586348;3400.6599340065795;3462.307538492312;8059.758207253792;8295.294899933573;8418.200270974248;8464.238218560358;8465.365097617703;8422.326213902874;8426.452280601741;8430.988281406271;8435.156484351515;8442.831143377098;6743.966280168666;6974.849149890313;7015.824604384829;7017.087778588853;7026.92973070273;4971.105201063843;4987.202047672355;4994.625403094855;5012.223105418666;5076.111680455831;8113.553754543556;8204.138565450781;8269.421140520106;8272.83454330898;8348.874766878624;8364.247217750431;8473.66105355779;8531.530765807693;8548.059713431394;8681.348898832739;5581.413951535193;6105.8168254952425;6206.534509911684;6217.720202590804;6219.906701399392;6606.735730570724;6787.287334829969;6790.728370865308;6921.307869213038;7192.820179495345;6389.329120442287;6527.281998980014;6669.133012708511;6720.966395167993;6774.525783195129;6220.688965551795;6314.589551679126;6373.7131829066875;6383.265924418752;6413.518986075969;5881.5588830837005;5970.164177015135;5990.071538911476;5994.790182343597;5997.100434934795;4280.592330688192;4326.762028088486;4364.323529852878;4463.018136010072;4480.700596826884;4305.289627211475;4347.891302717458;4352.869413917588;4373.14723628902;4398.846040388571 19:43:41 INFO - PROCESS | 13017 | |4;prototype.html;331.56233771421734;337.3590178660545;346.26937162586944;347.30365616938036;349.7831344566368;4774.188387974044;4807.495213002632;4842.830500932464;4850.708957462554;4859.659823812334;4779.187538118519;4789.353437285965;4802.399700037496;4818.253170758538;4819.807207711688;3672.1186915140374;3720.2373513429816;3740.6072362401965;3758.5489741230954;3846.557645870733;3811.9999999999864;3827.54069511659;3827.6363745444137;3829.5787463379006;3865.188310454808;3797.810109494528;3799.601041890603;3804.391297392418;3805.771653700787;3814.7030009796663;3609.314230296235;3718.3307004739136;3728.3102626014274;3792.867249646277;3842.154725960312;3388.5086662434055;3418.265073009315;3418.4872269159823;3427.5946861786547;3434.3303055904107;4661.487236403993;4672.57946784791;4674.789634466457;4680.204365257914;4683.103343146031;3964.226975739732;4101.8974525636795;4335.328024156261;4354.716943398669;4365.7817109144735;4945.950540494585;4947.579455746266;4955.4548999610015;4956.529129732669;4959.628027897886;3472.75690701651;3477;3495.4582039783872;3497.6327485613897;3501.2647344057777;5068.366454193226;5234.581233501349;5256.9737151314;5260.764175225514;5270.578353731692;3439.208981934169;3476.348734351163;3480.686738193524;3491.755577109578;3496.2483066140394;3519.331327047853;3524.4360902255517;3524.788512689247;3531.682148606638;3539.1152211947015;4070.3487442009136;4078.653314468244;4091.468109145792;4093.9385909211387;4112.115895082512;4756.952430475721;4962.950370496321;4989.101961646866;5022.89954200914;5028.572571331405;1201.387292480841;1210.4250481021445;1212.5089338817793;1222.7860124478182;1223.247702662856;794.7337641890032;827.1810907201827;832.2925513313756;836.1387770596295;838.262329150356;4278.679099067583;4332.176886391576;4338.783060846945;4361.498427680857;4456;5170.6380553360905;5174.663646862942;5175.120229560908;5186.662866913639;5192.494176688718;4476.843310484118;4478.932816007762;4498.5501449854755;4504.301833215857;4521.932171017437;2562.2441379793004;2656.4194304388857;2671.2119924622284;2692.8017032420776;2694.811363204557;4781.306710526954;4790.976045119752;4792.2332426811545;4800.711957282574;4801.327814106028;5188.7806365504075;5204.594759415012;5205.401378841406;5210.192420174879;5232.9738351308;4622.583967442946;4799.928001079987;4864.975675121602;4906.8773280668265;4911.87720306988;5186.688798672092;5188.250227379495;5189.860134618652;5192.740362981911;5201.583873290203;5127.743612819419;5132.255822905732;5133;5136.743162841918;5147.253648221062;4718.678769944421;4826.8793280168275;4876.244182151772;4896.853094407174;4904.8663831233525;3541.1678255610427;3567.3935430976276;3578.7137029038136;3597.8380972856808;3603.765755225902;5161.406438259648;5174.7930082796265;5177.585793136615;5195.740212989411;5208.24480450325;4813.711177329372;5036.29491871131;5053.974730126326;5055.115354812967;5056.469070747666;5014.423341315795;5017.222330538772;5022.518357084742;5026.623003274696;5050.292958985819;4998.725070121178;5000.924801167766;5009.373828271466;5010.574101201293;5013.147764880052;4862.635302352267;4934.18585933317;5007.846625950876;5072.949270507248;5117.795288188431;4850.563449289581;4943.604511638989;4952.851414457428;4962.6774259673;4971.229459433794 19:43:41 INFO - PROCESS | 13017 | |5;yui.html;649.058864646263;662.3773652766396;676.2054585861613;704.2570088556571;743.1231147246248;12122.575709850156;12715.23708577486;12736.872631273685;12741;12744.55394061208;13395.263260520667;13451.93274033631;13469.124506907045;13469.259190744506;13584.252866092362;675.9864802703943;695.6695569604448;811.0429692962302;812.208097105322;827.2224109337234;796.398718967179;796.5618909599697;797.7407342613644;797.8563858505466;798.0582912163644;888.7111688701166;890.0965519997208;890.3366991591241;890.5636238243279;890.6749036601659;705.4955706669756;708.5429897715995;722.9674664640052;723.6273319240595;883.7127933421664;529.0133900275996;626.8432891777056;628.7767842415922;637.7385272038442;642.0882347067129;13316;13706.52027179054;13728.862711372958;13762;13765.587032389045;12219.144659873813;12269.202501837352;12526.373681315898;12534.373281335897;12665.620031399072;11740.354280514568;11803.173777835555;11806.67663178874;12045.096617753703;12058.336791476466;3849.30712471755;3851.9229615407817;3896.065271485366;3897.279003384355;3910.1397692507885;62949.37050629481;63723.681381592796;64336.678316608115;64628;65000.3499964999;9766.46284454362;9825.803483930282;9888.060634239735;10499.160067194605;10805.351678899291;18733.31400173991;20026.89986550087;20043.799562004486;20054;20123.295684650966;6399;6605.801825945229;6616.139901812733;6719.932800671931;6814.693338799766;3372.325534892982;3403.7447191460737;3403.7787543809573;3460.480927860791;3536.5225694531423;2898.753605943476;2955.497565413885;2972.583838262645;2981.701829817001;2995.1613548206537;478.301679547864;479.0873386199296;481.55215649446353;489.63277541843615;491.97540122993706;480.62991496547465;544.5072209650273;546.0416968220748;571.8227349521661;580.7851095094842;7404.629768511553;7510.319636511281;7610;7616;7688.154303026663;568.2896379525592;572.2617823008355;576.052393812185;581.6655423131716;582.5165112956236;3675.8162091895297;3758.680512156497;3841.8079096045085;3855.8072096395067;3899.5710471848074;66452;66774;68013.31986680168;68115;68598.3140168602;26426.47147057048;43841;56680.71659641675;58447.70776146092;58804.41195588075;40028.799856000536;41740.58259417428;41801.79099104485;48982.510174899224;50005.24992125121;8178.591070446335;8477.824827278951;8693.521856297964;8772.824543508967;9037.68368107113;33910.66089339174;34211.4868276981;34335.65664343325;35507;36315.27369452649;41236;42038.57961420469;44731.32903006393;46222.306665399374;47794.28308575444;11561.479733412185;11613.883861161281;11660.475278612314;11709.590164344207;11914.76170476603;12829.294388808705;12949.805752913902;12979.675508112372;13058.869411305765;13112.213267203999;11524.769504609692;11614.767704646029;11844.407779610812;12263.632091037283;13158.671033224246;10978.725531861768;11406.885931140583;12289.051065820875;12613.369331533204;12991.870081299066;11328.830067548828;12132.93933530362;12206.633800985985;12473.688157796129;12862.67843303925;9728.416295022322;10323.225758068025;10360.481975901024;10539.683809485728;10654.73363165927;10072.01420335518;10668.573257069631;10777;10847.67456976292;10853.891461085605 19:43:41 INFO - PROCESS | 13017 | __end_tp_report 19:43:41 INFO - PROCESS | 13017 | __start_cc_report 19:43:41 INFO - PROCESS | 13017 | _x_x_mozilla_cycle_collect,1133 19:43:41 INFO - PROCESS | 13017 | __end_cc_report 19:43:41 INFO - PROCESS | 13017 | __startTimestamp1482464621216__endTimestamp 19:43:41 INFO - PROCESS | 13017 | 19:43:41 INFO - PROCESS | 13017 | ------- Summary: start ------- 19:43:41 INFO - PROCESS | 13017 | Number of tests: 6 19:43:41 INFO - PROCESS | 13017 | 19:43:41 INFO - PROCESS | 13017 | [#0] dojo.html Cycles:165 Average:4632.41 Median:4752.74 stddev:478.56 (10.1%) stddev-sans-first:452.01 19:43:41 INFO - PROCESS | 13017 | Values: 2575.6 2597.7 2610.3 2629.8 2634.0 4620.5 4693.0 4696.3 4698.6 4699.1 4658.0 4701.6 4729.4 4752.7 4785.5 4328.1 4337.7 4386.7 4420.0 4495.5 4036.6 4358.7 4452.3 4488.1 4542.8 4251.1 4383.8 4387.1 4449.2 4450.4 4272.5 4308.9 4355.3 4386.0 4403.8 4385.9 4395.4 4404.2 4434.5 4448.1 4741.4 4759.7 4759.9 4770.9 4774.7 4917.1 4929.6 4951.5 4972.2 5080.0 5009.4 5012.5 5014.9 5045.7 5055.8 4684.3 4691.9 4694.8 4697.7 4734.2 5019.8 5086.9 5108.9 5150.4 5151.1 4970.0 4996.4 5000.3 5043.5 5051.4 4925.2 4972.8 5084.5 5089.4 5143.2 4903.6 4906.8 4949.0 5000.2 5020.2 4847.7 4912.1 4927.2 4959.9 4974.1 4151.0 4171.6 4172.3 4184.1 4226.7 3630.7 3642.1 3642.1 3665.2 3672.1 4264.0 4382.2 4385.8 4386.6 4429.5 4903.4 4983.3 5031.4 5045.0 5097.7 4364.5 4387.1 4388.5 4396.2 4433.4 4480.3 4484.0 4484.9 4486.1 4525.4 4574.4 4617.4 4637.7 4646.6 4658.7 4947.0 4950.4 4957.1 4963.2 4977.0 4665.6 4670.0 4672.8 4707.2 4713.6 4792.5 4843.0 4868.5 4869.1 4900.0 4988.6 5002.7 5007.1 5032.1 5055.3 4772.5 4885.1 4898.5 4900.9 4945.8 4798.1 4799.9 4835.5 4852.7 4853.4 4783.9 4783.9 4795.7 4843.0 4844.0 4880.5 4883.9 4886.9 4889.6 4927.2 4704.4 4725.7 4737.9 4866.9 4900.1 19:43:41 INFO - PROCESS | 13017 | 19:43:41 INFO - PROCESS | 13017 | [#1] ext.html Cycles:180 Average:45186.75 Median:35675.64 stddev:76559.51 (214.6%) stddev-sans-first:76700.86 19:43:41 INFO - PROCESS | 13017 | Values: 532.4 545.8 557.1 560.7 573.3 31962.2 38659.8 40438.8 40466.0 44413.8 40419.2 40793.2 40858.0 40901.2 40968.0 7284.7 7444.3 7445.3 7451.3 7473.7 7321.5 9492.3 9662.8 9664.1 10016.2 9668.3 9680.9 9723.4 9729.2 9730.5 9907.0 10007.0 10008.4 10084.1 10119.1 9956.9 10013.6 10024.2 10032.0 10044.6 38033.2 38218.0 38291.2 38341.6 38404.6 2965.0 3073.4 3132.2 3283.9 3413.7 28921.3 28942.4 29027.3 29086.6 29827.0 3105.6 3129.2 3135.6 3164.4 3181.7 245491.0 245495.0 246552.0 246594.0 246693.0 52779.5 53242.0 53270.2 53305.7 53327.5 55692.0 55709.4 55977.2 55980.0 56006.4 14462.3 14584.9 14836.5 14929.5 14956.2 9956.7 10157.5 11093.3 11268.9 11312.2 16215.7 16591.3 16603.4 16613.4 16618.8 6105.6 6128.6 6174.3 6239.5 6251.9 780.3 832.4 903.1 994.4 1001.2 26604.7 27722.6 28012.3 28433.3 28691.0 939.3 965.5 977.1 989.9 998.8 14814.6 14838.8 14860.6 14883.9 15143.5 359256.0 361077.0 362641.0 365401.0 366579.0 50447.7 50529.7 50588.7 50668.0 50718.7 1209.5 1228.4 1243.2 1246.7 1253.4 23944.4 24255.0 25025.4 25127.4 25336.7 43667.8 44178.0 44233.1 44244.0 44374.8 251297.0 252457.0 252553.0 252908.0 253069.0 34432.0 35495.0 36176.5 37089.4 37270.4 41899.8 41925.4 42137.8 42738.8 43388.3 35290.8 35547.0 35621.3 35813.5 36219.5 35511.0 35644.3 35707.0 36101.8 36258.0 34193.3 34929.0 35142.6 35226.5 35776.0 30827.4 32650.8 32883.3 33004.8 33246.0 23059.1 23918.8 24093.9 24139.6 25581.9 19:43:41 INFO - PROCESS | 13017 | 19:43:41 INFO - PROCESS | 13017 | [#2] jquery.html Cycles:180 Average:22271.17 Median:5365.42 stddev:58619.81 (1092.5%) stddev-sans-first:58761.20 19:43:41 INFO - PROCESS | 13017 | Values: 377.5 378.2 379.8 395.7 397.8 15869.6 16704.7 17222.6 17310.2 17489.8 15998.4 16074.3 16187.4 16194.0 16392.1 4538.7 4743.1 4746.7 4752.6 4753.1 3610.4 3857.7 3893.7 4698.9 4753.4 5872.4 5880.8 5974.4 5979.6 5981.7 5483.4 5536.1 5701.4 5979.0 5984.2 5358.5 5363.1 5367.7 5369.0 5374.3 12290.6 12322.2 12326.1 12400.3 12409.5 1814.4 1851.0 1933.2 2106.2 2341.2 9005.0 9005.5 9120.0 9423.5 9580.1 1679.2 1732.0 1746.0 1753.8 1768.6 65956.0 66528.0 67070.0 67098.3 68449.3 9064.3 9072.5 9147.9 9374.5 9398.9 12362.8 12386.8 12391.1 12397.6 12417.8 6966.0 7050.4 7227.0 7298.7 7321.5 6890.8 6943.7 6988.7 7132.8 7145.1 3825.4 3862.7 3866.2 3917.4 3955.5 1658.7 1741.5 1759.3 1861.3 1882.9 716.9 783.1 867.0 876.8 881.3 15474.4 15640.9 15698.4 16087.1 16745.0 838.1 845.9 850.4 851.8 882.1 5728.5 9476.0 9587.1 9610.3 9842.9 350909.0 352435.0 355086.0 356645.0 360217.0 53203.7 56215.4 69468.8 73579.6 74711.0 910.5 923.1 931.1 932.7 936.4 14532.5 14724.9 15107.5 15145.8 15375.5 34679.7 35641.8 36411.3 36502.6 36684.8 46546.0 47008.5 47275.8 47471.3 47763.9 5896.0 6026.6 6977.8 7004.1 7236.3 9665.5 10021.0 10131.2 10491.8 10590.7 8756.0 8779.5 9024.0 9097.2 9150.3 9584.7 9849.0 9962.7 10039.4 10217.6 9579.4 9625.6 9669.9 10143.5 10411.0 7179.1 7567.4 7604.4 7711.7 7898.2 9146.5 9239.5 9366.3 9398.2 9561.4 19:43:41 INFO - PROCESS | 13017 | 19:43:41 INFO - PROCESS | 13017 | [#3] mootools.html Cycles:180 Average:4813.42 Median:4979.15 stddev:2618.32 (52.6%) stddev-sans-first:2612.73 19:43:41 INFO - PROCESS | 13017 | Values: 1351.4 1373.6 1488.2 1698.2 1745.4 7173.5 7598.0 7612.7 7613.2 7615.4 6698.4 6952.8 7215.7 7415.4 7440.0 2123.4 2163.3 2163.8 2164.9 2167.6 2142.1 2172.5 2173.8 2180.9 2182.0 2142.2 2146.1 2152.7 2182.6 2183.4 2083.3 2105.7 2110.8 2111.7 2112.3 2126.0 2152.5 2152.7 2155.0 2156.3 7414.4 7442.6 7493.0 7509.9 7542.8 4232.7 4343.4 4344.8 4347.5 4357.3 2794.3 2906.1 2941.4 2958.8 2960.7 804.9 847.3 849.2 853.3 1745.9 9807.0 9928.7 9934.3 9934.4 9936.4 3854.3 4002.5 4033.6 4058.8 4166.9 8043.1 8046.2 8050.1 8052.6 8058.6 4630.1 4631.7 4634.8 4638.0 4638.1 3748.5 3850.8 4130.4 4314.6 4320.0 3258.7 3264.3 3339.5 3345.1 3377.3 1060.5 1078.8 1079.4 1084.5 1099.4 695.4 784.5 790.6 806.2 820.2 4684.5 4839.5 4888.6 4919.7 5181.4 388.2 396.4 398.4 399.4 404.9 3200.8 3344.3 3355.1 3400.7 3462.3 8059.8 8295.3 8418.2 8464.2 8465.4 8422.3 8426.5 8431.0 8435.2 8442.8 6744.0 6974.8 7015.8 7017.1 7026.9 4971.1 4987.2 4994.6 5012.2 5076.1 8113.6 8204.1 8269.4 8272.8 8348.9 8364.2 8473.7 8531.5 8548.1 8681.3 5581.4 6105.8 6206.5 6217.7 6219.9 6606.7 6787.3 6790.7 6921.3 7192.8 6389.3 6527.3 6669.1 6721.0 6774.5 6220.7 6314.6 6373.7 6383.3 6413.5 5881.6 5970.2 5990.1 5994.8 5997.1 4280.6 4326.8 4364.3 4463.0 4480.7 4305.3 4347.9 4352.9 4373.1 4398.8 19:43:41 INFO - PROCESS | 13017 | 19:43:41 INFO - PROCESS | 13017 | [#4] prototype.html Cycles:180 Average:4154.68 Median:4785.33 stddev:1218.69 (25.5%) stddev-sans-first:1187.85 19:43:41 INFO - PROCESS | 13017 | Values: 331.6 337.4 346.3 347.3 349.8 4774.2 4807.5 4842.8 4850.7 4859.7 4779.2 4789.4 4802.4 4818.3 4819.8 3672.1 3720.2 3740.6 3758.5 3846.6 3812.0 3827.5 3827.6 3829.6 3865.2 3797.8 3799.6 3804.4 3805.8 3814.7 3609.3 3718.3 3728.3 3792.9 3842.2 3388.5 3418.3 3418.5 3427.6 3434.3 4661.5 4672.6 4674.8 4680.2 4683.1 3964.2 4101.9 4335.3 4354.7 4365.8 4946.0 4947.6 4955.5 4956.5 4959.6 3472.8 3477.0 3495.5 3497.6 3501.3 5068.4 5234.6 5257.0 5260.8 5270.6 3439.2 3476.3 3480.7 3491.8 3496.2 3519.3 3524.4 3524.8 3531.7 3539.1 4070.3 4078.7 4091.5 4093.9 4112.1 4757.0 4963.0 4989.1 5022.9 5028.6 1201.4 1210.4 1212.5 1222.8 1223.2 794.7 827.2 832.3 836.1 838.3 4278.7 4332.2 4338.8 4361.5 4456.0 5170.6 5174.7 5175.1 5186.7 5192.5 4476.8 4478.9 4498.6 4504.3 4521.9 2562.2 2656.4 2671.2 2692.8 2694.8 4781.3 4791.0 4792.2 4800.7 4801.3 5188.8 5204.6 5205.4 5210.2 5233.0 4622.6 4799.9 4865.0 4906.9 4911.9 5186.7 5188.3 5189.9 5192.7 5201.6 5127.7 5132.3 5133.0 5136.7 5147.3 4718.7 4826.9 4876.2 4896.9 4904.9 3541.2 3567.4 3578.7 3597.8 3603.8 5161.4 5174.8 5177.6 5195.7 5208.2 4813.7 5036.3 5054.0 5055.1 5056.5 5014.4 5017.2 5022.5 5026.6 5050.3 4998.7 5000.9 5009.4 5010.6 5013.1 4862.6 4934.2 5007.8 5072.9 5117.8 4850.6 4943.6 4952.9 4962.7 4971.2 19:43:41 INFO - PROCESS | 13017 | 19:43:41 INFO - PROCESS | 13017 | [#5] yui.html Cycles:180 Average:14537.74 Median:3904.86 stddev:17784.37 (455.4%) stddev-sans-first:17803.68 19:43:41 INFO - PROCESS | 13017 | Values: 649.1 662.4 676.2 704.3 743.1 12122.6 12715.2 12736.9 12741.0 12744.6 13395.3 13451.9 13469.1 13469.3 13584.3 676.0 695.7 811.0 812.2 827.2 796.4 796.6 797.7 797.9 798.1 888.7 890.1 890.3 890.6 890.7 705.5 708.5 723.0 723.6 883.7 529.0 626.8 628.8 637.7 642.1 13316.0 13706.5 13728.9 13762.0 13765.6 12219.1 12269.2 12526.4 12534.4 12665.6 11740.4 11803.2 11806.7 12045.1 12058.3 3849.3 3851.9 3896.1 3897.3 3910.1 62949.4 63723.7 64336.7 64628.0 65000.3 9766.5 9825.8 9888.1 10499.2 10805.4 18733.3 20026.9 20043.8 20054.0 20123.3 6399.0 6605.8 6616.1 6719.9 6814.7 3372.3 3403.7 3403.8 3460.5 3536.5 2898.8 2955.5 2972.6 2981.7 2995.2 478.3 479.1 481.6 489.6 492.0 480.6 544.5 546.0 571.8 580.8 7404.6 7510.3 7610.0 7616.0 7688.2 568.3 572.3 576.1 581.7 582.5 3675.8 3758.7 3841.8 3855.8 3899.6 66452.0 66774.0 68013.3 68115.0 68598.3 26426.5 43841.0 56680.7 58447.7 58804.4 40028.8 41740.6 41801.8 48982.5 50005.2 8178.6 8477.8 8693.5 8772.8 9037.7 33910.7 34211.5 34335.7 35507.0 36315.3 41236.0 42038.6 44731.3 46222.3 47794.3 11561.5 11613.9 11660.5 11709.6 11914.8 12829.3 12949.8 12979.7 13058.9 13112.2 11524.8 11614.8 11844.4 12263.6 13158.7 10978.7 11406.9 12289.1 12613.4 12991.9 11328.8 12132.9 12206.6 12473.7 12862.7 9728.4 10323.2 10360.5 10539.7 10654.7 10072.0 10668.6 10777.0 10847.7 10853.9 19:43:41 INFO - PROCESS | 13017 | -------- Summary: end -------- 19:43:41 INFO - PROCESS | 13017 | 19:43:42 INFO - TEST-INFO | 13017: exit 0 19:43:42 INFO - TEST-OK | dromaeo_css | took 1120787ms 19:43:42 INFO - TEST-START | kraken 19:43:42 INFO - operating with platform_type : linux_ 19:43:42 INFO - Initialising browser for kraken test... 19:43:42 INFO - TEST-INFO | started process 13316 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphSMwcZ/profile http://localhost:52072/getInfo.html) 19:43:42 INFO - PROCESS | 13316 | 1482464622588 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 19:43:44 INFO - PROCESS | 13316 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 19:43:44 INFO - PROCESS | 13316 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 19:43:45 INFO - PROCESS | 13316 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 19:43:45 INFO - PROCESS | 13316 | __metrics Screen width/height:1600/1200 19:43:45 INFO - PROCESS | 13316 | colorDepth:24 19:43:45 INFO - PROCESS | 13316 | Browser inner width/height: 1024/697 19:43:45 INFO - PROCESS | 13316 | __metrics 19:43:46 INFO - PROCESS | 13316 | 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" 19:43:46 INFO - PROCESS | 13316 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:43:47 INFO - PROCESS | 13316 | console.error: PushService: 19:43:47 INFO - PROCESS | 13316 | stateChangeProcessEnqueue: Error transitioning state 19:43:47 INFO - PROCESS | 13316 | UnknownError 19:43:47 INFO - TEST-INFO | 13316: exit 0 19:43:47 INFO - Browser initialized. 19:43:47 INFO - Running cycle 1/1 for kraken test... 19:43:47 INFO - TEST-INFO | started process 13402 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphSMwcZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:43:59 INFO - PROCESS | 13402 | RSS: Main: 151576576 19:43:59 INFO - PROCESS | 13402 | 19:44:02 INFO - PROCESS | 13402 | Cycle 1(1): loaded http://localhost:52072/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52072/tests/kraken/driver.html?testName=audio-beat-detection) 19:44:03 INFO - PROCESS | 13402 | RSS: Main: 205307904 19:44:03 INFO - PROCESS | 13402 | 19:44:06 INFO - PROCESS | 13402 | Cycle 1(1): loaded http://localhost:52072/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52072/tests/kraken/driver.html?testName=audio-dft) 19:44:06 INFO - PROCESS | 13402 | RSS: Main: 184958976 19:44:06 INFO - PROCESS | 13402 | 19:44:09 INFO - PROCESS | 13402 | Cycle 1(1): loaded http://localhost:52072/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52072/tests/kraken/driver.html?testName=audio-fft) 19:44:10 INFO - PROCESS | 13402 | RSS: Main: 266780672 19:44:10 INFO - PROCESS | 13402 | 19:44:12 INFO - PROCESS | 13402 | Cycle 1(1): loaded http://localhost:52072/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52072/tests/kraken/driver.html?testName=audio-oscillator) 19:44:13 INFO - PROCESS | 13402 | RSS: Main: 178335744 19:44:13 INFO - PROCESS | 13402 | 19:44:15 INFO - PROCESS | 13402 | Cycle 1(1): loaded http://localhost:52072/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52072/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:44:16 INFO - PROCESS | 13402 | RSS: Main: 181526528 19:44:16 INFO - PROCESS | 13402 | 19:44:19 INFO - PROCESS | 13402 | Cycle 1(1): loaded http://localhost:52072/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52072/tests/kraken/driver.html?testName=imaging-darkroom) 19:44:19 INFO - PROCESS | 13402 | RSS: Main: 220487680 19:44:19 INFO - PROCESS | 13402 | 19:44:23 INFO - PROCESS | 13402 | Cycle 1(1): loaded http://localhost:52072/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52072/tests/kraken/driver.html?testName=imaging-desaturate) 19:44:23 INFO - PROCESS | 13402 | RSS: Main: 215552000 19:44:23 INFO - PROCESS | 13402 | 19:44:26 INFO - PROCESS | 13402 | Cycle 1(1): loaded http://localhost:52072/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52072/tests/kraken/driver.html?testName=json-parse-financial) 19:44:27 INFO - PROCESS | 13402 | RSS: Main: 217169920 19:44:27 INFO - PROCESS | 13402 | 19:44:29 INFO - PROCESS | 13402 | Cycle 1(1): loaded http://localhost:52072/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52072/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:44:29 INFO - PROCESS | 13402 | RSS: Main: 180297728 19:44:29 INFO - PROCESS | 13402 | 19:44:31 INFO - PROCESS | 13402 | Cycle 1(1): loaded http://localhost:52072/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52072/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:44:32 INFO - PROCESS | 13402 | RSS: Main: 188116992 19:44:32 INFO - PROCESS | 13402 | 19:44:34 INFO - PROCESS | 13402 | Cycle 1(1): loaded http://localhost:52072/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52072/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:44:35 INFO - PROCESS | 13402 | RSS: Main: 196452352 19:44:35 INFO - PROCESS | 13402 | 19:44:37 INFO - PROCESS | 13402 | Cycle 1(1): loaded http://localhost:52072/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52072/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:44:38 INFO - PROCESS | 13402 | RSS: Main: 206475264 19:44:38 INFO - PROCESS | 13402 | 19:44:41 INFO - PROCESS | 13402 | Cycle 1(1): loaded http://localhost:52072/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52072/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:44:42 INFO - PROCESS | 13402 | RSS: Main: 202416128 19:44:42 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | Cycle 1(1): loaded http://localhost:52072/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52072/tests/kraken/driver.html?testName=ai-astar) 19:44:44 INFO - PROCESS | 13402 | RSS: Main: 253501440 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | __start_tp_report 19:44:44 INFO - PROCESS | 13402 | _x_x_mozilla_page_load 19:44:44 INFO - PROCESS | 13402 | _x_x_mozilla_page_load_details 19:44:44 INFO - PROCESS | 13402 | |i|pagename|runs| 19:44:44 INFO - PROCESS | 13402 | |0;ai-astar;106;114;114;115;115;114;112;117;114;113 19:44:44 INFO - PROCESS | 13402 | |1;audio-beat-detection;156;136;130;175;126;128;133;126;149;132 19:44:44 INFO - PROCESS | 13402 | |2;audio-dft;162;159;161;163;165;163;161;162;160;161 19:44:44 INFO - PROCESS | 13402 | |3;audio-fft;80;77;76;116;76;76;76;75;92;75 19:44:44 INFO - PROCESS | 13402 | |4;audio-oscillator;85;85;110;83;84;84;104;84;86;114 19:44:44 INFO - PROCESS | 13402 | |5;imaging-gaussian-blur;117;117;117;117;118;118;119;118;117;117 19:44:44 INFO - PROCESS | 13402 | |6;imaging-darkroom;151;151;152;152;151;152;152;151;153;149 19:44:44 INFO - PROCESS | 13402 | |7;imaging-desaturate;95;95;95;95;95;95;95;96;96;96 19:44:44 INFO - PROCESS | 13402 | |8;json-parse-financial;61;59;60;59;66;61;61;61;61;60 19:44:44 INFO - PROCESS | 13402 | |9;json-stringify-tinderbox;67;68;67;66;66;67;67;68;67;68 19:44:44 INFO - PROCESS | 13402 | |10;stanford-crypto-aes;85;84;84;84;84;83;84;83;84;83 19:44:44 INFO - PROCESS | 13402 | |11;stanford-crypto-ccm;143;142;141;133;132;141;131;142;189;140 19:44:44 INFO - PROCESS | 13402 | |12;stanford-crypto-pbkdf2;179;174;179;175;177;173;172;175;178;173 19:44:44 INFO - PROCESS | 13402 | |13;stanford-crypto-sha256-iterative;67;71;67;67;67;70;66;67;65;69 19:44:44 INFO - PROCESS | 13402 | __end_tp_report 19:44:44 INFO - PROCESS | 13402 | __start_cc_report 19:44:44 INFO - PROCESS | 13402 | _x_x_mozilla_cycle_collect,1865 19:44:44 INFO - PROCESS | 13402 | __end_cc_report 19:44:44 INFO - PROCESS | 13402 | __startTimestamp1482464684334__endTimestamp 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | ------- Summary: start ------- 19:44:44 INFO - PROCESS | 13402 | Number of tests: 14 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:2.91 (2.6%) stddev-sans-first:1.39 19:44:44 INFO - PROCESS | 13402 | Values: 106.0 114.0 114.0 115.0 115.0 114.0 112.0 117.0 114.0 113.0 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:134.50 stddev:16.05 (11.9%) stddev-sans-first:15.82 19:44:44 INFO - PROCESS | 13402 | Values: 156.0 136.0 130.0 175.0 126.0 128.0 133.0 126.0 149.0 132.0 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.70 (1.1%) stddev-sans-first:1.80 19:44:44 INFO - PROCESS | 13402 | Values: 162.0 159.0 161.0 163.0 165.0 163.0 161.0 162.0 160.0 161.0 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | [#3] audio-fft Cycles:10 Average:81.90 Median:76.00 stddev:13.03 (17.1%) stddev-sans-first:13.80 19:44:44 INFO - PROCESS | 13402 | Values: 80.0 77.0 76.0 116.0 76.0 76.0 76.0 75.0 92.0 75.0 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:84.00 stddev:12.29 (14.6%) stddev-sans-first:12.78 19:44:44 INFO - PROCESS | 13402 | Values: 85.0 85.0 110.0 83.0 84.0 84.0 104.0 84.0 86.0 114.0 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:117.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 19:44:44 INFO - PROCESS | 13402 | Values: 117.0 117.0 117.0 117.0 118.0 118.0 119.0 118.0 117.0 117.0 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | [#6] imaging-darkroom Cycles:10 Average:151.40 Median:152.00 stddev:1.07 (0.7%) stddev-sans-first:1.13 19:44:44 INFO - PROCESS | 13402 | Values: 151.0 151.0 152.0 152.0 151.0 152.0 152.0 151.0 153.0 149.0 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 19:44:44 INFO - PROCESS | 13402 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | [#8] json-parse-financial Cycles:10 Average:60.90 Median:61.00 stddev:1.97 (3.2%) stddev-sans-first:2.09 19:44:44 INFO - PROCESS | 13402 | Values: 61.0 59.0 60.0 59.0 66.0 61.0 61.0 61.0 61.0 60.0 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | [#9] json-stringify-tinderbox Cycles:10 Average:67.10 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.78 19:44:44 INFO - PROCESS | 13402 | Values: 67.0 68.0 67.0 66.0 66.0 67.0 67.0 68.0 67.0 68.0 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.00 stddev:0.63 (0.8%) stddev-sans-first:0.50 19:44:44 INFO - PROCESS | 13402 | Values: 85.0 84.0 84.0 84.0 84.0 83.0 84.0 83.0 84.0 83.0 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | [#11] stanford-crypto-ccm Cycles:10 Average:143.40 Median:141.50 stddev:16.66 (11.8%) stddev-sans-first:17.67 19:44:44 INFO - PROCESS | 13402 | Values: 143.0 142.0 141.0 133.0 132.0 141.0 131.0 142.0 189.0 140.0 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.50 Median:176.00 stddev:2.59 (1.5%) stddev-sans-first:2.42 19:44:44 INFO - PROCESS | 13402 | Values: 179.0 174.0 179.0 175.0 177.0 173.0 172.0 175.0 178.0 173.0 19:44:44 INFO - PROCESS | 13402 | 19:44:44 INFO - PROCESS | 13402 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.00 stddev:1.84 (2.7%) stddev-sans-first:1.94 19:44:44 INFO - PROCESS | 13402 | Values: 67.0 71.0 67.0 67.0 67.0 70.0 66.0 67.0 65.0 69.0 19:44:44 INFO - PROCESS | 13402 | -------- Summary: end -------- 19:44:44 INFO - PROCESS | 13402 | 19:44:45 INFO - TEST-INFO | 13402: exit 0 19:44:45 INFO - TEST-OK | kraken | took 63430ms 19:44:45 INFO - SUITE-END | took 1184s 19:44:45 INFO - Completed test suite (00:19:44) 19:44:45 INFO - javascript benchmark 19:44:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2575.639410482532, 2597.7012643546, 2610.3474131467133, 2629.8290611110288, 2634.025410598078, 4620.5148459411785, 4693.037927224919, 4696.319033740114, 4698.577128058479, 4699.0836786826585, 4658.045100754346, 4701.553352431522, 4729.408823897013, 4752.738599377033, 4785.54537318955, 4328.06946506501, 4337.652987760987, 4386.7367957922625, 4419.955800441987, 4495.483019452772, 4036.555978842325, 4358.716683415568, 4452.332150177467, 4488.124815660947, 4542.818287268522, 4251.06476575153, 4383.780810959471, 4387.056782791715, 4449.243628583149, 4450.354698568705, 4272.530021697611, 4308.870733877989, 4355.346697995326, 4385.978070109629, 4403.823847046115, 4385.912281754379, 4395.428594282755, 4404.207242696313, 4434.512203657628, 4448.132614140244, 4741.407324084489, 4759.714417134983, 4759.928601070987, 4770.856874293777, 4774.7135171889795, 4917.114919314522, 4929.556339929423, 4951.455339912607, 4972.154733695281, 5079.974600127012, 5009.398872135331, 5012.548870601627, 5014.949850501521, 5045.747712614355, 5055.848324550269, 4684.273937539687, 4691.859244222679, 4694.765261736899, 4697.741624210701, 4734.171519984024, 5019.799208031711, 5086.872828179251, 5108.897822043613, 5150.407703114115, 5151.124308867502, 4969.975150124226, 4996.3504744383, 5000.324956130876, 5043.470435604282, 5051.419086804991, 4925.21196608541, 4972.801087956514, 5084.491550844886, 5089.389273287155, 5143.202803565453, 4903.5586797188425, 4906.779194936236, 4949.0, 5000.199968005102, 5020.196768517019, 4847.660663753503, 4912.140375434285, 4927.162382395001, 4959.876003099951, 4974.054929563371, 4150.958490415057, 4171.6036976487185, 4172.311568591209, 4184.14225083857, 4226.6618670506905, 3630.6550877666577, 3642.053066202753, 3642.0712718257205, 3665.2303016366327, 3672.081979505124, 4264.019275566637, 4382.189294980439, 4385.802638881258, 4386.605205531518, 4429.49060857999, 4903.362562866804, 4983.327250821093, 5031.421386540522, 5044.974775126101, 5097.668651537638, 4364.519902810688, 4387.14450682114, 4388.4733831940375, 4396.230659634552, 4433.423654924839, 4480.32795080734, 4484.035932274576, 4484.910301793946, 4486.102779444124, 4525.434320709911, 4574.428196475441, 4617.422822147231, 4637.698549594265, 4646.605038571759, 4658.720476771405, 4947.0353281111275, 4950.381202349707, 4957.107720610325, 4963.230699241623, 4976.975115124401, 4665.5567721066445, 4669.976650116728, 4672.76636168197, 4707.19977603815, 4713.62291016711, 4792.520747925179, 4842.982973575588, 4868.537488938545, 4869.147899117711, 4900.019996000743, 4988.600911926965, 5002.749862506933, 5007.073691366963, 5032.094223039888, 5055.266986287042, 4772.546608072227, 4885.1206782779445, 4898.510148985216, 4900.90198196027, 4945.826896058621, 4798.1123492154065, 4799.928001080057, 4835.516448355136, 4852.660313778143, 4853.369062022056, 4783.856484305476, 4783.928241076317, 4795.712257264575, 4843.0313937211995, 4843.95156048435, 4880.487548807324, 4883.877903052452, 4886.877828054327, 4889.559939605452, 4927.162382395073, 4704.388429504143, 4725.692829966179, 4737.881552961204, 4866.878328041686, 4900.068986892479], "value": 4601.999272040701, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.398389819504, 545.7816873250699, 557.1447827584658, 560.6608002158694, 573.2547688005592, 31962.200944976386, 38659.80670096649, 40438.79780601097, 40466.0, 44413.77793111038, 40419.191616167656, 40793.18413631733, 40858.0, 40901.18197636046, 40968.0, 7284.672189751461, 7444.292792184761, 7445.329920307171, 7451.254874512532, 7473.738419155331, 7321.487495875291, 9492.335536512446, 9662.806743865118, 9664.081912218327, 10016.248781341392, 9668.274879384038, 9680.935097139345, 9723.367981081243, 9729.173020293283, 9730.464824434654, 9906.950465247664, 10007.049330313608, 10008.449535275557, 10084.14284785794, 10119.139873110795, 9956.900430995742, 10013.649522266685, 10024.19806415485, 10032.046955539212, 10044.648437304731, 38033.23933521314, 38218.0, 38291.23417531662, 38341.61658383408, 38404.61595384038, 2964.970350296491, 3073.431415188197, 3132.232603012254, 3283.868645254187, 3413.7269018478464, 28921.276968075967, 28942.421151577062, 29027.274318142216, 29086.563701544495, 29827.0, 3105.6117985251844, 3129.217695576106, 3135.6237251529733, 3164.4145833020966, 3181.7136457718684, 245491.0, 245495.0, 246552.0, 246594.0, 246693.0, 52779.47220527822, 53242.0, 53270.200946985824, 53305.733471332394, 53327.46672533225, 55692.0, 55709.442905570424, 55977.16034259489, 55980.0, 56006.43993560094, 14462.27688615565, 14584.927075364556, 14836.480723174853, 14929.477468288558, 14956.177410242324, 9956.651517196864, 10157.492125393701, 11093.33439993603, 11268.943655281835, 11312.20814542974, 16215.675686486204, 16591.336346546002, 16603.41888033913, 16613.41853035138, 16618.75071873923, 6105.603135796159, 6128.63228206309, 6174.320824709278, 6239.532035097295, 6251.906221406683, 780.2938340801584, 832.4381042796089, 903.1465265324337, 994.358638678063, 1001.1890368801148, 26604.733952660226, 27722.5841612376, 28012.299692507444, 28433.28916777056, 28691.0, 939.2861425316672, 965.4931161140457, 977.1498796047484, 989.8911119776819, 998.8451789972565, 14814.629634259014, 14838.777418338734, 14860.62848428798, 14883.925580372246, 15143.545693629208, 359256.0, 361077.0, 362641.0, 365401.0, 366579.0, 50447.74776126096, 50529.747351263744, 50588.747056265216, 50668.0, 50718.74640626773, 1209.52223871572, 1228.3858070964518, 1243.1670780577215, 1246.7132559511545, 1253.4422182129047, 23944.429041074076, 24255.0, 25025.374365640277, 25127.371815704755, 25336.74663253344, 43667.78166109149, 44178.0, 44233.11533769371, 44244.0, 44374.778126109166, 251297.0, 252457.0, 252553.0, 252908.0, 253069.0, 34432.0, 35495.0, 36176.45735314025, 37089.44365834461, 37270.44094338641, 41899.7905010473, 41925.371119432624, 42137.789311053246, 42738.78630606827, 43388.34917476177, 35290.82354588211, 35547.0, 35621.28757424785, 35813.46279805857, 36219.45670814887, 35511.0, 35644.28711425705, 35707.0, 36101.81949090238, 36258.0, 34193.31613367768, 34929.0, 35142.648573513936, 35226.47160292649, 35776.0, 30827.383452331276, 32650.83674581707, 32883.34233315368, 33004.83497582497, 33246.0, 23059.077636894337, 23918.760812391654, 24093.879530602233, 24139.637905431082, 25581.872090639426], "value": 17766.30679104289, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.4526935942883, 378.2397381263661, 379.79870668545664, 395.7348576453776, 397.7553804410289, 15869.603259918507, 16704.749428758547, 17222.56943576411, 17310.221040053195, 17489.82510174898, 15998.440054598092, 16074.276657550408, 16187.35250589975, 16194.0, 16392.09843458612, 4538.659600529965, 4743.075100355432, 4746.738929358884, 4752.572268495835, 4753.120672675549, 3610.368185567515, 3857.691384689232, 3893.7274390792654, 4698.929516057245, 4753.382060332152, 5872.412758724115, 5880.794172203974, 5974.4324289192455, 5979.551533634973, 5981.730822112993, 5483.424240454775, 5536.058869992071, 5701.401352857932, 5979.043353063511, 5984.162217289583, 5358.490943360374, 5363.115086010804, 5367.731613419313, 5369.0, 5374.301340825707, 12290.631281061582, 12322.199057061262, 12326.137170398075, 12400.255984640951, 12409.503619855195, 1814.355903654197, 1851.0, 1933.1783991803568, 2106.231225602644, 2341.2156927429332, 9004.954975225148, 9005.549722513848, 9120.0, 9423.528823558863, 9580.13778759908, 1679.202378870027, 1731.9567010824703, 1746.0309528211837, 1753.815849335827, 1768.5755418699418, 65956.0, 66528.0, 67070.0, 67098.3290167092, 68449.3155068448, 9064.320175986828, 9072.54637268134, 9147.908520914705, 9374.484403357745, 9398.859017114748, 12362.75274494505, 12386.81419778704, 12391.132620716644, 12397.62807115788, 12417.813732794015, 6966.0, 7050.4007159391, 7227.0, 7298.708051677873, 7321.487495875344, 6890.793276201722, 6943.6875340609795, 6988.650567471708, 7132.786016419515, 7145.1425828900865, 3825.3879379299733, 3862.748921320105, 3866.188100498927, 3917.3536366499206, 3955.4660120883314, 1658.7428948512759, 1741.4688520001268, 1759.3138675916402, 1861.2927087706855, 1882.905854707259, 716.8888822232458, 783.0916137280841, 866.9653213871375, 876.8334016536837, 881.3301890563218, 15474.381024758884, 15640.92179539095, 15698.447450239302, 16087.115208663401, 16745.0, 838.0990435281974, 845.9323254139654, 850.4259624753266, 851.8040850604395, 882.1222883231097, 5728.541716662657, 9476.0, 9587.089226523469, 9610.279229057849, 9842.852357214648, 350909.0, 352435.0, 355086.0, 356645.0, 360217.0, 53203.73398133062, 56215.43784562184, 69468.84295248412, 73579.63210183915, 74711.0, 910.4764760262875, 923.1230331185401, 931.0596297739332, 932.6595792535676, 936.3773090894479, 14532.491362802251, 14724.852751472348, 15107.54677359681, 15145.848541514444, 15375.461858834888, 34679.65320346764, 35641.82179089088, 36411.27177456383, 36502.634973649925, 36684.81657591695, 46546.018097285676, 47008.52991470179, 47275.76362118167, 47471.28793068037, 47763.88014037553, 5896.0, 6026.638401695912, 6977.790666279817, 7004.124484439445, 7236.312550307713, 9665.516724163905, 10021.0, 10131.189504839447, 10491.79016419652, 10590.735231619272, 8756.0, 8779.517126558101, 9024.0, 9097.226735727538, 9150.267978561566, 9584.712458626253, 9849.0, 9962.651307204213, 10039.4478303694, 10217.591296348062, 9579.377340472847, 9625.56684949165, 9669.9033009669, 10143.543540540546, 10411.0, 7179.138503379628, 7567.432442566719, 7604.42966777505, 7711.691532338644, 7898.249666281694, 9146.496942668216, 9239.538023098683, 9366.344355895022, 9398.248140148908, 9561.426314420878], "value": 7725.358617474312, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.3581049001723, 1373.6222538801828, 1488.1591900576173, 1698.2273065755082, 1745.397837745978, 7173.533720308183, 7597.962010189955, 7612.69549218032, 7613.2386761323905, 7615.428842836789, 6698.3971442570155, 6952.756653517128, 7215.675294611742, 7415.443841711866, 7440.0, 2123.4266747978036, 2163.3077415227135, 2163.7511686156136, 2164.8701077935375, 2167.6315026445463, 2142.1324363632752, 2172.478605134764, 2173.847830651855, 2180.869147851134, 2181.9781802182015, 2142.2180903970093, 2146.087912637123, 2152.7416709994823, 2182.5962196993605, 2183.4213933307688, 2083.322920050982, 2105.6630939049833, 2110.757262914769, 2111.651577489705, 2112.3134981131193, 2125.9574808503744, 2152.483403983033, 2152.720146380976, 2155.0, 2156.33153722345, 7414.369778568775, 7442.590657513834, 7493.0, 7509.887351689729, 7542.849143017163, 4232.703710740249, 4343.370211319372, 4344.847930322424, 4347.500037495697, 4357.281048626973, 2794.259521226856, 2906.070057581575, 2941.352902361477, 2958.76329893608, 2960.718731720483, 804.927556519916, 847.254416113822, 849.2144766091402, 853.3216093205916, 1745.9301627934867, 9806.9509652452, 9928.652497162564, 9934.25493088014, 9934.354266972696, 9936.354136981074, 3854.2676891390547, 4002.519697636302, 4033.556308806029, 4058.7564746115327, 4166.854160104382, 8043.075046369742, 8046.235607617267, 8050.11448740638, 8052.5571093590415, 8058.556779377073, 4630.120277147331, 4631.698939568917, 4634.83778067766, 4638.049199914077, 4638.095571363552, 3748.493953316322, 3850.7882066486613, 4130.380442933524, 4314.633256173261, 4319.97840010798, 3258.6904244096772, 3264.281857991238, 3339.4656854903583, 3345.1469875181674, 3377.324535092991, 1060.4590371705226, 1078.8489611454402, 1079.4063265204106, 1084.4848696868924, 1099.4392859641641, 695.4193248637343, 784.5488843914771, 790.6481615680967, 806.1817255485687, 820.1716266570807, 4684.531546845288, 4839.540243676845, 4888.584470320063, 4919.729414882144, 5181.4041385240425, 388.2138669194903, 396.43310066604454, 398.44218094667116, 399.37497815917993, 404.9473568436084, 3200.8079515228696, 3344.3144155448076, 3355.094124586348, 3400.6599340065795, 3462.307538492312, 8059.758207253792, 8295.294899933573, 8418.200270974248, 8464.238218560358, 8465.365097617703, 8422.326213902874, 8426.452280601741, 8430.988281406271, 8435.156484351515, 8442.831143377098, 6743.966280168666, 6974.849149890313, 7015.824604384829, 7017.087778588853, 7026.92973070273, 4971.105201063843, 4987.202047672355, 4994.625403094855, 5012.223105418666, 5076.111680455831, 8113.553754543556, 8204.138565450781, 8269.421140520106, 8272.83454330898, 8348.874766878624, 8364.247217750431, 8473.66105355779, 8531.530765807693, 8548.059713431394, 8681.348898832739, 5581.413951535193, 6105.8168254952425, 6206.534509911684, 6217.720202590804, 6219.906701399392, 6606.735730570724, 6787.287334829969, 6790.728370865308, 6921.307869213038, 7192.820179495345, 6389.329120442287, 6527.281998980014, 6669.133012708511, 6720.966395167993, 6774.525783195129, 6220.688965551795, 6314.589551679126, 6373.7131829066875, 6383.265924418752, 6413.518986075969, 5881.5588830837005, 5970.164177015135, 5990.071538911476, 5994.790182343597, 5997.100434934795, 4280.592330688192, 4326.762028088486, 4364.323529852878, 4463.018136010072, 4480.700596826884, 4305.289627211475, 4347.891302717458, 4352.869413917588, 4373.14723628902, 4398.846040388571], "value": 3865.1751958877326, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.56233771421734, 337.3590178660545, 346.26937162586944, 347.30365616938036, 349.7831344566368, 4774.188387974044, 4807.495213002632, 4842.830500932464, 4850.708957462554, 4859.659823812334, 4779.187538118519, 4789.353437285965, 4802.399700037496, 4818.253170758538, 4819.807207711688, 3672.1186915140374, 3720.2373513429816, 3740.6072362401965, 3758.5489741230954, 3846.557645870733, 3811.9999999999864, 3827.54069511659, 3827.6363745444137, 3829.5787463379006, 3865.188310454808, 3797.810109494528, 3799.601041890603, 3804.391297392418, 3805.771653700787, 3814.7030009796663, 3609.314230296235, 3718.3307004739136, 3728.3102626014274, 3792.867249646277, 3842.154725960312, 3388.5086662434055, 3418.265073009315, 3418.4872269159823, 3427.5946861786547, 3434.3303055904107, 4661.487236403993, 4672.57946784791, 4674.789634466457, 4680.204365257914, 4683.103343146031, 3964.226975739732, 4101.8974525636795, 4335.328024156261, 4354.716943398669, 4365.7817109144735, 4945.950540494585, 4947.579455746266, 4955.4548999610015, 4956.529129732669, 4959.628027897886, 3472.75690701651, 3477.0, 3495.4582039783872, 3497.6327485613897, 3501.2647344057777, 5068.366454193226, 5234.581233501349, 5256.9737151314, 5260.764175225514, 5270.578353731692, 3439.208981934169, 3476.348734351163, 3480.686738193524, 3491.755577109578, 3496.2483066140394, 3519.331327047853, 3524.4360902255517, 3524.788512689247, 3531.682148606638, 3539.1152211947015, 4070.3487442009136, 4078.653314468244, 4091.468109145792, 4093.9385909211387, 4112.115895082512, 4756.952430475721, 4962.950370496321, 4989.101961646866, 5022.89954200914, 5028.572571331405, 1201.387292480841, 1210.4250481021445, 1212.5089338817793, 1222.7860124478182, 1223.247702662856, 794.7337641890032, 827.1810907201827, 832.2925513313756, 836.1387770596295, 838.262329150356, 4278.679099067583, 4332.176886391576, 4338.783060846945, 4361.498427680857, 4456.0, 5170.6380553360905, 5174.663646862942, 5175.120229560908, 5186.662866913639, 5192.494176688718, 4476.843310484118, 4478.932816007762, 4498.5501449854755, 4504.301833215857, 4521.932171017437, 2562.2441379793004, 2656.4194304388857, 2671.2119924622284, 2692.8017032420776, 2694.811363204557, 4781.306710526954, 4790.976045119752, 4792.2332426811545, 4800.711957282574, 4801.327814106028, 5188.7806365504075, 5204.594759415012, 5205.401378841406, 5210.192420174879, 5232.9738351308, 4622.583967442946, 4799.928001079987, 4864.975675121602, 4906.8773280668265, 4911.87720306988, 5186.688798672092, 5188.250227379495, 5189.860134618652, 5192.740362981911, 5201.583873290203, 5127.743612819419, 5132.255822905732, 5133.0, 5136.743162841918, 5147.253648221062, 4718.678769944421, 4826.8793280168275, 4876.244182151772, 4896.853094407174, 4904.8663831233525, 3541.1678255610427, 3567.3935430976276, 3578.7137029038136, 3597.8380972856808, 3603.765755225902, 5161.406438259648, 5174.7930082796265, 5177.585793136615, 5195.740212989411, 5208.24480450325, 4813.711177329372, 5036.29491871131, 5053.974730126326, 5055.115354812967, 5056.469070747666, 5014.423341315795, 5017.222330538772, 5022.518357084742, 5026.623003274696, 5050.292958985819, 4998.725070121178, 5000.924801167766, 5009.373828271466, 5010.574101201293, 5013.147764880052, 4862.635302352267, 4934.18585933317, 5007.846625950876, 5072.949270507248, 5117.795288188431, 4850.563449289581, 4943.604511638989, 4952.851414457428, 4962.6774259673, 4971.229459433794], "value": 3776.2049725898187, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [649.058864646263, 662.3773652766396, 676.2054585861613, 704.2570088556571, 743.1231147246248, 12122.575709850156, 12715.23708577486, 12736.872631273685, 12741.0, 12744.55394061208, 13395.263260520667, 13451.93274033631, 13469.124506907045, 13469.259190744506, 13584.252866092362, 675.9864802703943, 695.6695569604448, 811.0429692962302, 812.208097105322, 827.2224109337234, 796.398718967179, 796.5618909599697, 797.7407342613644, 797.8563858505466, 798.0582912163644, 888.7111688701166, 890.0965519997208, 890.3366991591241, 890.5636238243279, 890.6749036601659, 705.4955706669756, 708.5429897715995, 722.9674664640052, 723.6273319240595, 883.7127933421664, 529.0133900275996, 626.8432891777056, 628.7767842415922, 637.7385272038442, 642.0882347067129, 13316.0, 13706.52027179054, 13728.862711372958, 13762.0, 13765.587032389045, 12219.144659873813, 12269.202501837352, 12526.373681315898, 12534.373281335897, 12665.620031399072, 11740.354280514568, 11803.173777835555, 11806.67663178874, 12045.096617753703, 12058.336791476466, 3849.30712471755, 3851.9229615407817, 3896.065271485366, 3897.279003384355, 3910.1397692507885, 62949.37050629481, 63723.681381592796, 64336.678316608115, 64628.0, 65000.3499964999, 9766.46284454362, 9825.803483930282, 9888.060634239735, 10499.160067194605, 10805.351678899291, 18733.31400173991, 20026.89986550087, 20043.799562004486, 20054.0, 20123.295684650966, 6399.0, 6605.801825945229, 6616.139901812733, 6719.932800671931, 6814.693338799766, 3372.325534892982, 3403.7447191460737, 3403.7787543809573, 3460.480927860791, 3536.5225694531423, 2898.753605943476, 2955.497565413885, 2972.583838262645, 2981.701829817001, 2995.1613548206537, 478.301679547864, 479.0873386199296, 481.55215649446353, 489.63277541843615, 491.97540122993706, 480.62991496547465, 544.5072209650273, 546.0416968220748, 571.8227349521661, 580.7851095094842, 7404.629768511553, 7510.319636511281, 7610.0, 7616.0, 7688.154303026663, 568.2896379525592, 572.2617823008355, 576.052393812185, 581.6655423131716, 582.5165112956236, 3675.8162091895297, 3758.680512156497, 3841.8079096045085, 3855.8072096395067, 3899.5710471848074, 66452.0, 66774.0, 68013.31986680168, 68115.0, 68598.3140168602, 26426.47147057048, 43841.0, 56680.71659641675, 58447.70776146092, 58804.41195588075, 40028.799856000536, 41740.58259417428, 41801.79099104485, 48982.510174899224, 50005.24992125121, 8178.591070446335, 8477.824827278951, 8693.521856297964, 8772.824543508967, 9037.68368107113, 33910.66089339174, 34211.4868276981, 34335.65664343325, 35507.0, 36315.27369452649, 41236.0, 42038.57961420469, 44731.32903006393, 46222.306665399374, 47794.28308575444, 11561.479733412185, 11613.883861161281, 11660.475278612314, 11709.590164344207, 11914.76170476603, 12829.294388808705, 12949.805752913902, 12979.675508112372, 13058.869411305765, 13112.213267203999, 11524.769504609692, 11614.767704646029, 11844.407779610812, 12263.632091037283, 13158.671033224246, 10978.725531861768, 11406.885931140583, 12289.051065820875, 12613.369331533204, 12991.870081299066, 11328.830067548828, 12132.93933530362, 12206.633800985985, 12473.688157796129, 12862.67843303925, 9728.416295022322, 10323.225758068025, 10360.481975901024, 10539.683809485728, 10654.73363165927, 10072.01420335518, 10668.573257069631, 10777.0, 10847.67456976292, 10853.891461085605], "value": 6272.464223109423, "unit": "score"}], "name": "dromaeo_css", "value": 6218.652866467177, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 114.0, 114.0, 115.0, 115.0, 114.0, 112.0, 117.0, 114.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 136.0, 130.0, 175.0, 126.0, 128.0, 133.0, 126.0, 149.0, 132.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 161.0, 163.0, 165.0, 163.0, 161.0, 162.0, 160.0, 161.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 77.0, 76.0, 116.0, 76.0, 76.0, 76.0, 75.0, 92.0, 75.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 110.0, 83.0, 84.0, 84.0, 104.0, 84.0, 86.0, 114.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 119.0, 118.0, 117.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 152.0, 152.0, 151.0, 152.0, 152.0, 151.0, 153.0, 149.0], "value": 151.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 60.0, 59.0, 66.0, 61.0, 61.0, 61.0, 61.0, 60.0], "value": 60.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 66.0, 66.0, 67.0, 67.0, 68.0, 67.0, 68.0], "value": 67.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 84.0, 84.0, 83.0, 84.0, 83.0, 84.0, 83.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 142.0, 141.0, 133.0, 132.0, 141.0, 131.0, 142.0, 189.0, 140.0], "value": 143.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 174.0, 179.0, 175.0, 177.0, 173.0, 172.0, 175.0, 178.0, 173.0], "value": 175.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 67.0, 67.0, 67.0, 70.0, 66.0, 67.0, 65.0, 69.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1550.4999999999998, "alertThreshold": 2.0}]} 19:44:46 INFO - Return code: 0 19:44:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:44:46 INFO - # TBPL SUCCESS # 19:44:46 INFO - Running post-action listener: _resource_record_post_action 19:44:46 INFO - [mozharness: 2016-12-23 03:44:46.117227Z] Finished run-tests step (success) 19:44:46 INFO - Running post-run listener: _resource_record_post_run 19:44:46 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 77824; Write bytes: 393625600; Read time: 36; Write time: 285204 19:44:46 INFO - TinderboxPrint: CPU usage
13.1% 19:44:46 INFO - TinderboxPrint: I/O read bytes / time
77,824 / 36 19:44:46 INFO - TinderboxPrint: I/O write bytes / time
393,625,600 / 285,204 19:44:46 INFO - TinderboxPrint: CPU idle
8,333.2 (86.9%) 19:44:46 INFO - TinderboxPrint: CPU user
1,208.5 (12.6%) 19:44:46 INFO - TinderboxPrint: Swap in / out
0 / 0 19:44:46 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110571520; Read time: 0; Write time: 64808 19:44:46 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 73728; Write bytes: 282509312; Read time: 20; Write time: 220368 19:44:46 INFO - Running post-run listener: _upload_blobber_files 19:44:46 INFO - Blob upload gear active. 19:44:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:44:46 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 19:44:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:44:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:44:47 INFO - (blobuploader) - INFO - Open directory for files ... 19:44:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:44:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:44:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:44:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:44:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:44:50 INFO - (blobuploader) - INFO - Done attempting. 19:44:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:44:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:44:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:44:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:44:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:44:51 INFO - (blobuploader) - INFO - Done attempting. 19:44:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:44:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:44:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:44:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:44:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:44:53 INFO - (blobuploader) - INFO - Done attempting. 19:44:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:44:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:44:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:44:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:44:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:44:54 INFO - (blobuploader) - INFO - Done attempting. 19:44:54 INFO - (blobuploader) - INFO - Iteration through files over. 19:44:54 INFO - Return code: 0 19:44:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:44:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:44:54 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5f3f7ef254fedb645ebf71e88d87d2caf2d71cb74e79d08496d42aaa0e08312455e205ab76415543a50321722775de764e00b2a1c107349ef8e77e93099e2ce7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/96588295cc16d53ec9581f953eab10b5bc583cf03fd596ceb2bcfb843b184034c0c3765d146f319f00fe962287ca8aa4377afce5f0879a3b73d050fe59f38f92", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7735e5251153fcd9a2738f919f4582e04352636166f4078518d2296083c43101ec6867a66a5dc39828668cf8853f6d61018a7c664ef35f002a4a142a2a60e82", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3233f438c7b49fef77b9be582a85f5631699daf7280c15ab2171a44c98ee451cd08c2d26857428b6b7aaa54ce7440f771ff9e8a0c7787aeda28f2bf1ce429191"} 19:44:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:44:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:44:54 INFO - Contents: 19:44:54 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5f3f7ef254fedb645ebf71e88d87d2caf2d71cb74e79d08496d42aaa0e08312455e205ab76415543a50321722775de764e00b2a1c107349ef8e77e93099e2ce7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/96588295cc16d53ec9581f953eab10b5bc583cf03fd596ceb2bcfb843b184034c0c3765d146f319f00fe962287ca8aa4377afce5f0879a3b73d050fe59f38f92", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7735e5251153fcd9a2738f919f4582e04352636166f4078518d2296083c43101ec6867a66a5dc39828668cf8853f6d61018a7c664ef35f002a4a142a2a60e82", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3233f438c7b49fef77b9be582a85f5631699daf7280c15ab2171a44c98ee451cd08c2d26857428b6b7aaa54ce7440f771ff9e8a0c7787aeda28f2bf1ce429191"} 19:44:54 INFO - Running post-run listener: copy_logs_to_upload_dir 19:44:54 INFO - Copying logs to upload dir... 19:44:54 INFO - mkdir: /builds/slave/test/build/upload/logs 19:44:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.846068 ========= master_lag: 0.24 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-12-22 19:44:54.155544) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 19:44:54.160805) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5f3f7ef254fedb645ebf71e88d87d2caf2d71cb74e79d08496d42aaa0e08312455e205ab76415543a50321722775de764e00b2a1c107349ef8e77e93099e2ce7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/96588295cc16d53ec9581f953eab10b5bc583cf03fd596ceb2bcfb843b184034c0c3765d146f319f00fe962287ca8aa4377afce5f0879a3b73d050fe59f38f92", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7735e5251153fcd9a2738f919f4582e04352636166f4078518d2296083c43101ec6867a66a5dc39828668cf8853f6d61018a7c664ef35f002a4a142a2a60e82", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3233f438c7b49fef77b9be582a85f5631699daf7280c15ab2171a44c98ee451cd08c2d26857428b6b7aaa54ce7440f771ff9e8a0c7787aeda28f2bf1ce429191"} build_url:https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011760 build_url: 'https://queue.taskcluster.net/v1/task/KGZCcvIuSgW3n5hv2REF3Q/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5f3f7ef254fedb645ebf71e88d87d2caf2d71cb74e79d08496d42aaa0e08312455e205ab76415543a50321722775de764e00b2a1c107349ef8e77e93099e2ce7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/96588295cc16d53ec9581f953eab10b5bc583cf03fd596ceb2bcfb843b184034c0c3765d146f319f00fe962287ca8aa4377afce5f0879a3b73d050fe59f38f92", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d7735e5251153fcd9a2738f919f4582e04352636166f4078518d2296083c43101ec6867a66a5dc39828668cf8853f6d61018a7c664ef35f002a4a142a2a60e82", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3233f438c7b49fef77b9be582a85f5631699daf7280c15ab2171a44c98ee451cd08c2d26857428b6b7aaa54ce7440f771ff9e8a0c7787aeda28f2bf1ce429191"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 19:44:54.188951) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 19:44:54.189342) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1482459308.755764-38264816 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004553 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 19:44:54.239560) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 19:44:54.239922) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 19:44:54.240293) ========= ========= Total master_lag: 0.37 =========