builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-028 starttime: 1495502077.45 results: success (0) revision: a6edfa473c7dde50edc14a85374eb400e5ada494 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 18:14:37.450786) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 18:14:37.451165) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 18:14:37.451428) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 18:14:37.478582) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:14:37.478834) ========= 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=836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-22 18:14:37-- 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% 43.0M=0s 2017-05-22 18:14:37 (43.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084653 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:14:37.576335) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:14:37.576637) ========= 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=836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015891 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:14:37.629222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 18:14:37.629510) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a6edfa473c7dde50edc14a85374eb400e5ada494 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a6edfa473c7dde50edc14a85374eb400e5ada494 --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=836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-22 18:14:38,072 truncating revision to first 12 chars 2017-05-22 18:14:38,073 Setting DEBUG logging. 2017-05-22 18:14:38,073 attempt 1/10 2017-05-22 18:14:38,073 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a6edfa473c7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 18:14:38,719 unpacking tar archive at: mozilla-beta-a6edfa473c7d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.019402 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 18:14:38.667194) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 18:14:38.667511) ========= script_repo_revision: a6edfa473c7dde50edc14a85374eb400e5ada494 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 18:14:38.667913) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 18:14:38.668207) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 18:14:38.676859) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-05-22 18:14:38.677131) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-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', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-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=836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:14:39 INFO - MultiFileLogger online at 20170522 18:14:39 in /builds/slave/test 18:14:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 18:14:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:14:39 INFO - {'append_to_log': False, 18:14:39 INFO - 'base_work_dir': '/builds/slave/test', 18:14:39 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 18:14:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:14:39 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 18:14:39 INFO - 'buildbot_json_path': 'buildprops.json', 18:14:39 INFO - 'config_files': ('talos/linux_config.py',), 18:14:39 INFO - 'default_actions': ('clobber', 18:14:39 INFO - 'read-buildbot-config', 18:14:39 INFO - 'download-and-extract', 18:14:39 INFO - 'populate-webroot', 18:14:39 INFO - 'create-virtualenv', 18:14:39 INFO - 'install', 18:14:39 INFO - 'run-tests'), 18:14:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:14:39 INFO - 'download_minidump_stackwalk': True, 18:14:39 INFO - 'download_symbols': 'ondemand', 18:14:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:14:39 INFO - 'tooltool.py': '/tools/tooltool.py', 18:14:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:14:39 INFO - '/tools/misc-python/virtualenv.py')}, 18:14:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:14:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:14:39 INFO - 'gecko_profile': False, 18:14:39 INFO - 'gecko_profile_interval': 0, 18:14:39 INFO - 'installer_path': 'installer.exe', 18:14:39 INFO - 'log_level': 'info', 18:14:39 INFO - 'log_name': 'talos', 18:14:39 INFO - 'log_to_console': True, 18:14:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:14:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:14:39 INFO - 'opt_config_files': (), 18:14:39 INFO - 'pip_index': False, 18:14:39 INFO - 'suite': 'dromaeojs', 18:14:39 INFO - 'system_bits': '32', 18:14:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:14:39 INFO - 'title': 'talos-linux64-ix-028', 18:14:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:14:39 INFO - 'use_talos_json': True, 18:14:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:14:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:14:39 INFO - 'work_dir': 'build'} 18:14:39 INFO - [mozharness: 2017-05-23 01:14:39.218643Z] Running clobber step. 18:14:39 INFO - Running pre-action listener: _resource_record_pre_action 18:14:39 INFO - Running main action method: clobber 18:14:39 INFO - rmtree: /builds/slave/test/build 18:14:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:14:40 INFO - Running post-action listener: _resource_record_post_action 18:14:40 INFO - [mozharness: 2017-05-23 01:14:40.769985Z] Finished clobber step (success) 18:14:40 INFO - [mozharness: 2017-05-23 01:14:40.770084Z] Running read-buildbot-config step. 18:14:40 INFO - Running pre-action listener: _resource_record_pre_action 18:14:40 INFO - Running main action method: read_buildbot_config 18:14:40 INFO - Using buildbot properties: 18:14:40 INFO - { 18:14:40 INFO - "project": "", 18:14:40 INFO - "product": "firefox", 18:14:40 INFO - "who": "asasaki@mozilla.com", 18:14:40 INFO - "installer_path": "public/build/target.tar.bz2", 18:14:40 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 18:14:40 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 18:14:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 18:14:40 INFO - "stage_platform": "linux64", 18:14:40 INFO - "basedir": "/builds/slave/test", 18:14:40 INFO - "buildnumber": 53, 18:14:40 INFO - "platform": "ubuntu64_hw", 18:14:40 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:14:40 INFO - "slavebuilddir": "test", 18:14:40 INFO - "taskId": "LAT9d_WDQku0JQ6IMBf3Wg", 18:14:40 INFO - "branch": "mozilla-beta", 18:14:40 INFO - "script_repo_revision": "production", 18:14:40 INFO - "revision": "a6edfa473c7dde50edc14a85374eb400e5ada494", 18:14:40 INFO - "slavename": "talos-linux64-ix-028", 18:14:40 INFO - "repo_path": "releases/mozilla-beta" 18:14:40 INFO - } 18:14:40 INFO - Finding installer, test and symbols from parent task. 18:14:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LAT9d_WDQku0JQ6IMBf3Wg'}, attempt #1 18:14:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LAT9d_WDQku0JQ6IMBf3Wg'}, attempt #1 18:14:41 INFO - Task dependencies: RRzjm-T_QTaXB7RP6qnjtg 18:14:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg'}, attempt #1 18:14:42 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.tar.bz2 18:14:42 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/test_packages.json 18:14:42 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.crashreporter-symbols.zip 18:14:42 INFO - Running post-action listener: _resource_record_post_action 18:14:42 INFO - [mozharness: 2017-05-23 01:14:42.152214Z] Finished read-buildbot-config step (success) 18:14:42 INFO - [mozharness: 2017-05-23 01:14:42.152431Z] Running download-and-extract step. 18:14:42 INFO - Running pre-action listener: _resource_record_pre_action 18:14:42 INFO - Running main action method: download_and_extract 18:14:42 INFO - mkdir: /builds/slave/test/build/tests 18:14:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:14:42 INFO - trying https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.test_packages.json 18:14:42 INFO - Downloading https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:14:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:14:42 INFO - Downloaded 986 bytes. 18:14:42 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:14:42 INFO - Using the following test package requirements: 18:14:42 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:14:42 INFO - u'common': [u'target.common.tests.zip'], 18:14:42 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:14:42 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:14:42 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:14:42 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:14:42 INFO - u'mozbase': [u'target.common.tests.zip'], 18:14:42 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:14:42 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:14:42 INFO - u'web-platform': [u'target.common.tests.zip', 18:14:42 INFO - u'target.web-platform.tests.zip'], 18:14:42 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:14:42 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:14:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.common.tests.zip 18:14:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:14:42 INFO - Fetch https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.common.tests.zip into memory 18:14:44 INFO - Expected file size: 59719628 18:14:44 INFO - Obtained file size: 59719628 18:14:53 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:14:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.common.tests.zip 18:14:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:14:53 INFO - Fetch https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.common.tests.zip into memory 18:14:55 INFO - Expected file size: 59719628 18:14:55 INFO - Obtained file size: 59719628 18:15:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.talos.tests.zip 18:15:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:15:05 INFO - Fetch https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.talos.tests.zip into memory 18:15:06 INFO - Expected file size: 14404449 18:15:06 INFO - Obtained file size: 14404449 18:15:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:06 INFO - trying https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.tar.bz2 18:15:06 INFO - Downloading https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:15:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:15:08 INFO - Downloaded 59637500 bytes. 18:15:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.tar.bz2 18:15:08 INFO - mkdir: /builds/slave/test/properties 18:15:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:15:08 INFO - Writing to file /builds/slave/test/properties/build_url 18:15:08 INFO - Contents: 18:15:08 INFO - build_url:https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.tar.bz2 18:15:08 INFO - Running post-action listener: _resource_record_post_action 18:15:08 INFO - Running post-action listener: set_extra_try_arguments 18:15:08 INFO - [mozharness: 2017-05-23 01:15:08.077187Z] Finished download-and-extract step (success) 18:15:08 INFO - [mozharness: 2017-05-23 01:15:08.077284Z] Running populate-webroot step. 18:15:08 INFO - Running pre-action listener: _resource_record_pre_action 18:15:08 INFO - Running main action method: populate_webroot 18:15:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:15:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:15:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:15:08 INFO - '--tppagecycles', 18:15:08 INFO - '7'], 18:15:08 INFO - 'tests': ['tsvgm']}}, 18:15:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:15:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:15:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:15:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:15:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:15:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:15:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:15:08 INFO - 'talos_options': ['--disable-e10s'], 18:15:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:15:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:15:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:15:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:15:08 INFO - 'talos_options': ['--disable-e10s'], 18:15:08 INFO - 'tests': ['damp', 'tps']}, 18:15:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:15:08 INFO - 'tests': ['damp', 'tps']}, 18:15:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:15:08 INFO - 'tests': ['dromaeo_dom']}, 18:15:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:15:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:15:08 INFO - 'tests': ['basic_compositor_video']}, 18:15:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:15:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:15:08 INFO - 'tests': ['a11yr', 18:15:08 INFO - 'ts_paint', 18:15:08 INFO - 'tpaint', 18:15:08 INFO - 'sessionrestore', 18:15:08 INFO - 'sessionrestore_no_auto_restore', 18:15:08 INFO - 'tabpaint']}, 18:15:08 INFO - 'other-e10s': {'tests': ['a11yr', 18:15:08 INFO - 'ts_paint', 18:15:08 INFO - 'tpaint', 18:15:08 INFO - 'sessionrestore', 18:15:08 INFO - 'sessionrestore_no_auto_restore', 18:15:08 INFO - 'tabpaint']}, 18:15:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:15:08 INFO - 'ts_paint', 18:15:08 INFO - 'tpaint', 18:15:08 INFO - 'sessionrestore', 18:15:08 INFO - 'sessionrestore_no_auto_restore', 18:15:08 INFO - 'tabpaint']}, 18:15:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:15:08 INFO - 'ts_paint', 18:15:08 INFO - 'tpaint', 18:15:08 INFO - 'sessionrestore', 18:15:08 INFO - 'sessionrestore_no_auto_restore', 18:15:08 INFO - 'tabpaint']}, 18:15:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:15:08 INFO - 'tests': ['a11yr', 18:15:08 INFO - 'ts_paint', 18:15:08 INFO - 'tpaint', 18:15:08 INFO - 'sessionrestore', 18:15:08 INFO - 'sessionrestore_no_auto_restore', 18:15:08 INFO - 'tabpaint']}, 18:15:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:15:08 INFO - 'tests': ['a11yr', 18:15:08 INFO - 'ts_paint', 18:15:08 INFO - 'tpaint', 18:15:08 INFO - 'sessionrestore', 18:15:08 INFO - 'sessionrestore_no_auto_restore', 18:15:08 INFO - 'tabpaint']}, 18:15:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:15:08 INFO - 'tests': ['tsvgx', 18:15:08 INFO - 'tsvgr_opacity', 18:15:08 INFO - 'tart', 18:15:08 INFO - 'tscrollx', 18:15:08 INFO - 'cart', 18:15:08 INFO - 'tsvg_static']}, 18:15:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:15:08 INFO - 'tsvgr_opacity', 18:15:08 INFO - 'tart', 18:15:08 INFO - 'tscrollx', 18:15:08 INFO - 'cart', 18:15:08 INFO - 'tsvg_static']}, 18:15:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:15:08 INFO - 'talos_options': ['--disable-e10s'], 18:15:08 INFO - 'tests': ['tp5o']}, 18:15:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:15:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:15:08 INFO - 'talos_options': ['--disable-e10s', 18:15:08 INFO - '--xperf_path', 18:15:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:15:08 INFO - 'tests': ['tp5n']}, 18:15:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:15:08 INFO - 'talos_options': ['--xperf_path', 18:15:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:15:08 INFO - 'tests': ['tp5n']}}, 18:15:08 INFO - 'talos.zip': {'path': '', 18:15:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:15:08 INFO - Running post-action listener: _resource_record_post_action 18:15:08 INFO - [mozharness: 2017-05-23 01:15:08.090479Z] Finished populate-webroot step (success) 18:15:08 INFO - [mozharness: 2017-05-23 01:15:08.090568Z] Running create-virtualenv step. 18:15:08 INFO - Running pre-action listener: _resource_record_pre_action 18:15:08 INFO - Running main action method: create_virtualenv 18:15:08 INFO - Creating virtualenv /builds/slave/test/build/venv 18:15:08 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 18:15:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:15:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:15:08 INFO - Using real prefix '/usr' 18:15:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:15:08 INFO - Installing distribute.............................................................................................................................................................................................done. 18:15:10 INFO - Installing pip.................done. 18:15:10 INFO - Return code: 0 18:15:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:15:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:15:10 INFO - Reading from file tmpfile_stdout 18:15:10 INFO - Output received: 18:15:10 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) 18:15:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:10 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/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 0x25641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2802310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ff400>, '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 0x28003b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2800840>, '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': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', '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 18:15:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:15:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:15:10 INFO - Using env: {'DISPLAY': ':0', 18:15:10 INFO - 'HOME': '/home/cltbld', 18:15:10 INFO - 'LANG': 'en_US.UTF-8', 18:15:10 INFO - 'LANGUAGE': 'en_US:en', 18:15:10 INFO - 'LOGNAME': 'cltbld', 18:15:10 INFO - 'MAIL': '/var/mail/cltbld', 18:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:10 INFO - 'MOZ_NO_REMOTE': '1', 18:15:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:10 INFO - 'NO_EM_RESTART': '1', 18:15:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:10 INFO - 'PWD': '/builds/slave/test', 18:15:10 INFO - 'SHELL': '/bin/bash', 18:15:10 INFO - 'SHLVL': '1', 18:15:10 INFO - 'TERM': 'linux', 18:15:10 INFO - 'TMOUT': '86400', 18:15:10 INFO - 'USER': 'cltbld', 18:15:10 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', 18:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:10 INFO - '_': '/tools/buildbot/bin/python'} 18:15:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:11 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 18:15:11 INFO - Cleaning up... 18:15:11 INFO - Return code: 0 18:15:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:11 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/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 0x25641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2802310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ff400>, '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 0x28003b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2800840>, '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': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', '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 18:15:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:15:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:15:11 INFO - Using env: {'DISPLAY': ':0', 18:15:11 INFO - 'HOME': '/home/cltbld', 18:15:11 INFO - 'LANG': 'en_US.UTF-8', 18:15:11 INFO - 'LANGUAGE': 'en_US:en', 18:15:11 INFO - 'LOGNAME': 'cltbld', 18:15:11 INFO - 'MAIL': '/var/mail/cltbld', 18:15:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:11 INFO - 'MOZ_NO_REMOTE': '1', 18:15:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:11 INFO - 'NO_EM_RESTART': '1', 18:15:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:11 INFO - 'PWD': '/builds/slave/test', 18:15:11 INFO - 'SHELL': '/bin/bash', 18:15:11 INFO - 'SHLVL': '1', 18:15:11 INFO - 'TERM': 'linux', 18:15:11 INFO - 'TMOUT': '86400', 18:15:11 INFO - 'USER': 'cltbld', 18:15:11 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', 18:15:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:11 INFO - '_': '/tools/buildbot/bin/python'} 18:15:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:11 INFO - Downloading/unpacking psutil>=3.1.1 18:15:11 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 18:15:11 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 18:15:11 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 18:15:11 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 18:15:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:15:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:15:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:15:12 INFO - Installing collected packages: psutil 18:15:12 INFO - Running setup.py install for psutil 18:15:12 INFO - building 'psutil._psutil_linux' extension 18:15:12 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 18:15:12 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 18:15:12 INFO - building 'psutil._psutil_posix' extension 18:15:12 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 18:15:12 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 18:15:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:15:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:15:13 INFO - Successfully installed psutil 18:15:13 INFO - Cleaning up... 18:15:13 INFO - Return code: 0 18:15:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:15:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:13 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/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 0x25641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2802310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ff400>, '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 0x28003b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2800840>, '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': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', '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 18:15:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:15:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:15:13 INFO - Using env: {'DISPLAY': ':0', 18:15:13 INFO - 'HOME': '/home/cltbld', 18:15:13 INFO - 'LANG': 'en_US.UTF-8', 18:15:13 INFO - 'LANGUAGE': 'en_US:en', 18:15:13 INFO - 'LOGNAME': 'cltbld', 18:15:13 INFO - 'MAIL': '/var/mail/cltbld', 18:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:13 INFO - 'MOZ_NO_REMOTE': '1', 18:15:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:13 INFO - 'NO_EM_RESTART': '1', 18:15:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:13 INFO - 'PWD': '/builds/slave/test', 18:15:13 INFO - 'SHELL': '/bin/bash', 18:15:13 INFO - 'SHLVL': '1', 18:15:13 INFO - 'TERM': 'linux', 18:15:13 INFO - 'TMOUT': '86400', 18:15:13 INFO - 'USER': 'cltbld', 18:15:13 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', 18:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:13 INFO - '_': '/tools/buildbot/bin/python'} 18:15:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:15:13 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 18:15:13 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 18:15:13 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 18:15:13 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 18:15:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:15:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:15:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:15:14 INFO - Installing collected packages: mozsystemmonitor 18:15:14 INFO - Running setup.py install for mozsystemmonitor 18:15:14 INFO - Successfully installed mozsystemmonitor 18:15:14 INFO - Cleaning up... 18:15:14 INFO - Return code: 0 18:15:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:15:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:14 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/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 0x25641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2802310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ff400>, '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 0x28003b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2800840>, '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': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', '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 18:15:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:15:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:15:14 INFO - Using env: {'DISPLAY': ':0', 18:15:14 INFO - 'HOME': '/home/cltbld', 18:15:14 INFO - 'LANG': 'en_US.UTF-8', 18:15:14 INFO - 'LANGUAGE': 'en_US:en', 18:15:14 INFO - 'LOGNAME': 'cltbld', 18:15:14 INFO - 'MAIL': '/var/mail/cltbld', 18:15:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:14 INFO - 'MOZ_NO_REMOTE': '1', 18:15:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:14 INFO - 'NO_EM_RESTART': '1', 18:15:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:14 INFO - 'PWD': '/builds/slave/test', 18:15:14 INFO - 'SHELL': '/bin/bash', 18:15:14 INFO - 'SHLVL': '1', 18:15:14 INFO - 'TERM': 'linux', 18:15:14 INFO - 'TMOUT': '86400', 18:15:14 INFO - 'USER': 'cltbld', 18:15:14 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', 18:15:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:14 INFO - '_': '/tools/buildbot/bin/python'} 18:15:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:14 INFO - Downloading/unpacking jsonschema==2.5.1 18:15:14 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 18:15:14 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 18:15:14 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 18:15:14 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 18:15:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:15:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:15:15 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 18:15:15 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 18:15:15 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 18:15:15 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 18:15:15 INFO - Downloading functools32-3.2.3-2.tar.gz 18:15:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:15:16 INFO - warning: no files found matching '*.txt' 18:15:16 INFO - no previously-included directories found matching 'build' 18:15:16 INFO - no previously-included directories found matching 'dist' 18:15:16 INFO - no previously-included directories found matching '.git*' 18:15:16 INFO - Installing collected packages: jsonschema, functools32 18:15:16 INFO - Running setup.py install for functools32 18:15:16 INFO - warning: no files found matching '*.txt' 18:15:16 INFO - no previously-included directories found matching 'build' 18:15:16 INFO - no previously-included directories found matching 'dist' 18:15:16 INFO - no previously-included directories found matching '.git*' 18:15:16 INFO - Successfully installed jsonschema functools32 18:15:16 INFO - Cleaning up... 18:15:16 INFO - Return code: 0 18:15:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:16 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/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 0x25641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2802310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ff400>, '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 0x28003b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2800840>, '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': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', '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 18:15:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:15:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:15:16 INFO - Using env: {'DISPLAY': ':0', 18:15:16 INFO - 'HOME': '/home/cltbld', 18:15:16 INFO - 'LANG': 'en_US.UTF-8', 18:15:16 INFO - 'LANGUAGE': 'en_US:en', 18:15:16 INFO - 'LOGNAME': 'cltbld', 18:15:16 INFO - 'MAIL': '/var/mail/cltbld', 18:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:16 INFO - 'MOZ_NO_REMOTE': '1', 18:15:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:16 INFO - 'NO_EM_RESTART': '1', 18:15:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:16 INFO - 'PWD': '/builds/slave/test', 18:15:16 INFO - 'SHELL': '/bin/bash', 18:15:16 INFO - 'SHLVL': '1', 18:15:16 INFO - 'TERM': 'linux', 18:15:16 INFO - 'TMOUT': '86400', 18:15:16 INFO - 'USER': 'cltbld', 18:15:16 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', 18:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:16 INFO - '_': '/tools/buildbot/bin/python'} 18:15:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:15:16 INFO - Cleaning up... 18:15:16 INFO - Return code: 0 18:15:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:16 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/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 0x25641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2802310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ff400>, '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 0x28003b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2800840>, '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': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', '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 18:15:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 18:15:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:15:16 INFO - Using env: {'DISPLAY': ':0', 18:15:16 INFO - 'HOME': '/home/cltbld', 18:15:16 INFO - 'LANG': 'en_US.UTF-8', 18:15:16 INFO - 'LANGUAGE': 'en_US:en', 18:15:16 INFO - 'LOGNAME': 'cltbld', 18:15:16 INFO - 'MAIL': '/var/mail/cltbld', 18:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:16 INFO - 'MOZ_NO_REMOTE': '1', 18:15:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:16 INFO - 'NO_EM_RESTART': '1', 18:15:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:16 INFO - 'PWD': '/builds/slave/test', 18:15:16 INFO - 'SHELL': '/bin/bash', 18:15:16 INFO - 'SHLVL': '1', 18:15:16 INFO - 'TERM': 'linux', 18:15:16 INFO - 'TMOUT': '86400', 18:15:16 INFO - 'USER': 'cltbld', 18:15:16 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', 18:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:16 INFO - '_': '/tools/buildbot/bin/python'} 18:15:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:16 INFO - Downloading/unpacking blobuploader==1.2.4 18:15:16 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 18:15:16 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 18:15:16 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 18:15:16 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 18:15:17 INFO - Downloading blobuploader-1.2.4.tar.gz 18:15:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:15:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:15:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:15:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:15:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:15:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:15:18 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 18:15:18 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 18:15:18 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 18:15:18 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 18:15:18 INFO - Downloading docopt-0.6.1.tar.gz 18:15:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:15:18 INFO - Installing collected packages: blobuploader, requests, docopt 18:15:18 INFO - Running setup.py install for blobuploader 18:15:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:15:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:15:18 INFO - Running setup.py install for requests 18:15:18 INFO - Running setup.py install for docopt 18:15:19 INFO - Successfully installed blobuploader requests docopt 18:15:19 INFO - Cleaning up... 18:15:19 INFO - Return code: 0 18:15:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:15:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:19 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/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 0x25641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2802310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ff400>, '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 0x28003b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2800840>, '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': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', '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 18:15:19 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:15:19 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:15:19 INFO - Using env: {'DISPLAY': ':0', 18:15:19 INFO - 'HOME': '/home/cltbld', 18:15:19 INFO - 'LANG': 'en_US.UTF-8', 18:15:19 INFO - 'LANGUAGE': 'en_US:en', 18:15:19 INFO - 'LOGNAME': 'cltbld', 18:15:19 INFO - 'MAIL': '/var/mail/cltbld', 18:15:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:19 INFO - 'MOZ_NO_REMOTE': '1', 18:15:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:19 INFO - 'NO_EM_RESTART': '1', 18:15:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:19 INFO - 'PWD': '/builds/slave/test', 18:15:19 INFO - 'SHELL': '/bin/bash', 18:15:19 INFO - 'SHLVL': '1', 18:15:19 INFO - 'TERM': 'linux', 18:15:19 INFO - 'TMOUT': '86400', 18:15:19 INFO - 'USER': 'cltbld', 18:15:19 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', 18:15:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:19 INFO - '_': '/tools/buildbot/bin/python'} 18:15:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:15:19 INFO - Running setup.py (path:/tmp/pip-Iy5_qK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:15:19 INFO - Running setup.py (path:/tmp/pip-M1W4qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:15:19 INFO - Running setup.py (path:/tmp/pip-v_3Qlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:15:19 INFO - Running setup.py (path:/tmp/pip-2jmi5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:15:19 INFO - Running setup.py (path:/tmp/pip-_BZ1kI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:15:19 INFO - Running setup.py (path:/tmp/pip-djy0eK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:15:19 INFO - Running setup.py (path:/tmp/pip-KQnGtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:15:19 INFO - Running setup.py (path:/tmp/pip-M4b90O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:15:19 INFO - Running setup.py (path:/tmp/pip-T3zymE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:15:19 INFO - Running setup.py (path:/tmp/pip-K5pT_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:15:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:15:19 INFO - Running setup.py (path:/tmp/pip-Ctmp4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:15:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:15:20 INFO - Running setup.py (path:/tmp/pip-TySUTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:15:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:15:20 INFO - Running setup.py (path:/tmp/pip-xTIaXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:15:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:15:20 INFO - Running setup.py (path:/tmp/pip-KVgMgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:15:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:15:20 INFO - Running setup.py (path:/tmp/pip-mXiCad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:15:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:15:20 INFO - Running setup.py (path:/tmp/pip-xOfG8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:15:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:15:20 INFO - Running setup.py (path:/tmp/pip-ZMKDEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:15:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:15:20 INFO - Running setup.py install for manifestparser 18:15:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:15:20 INFO - Running setup.py install for mozcrash 18:15:20 INFO - Running setup.py install for mozdebug 18:15:20 INFO - Running setup.py install for mozdevice 18:15:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:15:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:15:20 INFO - Running setup.py install for mozfile 18:15:21 INFO - Running setup.py install for mozhttpd 18:15:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:15:21 INFO - Running setup.py install for mozinfo 18:15:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:15:21 INFO - Running setup.py install for mozInstall 18:15:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:15:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:15:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:15:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:15:21 INFO - Running setup.py install for mozleak 18:15:21 INFO - Running setup.py install for mozlog 18:15:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:15:21 INFO - Running setup.py install for moznetwork 18:15:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:15:21 INFO - Running setup.py install for mozprocess 18:15:21 INFO - Running setup.py install for mozprofile 18:15:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:15:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:15:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:15:21 INFO - Running setup.py install for mozrunner 18:15:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:15:21 INFO - Running setup.py install for mozscreenshot 18:15:22 INFO - Running setup.py install for moztest 18:15:22 INFO - Running setup.py install for mozversion 18:15:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:15:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:15:22 INFO - Cleaning up... 18:15:22 INFO - Return code: 0 18:15:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:22 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/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 0x25641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2802310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ff400>, '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 0x28003b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2800840>, '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': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', '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 18:15:22 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:15:22 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:15:22 INFO - Using env: {'DISPLAY': ':0', 18:15:22 INFO - 'HOME': '/home/cltbld', 18:15:22 INFO - 'LANG': 'en_US.UTF-8', 18:15:22 INFO - 'LANGUAGE': 'en_US:en', 18:15:22 INFO - 'LOGNAME': 'cltbld', 18:15:22 INFO - 'MAIL': '/var/mail/cltbld', 18:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:22 INFO - 'MOZ_NO_REMOTE': '1', 18:15:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:22 INFO - 'NO_EM_RESTART': '1', 18:15:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:22 INFO - 'PWD': '/builds/slave/test', 18:15:22 INFO - 'SHELL': '/bin/bash', 18:15:22 INFO - 'SHLVL': '1', 18:15:22 INFO - 'TERM': 'linux', 18:15:22 INFO - 'TMOUT': '86400', 18:15:22 INFO - 'USER': 'cltbld', 18:15:22 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', 18:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:22 INFO - '_': '/tools/buildbot/bin/python'} 18:15:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:15:22 INFO - Running setup.py (path:/tmp/pip-0Bcz0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:15:22 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)) 18:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:15:22 INFO - Running setup.py (path:/tmp/pip-ZjAUL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:15:22 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)) 18:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:15:22 INFO - Running setup.py (path:/tmp/pip-9q_n3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:15:22 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)) 18:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:15:22 INFO - Running setup.py (path:/tmp/pip-OkOAw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:15:22 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)) 18:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:15:22 INFO - Running setup.py (path:/tmp/pip-RA9PRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:15:22 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)) 18:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:15:22 INFO - Running setup.py (path:/tmp/pip-B5EPLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:15:22 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)) 18:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:15:22 INFO - Running setup.py (path:/tmp/pip-jDmcz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:15:23 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)) 18:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:15:23 INFO - Running setup.py (path:/tmp/pip-Ypet4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:15:23 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)) 18:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:15:23 INFO - Running setup.py (path:/tmp/pip-RV0EjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:15:23 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)) 18:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:15:23 INFO - Running setup.py (path:/tmp/pip-Z7fJ4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:15:23 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)) 18:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:15:23 INFO - Running setup.py (path:/tmp/pip-ValPud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:15:23 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)) 18:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:15:23 INFO - Running setup.py (path:/tmp/pip-Vs7XBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:15:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:15:23 INFO - Running setup.py (path:/tmp/pip-BJX0RG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:15:23 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)) 18:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:15:23 INFO - Running setup.py (path:/tmp/pip-X5j5hP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:15:23 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)) 18:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:15:23 INFO - Running setup.py (path:/tmp/pip-Yqp1fR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:15:23 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)) 18:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:15:23 INFO - Running setup.py (path:/tmp/pip-eKNG7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:15:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:15:23 INFO - Running setup.py (path:/tmp/pip-qC4fNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:15:23 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)) 18:15:23 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)) 18:15:23 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)) 18:15:23 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)) 18:15:23 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)) 18:15:23 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)) 18:15:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:15:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:15:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:15:24 INFO - Downloading blessings-1.6.tar.gz 18:15:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:15:24 INFO - Installing collected packages: blessings 18:15:24 INFO - Running setup.py install for blessings 18:15:25 INFO - Successfully installed blessings 18:15:25 INFO - Cleaning up... 18:15:25 INFO - Return code: 0 18:15:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:15:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:15:25 INFO - Reading from file tmpfile_stdout 18:15:25 INFO - Current package versions: 18:15:25 INFO - argparse == 1.2.1 18:15:25 INFO - blessings == 1.6 18:15:25 INFO - blobuploader == 1.2.4 18:15:25 INFO - docopt == 0.6.1 18:15:25 INFO - functools32 == 3.2.3-2 18:15:25 INFO - jsonschema == 2.5.1 18:15:25 INFO - manifestparser == 1.1 18:15:25 INFO - mozInstall == 1.12 18:15:25 INFO - mozcrash == 1.0 18:15:25 INFO - mozdebug == 0.1 18:15:25 INFO - mozdevice == 0.49 18:15:25 INFO - mozfile == 1.2 18:15:25 INFO - mozhttpd == 0.7 18:15:25 INFO - mozinfo == 0.9 18:15:25 INFO - mozleak == 0.1 18:15:25 INFO - mozlog == 3.4 18:15:25 INFO - moznetwork == 0.27 18:15:25 INFO - mozprocess == 0.25 18:15:25 INFO - mozprofile == 0.28 18:15:25 INFO - mozrunner == 6.13 18:15:25 INFO - mozscreenshot == 0.1 18:15:25 INFO - mozsystemmonitor == 0.3 18:15:25 INFO - moztest == 0.8 18:15:25 INFO - mozversion == 1.4 18:15:25 INFO - psutil == 3.1.1 18:15:25 INFO - requests == 1.2.3 18:15:25 INFO - wsgiref == 0.1.2 18:15:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15:25 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/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 0x25641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2802310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ff400>, '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 0x28003b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2800840>, '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': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', '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 18:15:25 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:15:25 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:15:25 INFO - Using env: {'DISPLAY': ':0', 18:15:25 INFO - 'HOME': '/home/cltbld', 18:15:25 INFO - 'LANG': 'en_US.UTF-8', 18:15:25 INFO - 'LANGUAGE': 'en_US:en', 18:15:25 INFO - 'LOGNAME': 'cltbld', 18:15:25 INFO - 'MAIL': '/var/mail/cltbld', 18:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:25 INFO - 'MOZ_NO_REMOTE': '1', 18:15:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:25 INFO - 'NO_EM_RESTART': '1', 18:15:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:25 INFO - 'PWD': '/builds/slave/test', 18:15:25 INFO - 'SHELL': '/bin/bash', 18:15:25 INFO - 'SHLVL': '1', 18:15:25 INFO - 'TERM': 'linux', 18:15:25 INFO - 'TMOUT': '86400', 18:15:25 INFO - 'USER': 'cltbld', 18:15:25 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', 18:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:25 INFO - '_': '/tools/buildbot/bin/python'} 18:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:25 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)) 18:15:25 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)) 18:15:25 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)) 18:15:25 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)) 18:15:25 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)) 18:15:25 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)) 18:15:25 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)) 18:15:25 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)) 18:15:25 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)) 18:15:25 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)) 18:15:25 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)) 18:15:25 INFO - Cleaning up... 18:15:25 INFO - Return code: 0 18:15:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:15: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/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 0x25641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25a2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2802310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27ff400>, '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 0x28003b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2800840>, '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': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', '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 18:15:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:15:25 INFO - Using env: {'DISPLAY': ':0', 18:15:25 INFO - 'HOME': '/home/cltbld', 18:15:25 INFO - 'LANG': 'en_US.UTF-8', 18:15:25 INFO - 'LANGUAGE': 'en_US:en', 18:15:25 INFO - 'LOGNAME': 'cltbld', 18:15:25 INFO - 'MAIL': '/var/mail/cltbld', 18:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:25 INFO - 'MOZ_NO_REMOTE': '1', 18:15:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:25 INFO - 'NO_EM_RESTART': '1', 18:15:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:25 INFO - 'PWD': '/builds/slave/test', 18:15:25 INFO - 'SHELL': '/bin/bash', 18:15:25 INFO - 'SHLVL': '1', 18:15:25 INFO - 'TERM': 'linux', 18:15:25 INFO - 'TMOUT': '86400', 18:15:25 INFO - 'USER': 'cltbld', 18:15:25 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', 18:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:25 INFO - '_': '/tools/buildbot/bin/python'} 18:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:15:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:15:25 INFO - Cleaning up... 18:15:25 INFO - Return code: 0 18:15:25 INFO - Running post-action listener: _resource_record_post_action 18:15:25 INFO - Running post-action listener: _start_resource_monitoring 18:15:25 INFO - Starting resource monitoring. 18:15:25 INFO - [mozharness: 2017-05-23 01:15:25.788755Z] Finished create-virtualenv step (success) 18:15:25 INFO - [mozharness: 2017-05-23 01:15:25.789227Z] Running install step. 18:15:25 INFO - Running pre-action listener: _resource_record_pre_action 18:15:25 INFO - Running main action method: install 18:15:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:15:25 INFO - Reading from file tmpfile_stdout 18:15:25 INFO - Detecting whether we're running mozinstall >=1.0... 18:15:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:15:26 INFO - Reading from file tmpfile_stdout 18:15:26 INFO - Output received: 18:15:26 INFO - Usage: mozinstall [options] installer 18:15:26 INFO - Options: 18:15:26 INFO - -h, --help show this help message and exit 18:15:26 INFO - -d DEST, --destination=DEST 18:15:26 INFO - Directory to install application into. [default: 18:15:26 INFO - "/builds/slave/test"] 18:15:26 INFO - --app=APP Application being installed. [default: firefox] 18:15:26 INFO - mkdir: /builds/slave/test/build/application 18:15:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:15:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:15:40 INFO - Reading from file tmpfile_stdout 18:15:40 INFO - Output received: 18:15:40 INFO - /builds/slave/test/build/application/firefox/firefox 18:15:40 INFO - Running post-action listener: _resource_record_post_action 18:15:40 INFO - [mozharness: 2017-05-23 01:15:40.265316Z] Finished install step (success) 18:15:40 INFO - [mozharness: 2017-05-23 01:15:40.265618Z] Running run-tests step. 18:15:40 INFO - Running pre-action listener: _resource_record_pre_action 18:15:40 INFO - Running main action method: run_tests 18:15:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:15:40 INFO - Python 2.7.3 18:15:40 INFO - Return code: 0 18:15:40 INFO - grabbing minidump binary from tooltool 18:15:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 18:15:40 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 0x27ff400>, '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 0x28003b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2800840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:15:40 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 18:15:40 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 18:15:40 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 18:15:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:15:40 INFO - Return code: 0 18:15:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:15:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:15:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:15:40 INFO - ENV: RUST_BACKTRACE is now 1 18:15:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:15:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:15:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 18:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-028 --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 18:15:40 INFO - Using env: {'DISPLAY': ':0', 18:15:40 INFO - 'HOME': '/home/cltbld', 18:15:40 INFO - 'LANG': 'en_US.UTF-8', 18:15:40 INFO - 'LANGUAGE': 'en_US:en', 18:15:40 INFO - 'LOGNAME': 'cltbld', 18:15:40 INFO - 'MAIL': '/var/mail/cltbld', 18:15:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:15:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:15:40 INFO - 'MOZ_NO_REMOTE': '1', 18:15:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:15:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:15:40 INFO - 'NO_EM_RESTART': '1', 18:15:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:15:40 INFO - 'PWD': '/builds/slave/test', 18:15:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:15:40 INFO - 'RUST_BACKTRACE': '1', 18:15:40 INFO - 'SHELL': '/bin/bash', 18:15:40 INFO - 'SHLVL': '1', 18:15:40 INFO - 'TERM': 'linux', 18:15:40 INFO - 'TMOUT': '86400', 18:15:40 INFO - 'USER': 'cltbld', 18:15:40 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615', 18:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:15:40 INFO - '_': '/tools/buildbot/bin/python'} 18:15:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 18:15:40 INFO - mozversion application_buildid: 20170523002428 18:15:40 INFO - mozversion application_changeset: a6edfa473c7dde50edc14a85374eb400e5ada494 18:15:40 INFO - mozversion application_display_name: Firefox 18:15:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:15:40 INFO - mozversion application_name: Firefox 18:15:40 INFO - mozversion application_remotingname: firefox 18:15:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:15:40 INFO - mozversion application_vendor: Mozilla 18:15:40 INFO - mozversion application_version: 54.0 18:15:40 INFO - mozversion platform_buildid: 20170523002428 18:15:40 INFO - mozversion platform_changeset: a6edfa473c7dde50edc14a85374eb400e5ada494 18:15:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:15:40 INFO - mozversion platform_version: 54.0 18:15:40 INFO - using testdate: 1495502140 18:15:40 INFO - actual date: 1495502140 18:15:40 INFO - starting webserver on 'localhost:33241' 18:15:40 INFO - SUITE-START | Running 2 tests 18:15:40 INFO - TEST-START | dromaeo_css 18:15:40 INFO - operating with platform_type : linux_ 18:15:40 INFO - Initialising browser for dromaeo_css test... 18:15:40 INFO - TEST-INFO | started process 8064 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0oZ0qD/profile http://localhost:33241/getInfo.html) 18:15:44 INFO - PROCESS | 8064 | __metrics Screen width/height:1600/1200 18:15:44 INFO - PROCESS | 8064 | colorDepth:24 18:15:44 INFO - PROCESS | 8064 | Browser inner width/height: 1024/697 18:15:44 INFO - PROCESS | 8064 | __metrics 18:15:45 INFO - PROCESS | 8064 | 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" 18:15:45 INFO - PROCESS | 8064 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:15:46 INFO - TEST-INFO | 8064: exit 0 18:15:46 INFO - Browser initialized. 18:15:46 INFO - Running cycle 1/1 for dromaeo_css test... 18:15:46 INFO - TEST-INFO | started process 8154 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0oZ0qD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:15:58 INFO - PROCESS | 8154 | RSS: Main: 141496320 18:15:58 INFO - PROCESS | 8154 | 18:18:59 INFO - PROCESS | 8154 | Cycle 1(1): loaded http://localhost:33241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33241/tests/dromaeo/cssquery-ext.html) 18:19:00 INFO - PROCESS | 8154 | RSS: Main: 263671808 18:19:00 INFO - PROCESS | 8154 | 18:22:03 INFO - PROCESS | 8154 | Cycle 1(1): loaded http://localhost:33241/tests/dromaeo/cssquery-ext.html (next: http://localhost:33241/tests/dromaeo/cssquery-jquery.html) 18:22:03 INFO - PROCESS | 8154 | RSS: Main: 225292288 18:22:03 INFO - PROCESS | 8154 | 18:25:05 INFO - PROCESS | 8154 | Cycle 1(1): loaded http://localhost:33241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33241/tests/dromaeo/cssquery-mootools.html) 18:25:06 INFO - PROCESS | 8154 | RSS: Main: 170921984 18:25:06 INFO - PROCESS | 8154 | 18:28:08 INFO - PROCESS | 8154 | Cycle 1(1): loaded http://localhost:33241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33241/tests/dromaeo/cssquery-prototype.html) 18:28:08 INFO - PROCESS | 8154 | RSS: Main: 171487232 18:28:08 INFO - PROCESS | 8154 | 18:31:11 INFO - PROCESS | 8154 | Cycle 1(1): loaded http://localhost:33241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33241/tests/dromaeo/cssquery-yui.html) 18:31:11 INFO - PROCESS | 8154 | RSS: Main: 175628288 18:31:11 INFO - PROCESS | 8154 | 18:34:14 INFO - PROCESS | 8154 | Cycle 1(1): loaded http://localhost:33241/tests/dromaeo/cssquery-yui.html (next: http://localhost:33241/tests/dromaeo/cssquery-dojo.html) 18:34:14 INFO - PROCESS | 8154 | RSS: Main: 264531968 18:34:14 INFO - PROCESS | 8154 | 18:34:14 INFO - PROCESS | 8154 | __start_tp_report 18:34:14 INFO - PROCESS | 8154 | _x_x_mozilla_page_load 18:34:14 INFO - PROCESS | 8154 | _x_x_mozilla_page_load_details 18:34:14 INFO - PROCESS | 8154 | |i|pagename|runs| 18:34:14 INFO - PROCESS | 8154 | |0;dojo.html;3381.0871064812495;3418.538497302865;3420.777649452786;3457.2912552926623;3474.704650104741;5730.312362516499;5753.827385178452;5754.338251101125;5771.595988280822;5781.855453613662;5753.913691294634;5759.16492108644;5803.709814509279;5805.3323867755225;5807.419258074201;5146.356705411824;5152.974235128838;5160.845174644757;5166.664166829163;5220.347456567929;4945.950540494585;5000.0999820032375;5230.34620672416;5238.319018527586;5271.9472805272035;5101.923471147936;5112.182050871861;5122.6158038147105;5126.71803050832;5154.768035438415;5207.921881171786;5217.686938783666;5225.399079105913;5230.032443997854;5251.553617942442;4889.09551732927;5068.847934561969;5139.511746384088;5188.92216616751;5200.3759548854005;5527.226188333637;5541.418151094158;5545.195946587723;5547.1124620061;5548.389677135512;5912.763489460416;5950.7917222896995;5960;5960.731767070492;5964;6000.099985002241;6003.099535069731;6173.475254603364;6210.937890621081;6240.625562466266;5786.334571524287;5795.101759227242;5797.217375654275;5803.738831752581;5835.708214589296;6273.309935907047;6303.212098487689;6310.5898116622275;6314.116023756677;6326.746930122744;5781.971090144522;5834.066549352168;6015.278166619946;6051.364606716232;6064.575479716377;6179.62922224659;6190.102435146879;6193.628382297076;6195.473384762256;6201;6163.445289923929;6167.414095660905;6168.25980882297;6171.043488259327;6174.845628859314;5875.265591801025;5905.409459054146;6056.242969628796;6083.239595050619;6138.357744607992;4705.223638099744;4725.574698277172;4726.574608285202;4729.503402142795;4747.667663263538;4188.6230239278;4196.664266858644;4200.138971510833;4206.137741762931;4228.027553662675;5619.26949496571;5659.037963546207;5827.271591051119;5858.56060795442;5864.736086876101;6174.25908890927;6176.969115154396;6180.536459765535;6182.227221597301;6183.319834818167;5799.681017544076;5850.765969361263;5852.268466441695;5877.4122587740885;5878.5297176225795;5062.670926389773;5074.49255074497;5236.319278493847;5284.630075894651;5290.841274761763;6346.587471814318;6378.170837791057;6416.165898433173;6424.903626445701;6439.162908822011;6189.288231853395;6191.102290167991;6205.379462053939;6205.410486003822;6214;6083.209182806207;6461.224653041665;6462.676866156768;6466.805995820133;6477.319881412383;5977.163197152315;5990.940090599038;6055.697215139137;6113.810786210694;6118.418750218722;6189.535784816247;6228.688565571794;6231.065340199007;6237.5321850860455;6238.220222472191;6029.186059881859;6047.03247480401;6053.818385448443;6057.939420605914;6061.908856405713;5749.166370876116;5793.304803423616;5965.313988891333;5986;6011.849703757441;6004.969975150097;6027.156198132353;6060.6666633333825;6064.363241859718;6092.147099405998;5779.2775903012125;5826.475617194473;6102.755889764359;6105.633661980295;6117.785877494266;5663.292088488939;5703.714814259353;5714.342850572238;5851.297844258716;5898.64608123514 18:34:14 INFO - PROCESS | 8154 | |1;ext.html;775.1163673412308;849.6643825688851;865.7922098696313;927.4852456886425;931.5714771205245;31807.68192318076;43597;44565.77717111418;44570.33144502827;44713.552864471385;42264.366034509505;42417.78791106048;42527;45956;46285;7746.806329841771;7937.722179723711;9292.953535232347;9404.388714733555;9409.76475588109;10676.51955661997;10860.782784344308;10861.18541109416;10868.347899126038;10869.945650271737;10918.563257469692;10988.011079002888;11003.449827508633;11013.61452349168;11027.61403350883;10890.673279801618;10923.180761442844;10933.617323393722;10934;10935.507902144383;10753.569857205703;10802.945985270102;10811.297265677706;10812.35125892449;10813.026827585476;43550.34674479885;43649;43729.344059839124;43750.343744843856;43790.34314485285;4280.464941882265;4381.036172042146;4446.4886538047895;4475.932861007088;4633.027064316498;27430;27852.442951140863;28010.5798413024;28258.858705706545;28576.14271571856;4131.566185550504;4197.832086716528;4202.348635961431;4214.346776249687;4228.154369126156;242296;242623;242656;242776;243639;45291.77354113208;45713.31430028552;45893.31160032602;46170.53829461662;46290;47573;47909.28136077962;47915.84378702967;47946.76026619914;48034.51965480301;15554.22228888551;15590;15802.841971580367;15821.920890395704;15823.604409889615;11321.26411783248;11533.826992595117;13448;13725.245111518763;13731.382087806072;12836.550720724917;13054.282014489294;13101.737965240642;13135.080544361803;13159.736805263841;5506.256655351556;5518.144687573433;5543.750531226105;5564.610477266552;5635.098384258581;931.9953400232955;954.4654993203828;957.8611101390399;958.5063692198523;972.3485264872411;26177.607335889978;26203.73796262013;26491.735082648927;26532.469350613264;26554.46891062168;1002.4586723169407;1017.211660962745;1030.577463240083;1030.9278350515392;1033.5504055735778;13238.404271807793;13789.724205515833;13905.86094139066;13921.930390347983;14193.85806141946;331667;333530;334094;334198;336520;58348.416515835146;58634.7068264656;58722;58822.41177588255;58869;1437.1377173695698;1505.9322330495154;1518.9544313670388;1556.2763315058553;1572.8977616454895;28984.42031159323;30064.947726829458;30129.698703013568;30530.694693053672;30575;39114.4132838002;39229.60770392259;39301.80349098236;39311.21377572375;39319;299337;299813;300276;300286;300542;25731.485370292863;27901.86049069823;27924.44151117007;32203.51694724628;32268.515972260906;33532.83233583817;33551.32897342088;33770.83114584412;39721.2055758889;39892;30743.846280768452;31348.529772053895;31486.527702084946;31552.36895262036;31701.841490793322;30529;30771.692283077777;31067.84466077655;31161.688383115878;31546.211344716565;34933.777317793756;35007.82496087605;35054.29891402209;35298;35739;29077.12768616945;29278.560821587267;29368.853155734083;29380.55929161107;29863.701362986092;23389.76610233876;24202.87898560496;24229;24503.632445512976;25137.49725005453 18:34:14 INFO - PROCESS | 8154 | |2;jquery.html;450.61922675339326;452.902625935424;454.9317602359646;469.73929469144633;473.2191883392402;16028.35886564536;16707.91646041768;20385.796142038576;22126;22612.321630351096;20948.58102837946;21222.257220997268;21311.786882131175;21410.892945535288;21614;6523.347665233474;6851.246362900077;6856.554323968926;6856.897146542806;6865.416439602614;4942.827001054946;6770.796876093725;6858.72565097398;7734.265244801735;7792.298693117618;7627.542347459143;8064.27421532062;8068.47554908929;8074.394420418463;8084.353251739847;7722.111957124918;7808.375329973588;7809.960950195269;7813.531188128731;7823.608819559056;7547.981022561994;7741.458097933147;7770.883436748453;7801.336886364666;7803.453758236926;25772.226833194847;26664;26844.731552684418;27125.864370678217;27159.321016974536;3629.6007439181667;3687.8893633191046;3698.6116457771814;3738.4392341148773;3764.1154328732728;16382.918085409498;16662.166891655368;16978.235979380835;17022.914885425616;17282.740758888627;2160.8055275025167;2214.2803588833694;2218.7115674962315;2280.3614987803276;2284.7601001894727;96985;97261.51369243108;98244;98280.50859745727;100100.49949750205;18911.14899829511;19294.32469863548;19459.805401946083;19629.607407852047;19988;36674.26651466956;36779;36788.264234715156;36881.78709277452;37080.44379334312;11112.722181945353;11314.660560183207;11440.256383335223;11440.542378304775;11525.654230373102;9369.203617692572;9392.483413412328;9410.482423466638;9592.424454532751;9614.471204083702;9897.851532226878;10403.271770976111;10502.159827213805;10675.89324106749;10768.78462430747;3711.665950064453;3791.279656865187;3863.7874916879277;3958.8218530166214;4019.577944315805;833.3125171733344;894.5169608411385;986.1765425869336;996.4668902137322;1022.867027286448;22335.888320558293;22400.77599223987;22406.215782447536;22409.991550380273;22448.438789030406;994.7264502261936;995.9601615935281;996.6810620601337;1009.6769033909079;1013.7465633591602;12129.21160124589;12597.811032834516;12901.419436125398;13001.284929328789;13011.479540818262;292781.5360923182;433065;574661;580056;581885;82148.58925705333;90036;91159;91870;93298;1264.4120483974996;1267.1320145700224;1296.5138073222543;1302.8957683385495;1305.654001689534;18796.248150073843;19214;19372.903135484798;19399.612007759482;19544.90227548853;45186.32220516629;51255.48744512507;56089.43910560842;56252.71873640606;56710.14934775899;66646;66697;68197;68654;69110.30889691038;11980.981616562342;12093.213941093802;12662.113652044269;12692.809607855705;12917.806232906327;13758.31208439554;13762.793558096837;13769.655758606115;13784.931075344559;13855;11669.591564295548;11842;13168.539101131797;13314.800277996032;13719;12091.093168012258;12117.394130293274;12172.695682607651;12220.694482638006;12433.254004759743;12802;12806.743865122458;12934.094613376974;12935;13208.471661133448;12976.416061277076;12985.935070324966;13000.804987925378;13003.544875929298;13119.212847229197;12271.57049503263;12526.436310366238;12534.874651253736;12669.049821263257;13179.341032948507 18:34:14 INFO - PROCESS | 8154 | |3;mootools.html;1540.0682587034844;1573.1662219023922;1577.440008796877;1582.4224158182253;1583.0897234090398;10735.355878647277;11471.713207169825;11480.368579728112;11492.655220343382;11503.194776365637;10923.672289831318;11220.158488113384;11237.719057023578;11247.381394023325;11261.831072533918;2507.674002379693;2540.9110681126162;2544.2875994721508;2554.540182767101;2558.9744102559016;2452;2523.255639586317;2578.987105064472;2584.4443444659396;2588.5081834451394;2434.3427274635837;2501.9374515637164;2512.258883629334;2531.582288922335;2531.9746802532013;2377.1204654278026;2435.610302351624;2440.8413453125577;2482.937926551833;2484.1429706751323;2420.6490058941354;2449.1795248591743;2459.6679448274613;2461.483088551406;2462.2982450001664;10712.571497140105;10888.074513666348;10904.400257985808;10938.781224375469;10944.726381840437;5763.711814409263;5898.4101589840675;5904.084866845646;5909.2908850938165;5915.970420147914;3560.7151427885965;3616.72874534411;3681.815909204529;3794.620537946211;3857.1321452673205;1360.408222423239;1454.9418023279056;1455.468753904817;1465.2087872548811;1496.4280739458375;15817.130057846814;16055.357785688559;16206.35174593015;16214.837851621569;16217.108059056747;4752.397150533631;4766.523347665206;4783.402074740658;4865.026994601094;4882.145624515767;12833.294168820805;12833.614991550268;12835.229886206858;12848.164869283466;12857.485700572059;5890.9410905891245;5989.610675306091;5989.790357337472;5998.25021872266;6007.699615019319;5004.9499505005215;5324.334458192726;5353.544948679406;5405.918911216335;5426.484483974016;3941.546722126992;4036.3138266494766;4085.101277718897;4108.4453598764385;4140.937885931713;1023.8464230365505;1026.4098143567478;1045.1795340657548;1045.2056437107701;1056.0864851903202;1074.99462502687;1077.488193108283;1082.7022568793486;1083.111848284415;1091.8689757229024;6852.280510546321;6916;6952.826179345456;7087.858242835115;7096.7516136934955;1046.450613427941;1081.8160912644869;1090.4002798460974;1096.7970925378831;1119.121489630636;4666.836660716858;4678.274867395559;4789.2337226044365;4840.10458065252;4885.682430642068;13586.116902401312;14105;14193;14972.850271497364;14973.775393369107;12161.878381216251;12467.5012999479;14559;14560.126392416278;14561.92719036398;10199.18406527476;10254.692359229384;10365;10742;10808.94595527033;7258.528195667264;7549.811254718676;7554.395648348229;7642.8089297768;7731.536107833548;12974.091813572959;13084.934575327063;13204.537841175512;13220.47118115303;13238.338083096;13247.470101195844;13274.469021239041;13334.533291334757;13395.598132056053;13589.592312230649;9813.558389872616;10097.44464054484;10107.292489525662;10109.595616175271;10110.848337275094;10880.238383313408;10886.51010704536;10913.508892100033;10961.725956851142;10988.285761425483;9197.77005574866;9266.721998340061;9353.579088941151;9393.577289021874;9474.526273686426;8985.191332780081;8986.32602554798;9014.098590141195;9059.954700226457;9137.63449462014;8395.748127556182;8445.071042185478;8533.786655333666;8537.18896704812;8538.743837684879;6537.934620653733;6570;6600.471962242913;6647.102641143577;6678.332166783282;6407.775727849503;6628.370304821035;6680.720924017773;6681.498887583353;6696.129503164558 18:34:14 INFO - PROCESS | 8154 | |4;prototype.html;401.3357892687603;432.7521947781896;433.763598838633;436.6463164836481;442.1554830274176;5351.625386222956;5367;5383.573353061437;5401.297831281938;5420.810271640494;5074.264231686403;5303.65526240794;5326.627136100465;5337.599680023995;5351.822599028217;4320.7901787499395;4375.453068366454;4384.912301753971;4389.385486031958;4412.316091005883;4353.412289340946;4380.474343078835;4380.540043295456;4410.448693913261;4466.709663871838;4390.956090439103;4412.161689279027;4423.203823311803;4433.822647094113;4466.3300504924355;4187.560306167854;4371.650267978554;4406.5593440655675;4409.706952384774;4418.3372494125815;4189.581041895816;4190.769507677077;4191.3293872980485;4191.455110835603;4195.895102622429;5147.349115205672;5185.196294574347;5229.477052294778;5272.945410917831;5294.41761406245;5048.7223202723835;5147.613928955343;5156.948430515685;5202.621305354084;5238.192823475894;5635.295588051494;5640.379558248589;5643.887122257573;5650.237217975562;5653.293338332708;4538.99863929365;4650.74429903924;4700.788464519105;4704.858854234379;4717.9292310615365;5537.612367134302;5579.748911298961;5585.4973052425885;5685.805980744049;5826.271716035496;4606.032733126014;4608.838690645878;4613.446386433649;4721.197396442613;4739.383880095635;4701.153792317347;4762.380890484214;4776.713397196179;4782.665213435026;4804.73573953429;4872.902541949141;4888.2178851383605;5060.2156665716875;5107.131787596118;5150.201718733602;5551.3893471718075;5558.388577256498;5565.552956231328;5577.410438025144;5588.720563971785;1657.3453485873013;1679.3618424998667;1683.7876728755277;1684.553593297777;1691.1037150310356;1255.5605538061789;1297.8507471640694;1305.9934700326437;1315.6645055510976;1326;5160.948390516047;5445.774700692313;5453.836384908459;5455.863603409946;5461.972690136524;5673.801416950387;5712.686641747776;5732.34078081026;5770.971145144247;5798.8550286242335;5497.835064947978;5513.641613295204;5514.338279406497;5520.282363292747;5522.91715624266;2840.6449193850767;2974.479466093425;2987.3726517431583;3002.4745669507747;3011.6536598291477;5913.048693931079;5923.889957772118;5944.64332140073;5964.642121472725;5994.400559943971;5695.5191650171255;5783.797567085216;5843.415658434123;5878.441548052928;5909.261342332208;5883.529317654577;5914.6746928918465;5915.37888521699;5919;5990.670513121733;5688.687122208318;5709.628874123335;5738.196652468574;5740.990653271321;5752.597318187687;5383.488568585978;5429.809956651498;5553.16702494629;5556.611037227355;5672.971635141798;5597.356304025089;5721.654121810038;5831;5934.329551701838;5959.93063572252;4752.168370535212;4752.405949256343;4754.405699287589;4778.37881075458;4778.402699662543;5553.777848886161;5570.5543556516195;5760.876629057294;5786.537077033743;5819.748754017737;5457.50719539503;5478.369987451335;5478.945210547843;5501.5323697486165;5504.972475137599;5480.753366098455;5502.724863756876;5536.474034966672;5591.384947655673;5591.972040139936;5311.45967669387;5522.641028333145;5543.307086614173;5545.7781688732;5578.553715702653;5481.328194900593;5534.833954981357;5535.5054135384025;5547.611667183259;5565.805196818092;5217.50433708797;5410.594205434687;5436.891262174813;5482;5606.579506537108 18:34:14 INFO - PROCESS | 8154 | |5;yui.html;841.43203337747;875.1685898396521;878.1218781218781;889.8353804546158;926.1386910173538;17115.48653540393;17983.64032719348;18650.160742766573;18685.813141868577;18711.532211694714;17214.741778873296;17320.826791732077;17401.564960875952;17409;17440.738388924176;883.2977782662798;908.0783005249676;909.2226146644623;924.7688077980505;928.6517555916531;917.807260475301;920.4477313611847;921.4425272710015;922.4603606889943;922.8154369126169;970.4129001953822;971.6550624528297;972.0522490571707;972.4602845420789;972.8637990681275;965.507591128526;970.7476056225362;971.4462756228952;972.5234635028855;973.990260097397;715.8675645005691;719.4172720096739;719.6150059718026;720.5208536323339;721.0662192460776;18238.179281932345;18257.908710456497;18387;18849.246030158778;19043.333483328155;16222.351105955748;16457.506274811654;16464.42374516886;16465.34138634453;17065.65868682632;13700.93149534256;13757;13990.230537320444;14005.439782408692;14174.787378189336;3970.4441378207075;4000.119973605831;4010.2380547695843;4067.6135767102373;4165.604267594574;47326.763366182946;55610;63248.683756581384;80157.59921200415;88290;12330.321832299309;12547.686307842378;12623.621291361274;12634.747305053848;12640.11519193666;23386;23473.647895281585;23753.16863909755;23778.286651400485;23956;8684.478931264144;8751.868721969047;8788.692395766118;9039.367244292836;9040.141186587263;5006.4743201964;5011.799528018912;5096.337476128154;5172.5603323717905;5217.399999000164;3843.8846834595006;3935.370340745467;3941.3299739044432;3947.782871942071;3948.7038472114705;667.7062092679206;669.223700507409;671.6809515480205;687;692.944564434844;841.7138173021202;868.3660927522943;872.829798189347;880.3265501891059;882.2456799436518;11568.190226684219;11946.641600751991;12027.338496382608;12169.939150304192;12448.439820207932;761.6153842309598;762.6568044380051;769.1347234361343;791.8930944322443;795.4749865089013;5569.860753481195;5693.601447898607;5849;5853.560982926298;5867.061270196748;75692;83540;84438;85156.57421712852;88662.55668721744;72048;72389;73602.63198684079;74920.25079749242;76161.61919190368;66206.66896665582;66454.66772666106;67521;68154;69669.65165174243;10957.452127393439;11064.391458469861;11140.387278699749;11375.146863985068;11478.253913495597;47263;47676.284855727885;47875.5212447885;48110;48311;53187.734061329444;53910.46089539054;54481;55132.17301740397;56282;11905.642830715093;12412.62762117138;12476.376181190722;12899.419526121159;12906.161099528501;14310.642233944234;14670.193139377438;14814.185219813015;15108.320125594155;15188.848111519186;14257.358418870968;14257.50098746539;14444.494442694455;14545.927270364004;14671;12250.75498490043;12299;12325;12530.185537940004;12569.183003104768;13492.797608035691;13694.589162325146;13936.930315348764;14032.298385080501;14049.789253161414;9699.321047526888;10054.245931555017;10186.694399168036;10206.642767503101;10309.48452577353;11763.823542646696;11772.823407649063;11917.22538035025;12032.639020829389;12096.395180241128 18:34:14 INFO - PROCESS | 8154 | __end_tp_report 18:34:14 INFO - PROCESS | 8154 | __start_cc_report 18:34:14 INFO - PROCESS | 8154 | _x_x_mozilla_cycle_collect,1204 18:34:14 INFO - PROCESS | 8154 | __end_cc_report 18:34:14 INFO - PROCESS | 8154 | __startTimestamp1495503254514__endTimestamp 18:34:14 INFO - PROCESS | 8154 | 18:34:14 INFO - PROCESS | 8154 | ------- Summary: start ------- 18:34:14 INFO - PROCESS | 8154 | Number of tests: 6 18:34:14 INFO - PROCESS | 8154 | 18:34:14 INFO - PROCESS | 8154 | [#0] dojo.html Cycles:165 Average:5688.40 Median:5864.74 stddev:648.64 (11.1%) stddev-sans-first:624.87 18:34:14 INFO - PROCESS | 8154 | Values: 3381.1 3418.5 3420.8 3457.3 3474.7 5730.3 5753.8 5754.3 5771.6 5781.9 5753.9 5759.2 5803.7 5805.3 5807.4 5146.4 5153.0 5160.8 5166.7 5220.3 4946.0 5000.1 5230.3 5238.3 5271.9 5101.9 5112.2 5122.6 5126.7 5154.8 5207.9 5217.7 5225.4 5230.0 5251.6 4889.1 5068.8 5139.5 5188.9 5200.4 5527.2 5541.4 5545.2 5547.1 5548.4 5912.8 5950.8 5960.0 5960.7 5964.0 6000.1 6003.1 6173.5 6210.9 6240.6 5786.3 5795.1 5797.2 5803.7 5835.7 6273.3 6303.2 6310.6 6314.1 6326.7 5782.0 5834.1 6015.3 6051.4 6064.6 6179.6 6190.1 6193.6 6195.5 6201.0 6163.4 6167.4 6168.3 6171.0 6174.8 5875.3 5905.4 6056.2 6083.2 6138.4 4705.2 4725.6 4726.6 4729.5 4747.7 4188.6 4196.7 4200.1 4206.1 4228.0 5619.3 5659.0 5827.3 5858.6 5864.7 6174.3 6177.0 6180.5 6182.2 6183.3 5799.7 5850.8 5852.3 5877.4 5878.5 5062.7 5074.5 5236.3 5284.6 5290.8 6346.6 6378.2 6416.2 6424.9 6439.2 6189.3 6191.1 6205.4 6205.4 6214.0 6083.2 6461.2 6462.7 6466.8 6477.3 5977.2 5990.9 6055.7 6113.8 6118.4 6189.5 6228.7 6231.1 6237.5 6238.2 6029.2 6047.0 6053.8 6057.9 6061.9 5749.2 5793.3 5965.3 5986.0 6011.8 6005.0 6027.2 6060.7 6064.4 6092.1 5779.3 5826.5 6102.8 6105.6 6117.8 5663.3 5703.7 5714.3 5851.3 5898.6 18:34:14 INFO - PROCESS | 8154 | 18:34:14 INFO - PROCESS | 8154 | [#1] ext.html Cycles:180 Average:45144.73 Median:30919.77 stddev:77091.42 (249.3%) stddev-sans-first:77235.70 18:34:14 INFO - PROCESS | 8154 | Values: 775.1 849.7 865.8 927.5 931.6 31807.7 43597.0 44565.8 44570.3 44713.6 42264.4 42417.8 42527.0 45956.0 46285.0 7746.8 7937.7 9293.0 9404.4 9409.8 10676.5 10860.8 10861.2 10868.3 10869.9 10918.6 10988.0 11003.4 11013.6 11027.6 10890.7 10923.2 10933.6 10934.0 10935.5 10753.6 10802.9 10811.3 10812.4 10813.0 43550.3 43649.0 43729.3 43750.3 43790.3 4280.5 4381.0 4446.5 4475.9 4633.0 27430.0 27852.4 28010.6 28258.9 28576.1 4131.6 4197.8 4202.3 4214.3 4228.2 242296.0 242623.0 242656.0 242776.0 243639.0 45291.8 45713.3 45893.3 46170.5 46290.0 47573.0 47909.3 47915.8 47946.8 48034.5 15554.2 15590.0 15802.8 15821.9 15823.6 11321.3 11533.8 13448.0 13725.2 13731.4 12836.6 13054.3 13101.7 13135.1 13159.7 5506.3 5518.1 5543.8 5564.6 5635.1 932.0 954.5 957.9 958.5 972.3 26177.6 26203.7 26491.7 26532.5 26554.5 1002.5 1017.2 1030.6 1030.9 1033.6 13238.4 13789.7 13905.9 13921.9 14193.9 331667.0 333530.0 334094.0 334198.0 336520.0 58348.4 58634.7 58722.0 58822.4 58869.0 1437.1 1505.9 1519.0 1556.3 1572.9 28984.4 30064.9 30129.7 30530.7 30575.0 39114.4 39229.6 39301.8 39311.2 39319.0 299337.0 299813.0 300276.0 300286.0 300542.0 25731.5 27901.9 27924.4 32203.5 32268.5 33532.8 33551.3 33770.8 39721.2 39892.0 30743.8 31348.5 31486.5 31552.4 31701.8 30529.0 30771.7 31067.8 31161.7 31546.2 34933.8 35007.8 35054.3 35298.0 35739.0 29077.1 29278.6 29368.9 29380.6 29863.7 23389.8 24202.9 24229.0 24503.6 25137.5 18:34:14 INFO - PROCESS | 8154 | 18:34:14 INFO - PROCESS | 8154 | [#2] jquery.html Cycles:180 Average:32449.50 Median:12446.34 stddev:83459.23 (670.6%) stddev-sans-first:83658.77 18:34:14 INFO - PROCESS | 8154 | Values: 450.6 452.9 454.9 469.7 473.2 16028.4 16707.9 20385.8 22126.0 22612.3 20948.6 21222.3 21311.8 21410.9 21614.0 6523.3 6851.2 6856.6 6856.9 6865.4 4942.8 6770.8 6858.7 7734.3 7792.3 7627.5 8064.3 8068.5 8074.4 8084.4 7722.1 7808.4 7810.0 7813.5 7823.6 7548.0 7741.5 7770.9 7801.3 7803.5 25772.2 26664.0 26844.7 27125.9 27159.3 3629.6 3687.9 3698.6 3738.4 3764.1 16382.9 16662.2 16978.2 17022.9 17282.7 2160.8 2214.3 2218.7 2280.4 2284.8 96985.0 97261.5 98244.0 98280.5 100100.5 18911.1 19294.3 19459.8 19629.6 19988.0 36674.3 36779.0 36788.3 36881.8 37080.4 11112.7 11314.7 11440.3 11440.5 11525.7 9369.2 9392.5 9410.5 9592.4 9614.5 9897.9 10403.3 10502.2 10675.9 10768.8 3711.7 3791.3 3863.8 3958.8 4019.6 833.3 894.5 986.2 996.5 1022.9 22335.9 22400.8 22406.2 22410.0 22448.4 994.7 996.0 996.7 1009.7 1013.7 12129.2 12597.8 12901.4 13001.3 13011.5 292781.5 433065.0 574661.0 580056.0 581885.0 82148.6 90036.0 91159.0 91870.0 93298.0 1264.4 1267.1 1296.5 1302.9 1305.7 18796.2 19214.0 19372.9 19399.6 19544.9 45186.3 51255.5 56089.4 56252.7 56710.1 66646.0 66697.0 68197.0 68654.0 69110.3 11981.0 12093.2 12662.1 12692.8 12917.8 13758.3 13762.8 13769.7 13784.9 13855.0 11669.6 11842.0 13168.5 13314.8 13719.0 12091.1 12117.4 12172.7 12220.7 12433.3 12802.0 12806.7 12934.1 12935.0 13208.5 12976.4 12985.9 13000.8 13003.5 13119.2 12271.6 12526.4 12534.9 12669.0 13179.3 18:34:14 INFO - PROCESS | 8154 | 18:34:14 INFO - PROCESS | 8154 | [#3] mootools.html Cycles:180 Average:7115.46 Median:2455.83 stddev:4414.03 (179.7%) stddev-sans-first:4406.54 18:34:14 INFO - PROCESS | 8154 | Values: 1540.1 1573.2 1577.4 1582.4 1583.1 10735.4 11471.7 11480.4 11492.7 11503.2 10923.7 11220.2 11237.7 11247.4 11261.8 2507.7 2540.9 2544.3 2554.5 2559.0 2452.0 2523.3 2579.0 2584.4 2588.5 2434.3 2501.9 2512.3 2531.6 2532.0 2377.1 2435.6 2440.8 2482.9 2484.1 2420.6 2449.2 2459.7 2461.5 2462.3 10712.6 10888.1 10904.4 10938.8 10944.7 5763.7 5898.4 5904.1 5909.3 5916.0 3560.7 3616.7 3681.8 3794.6 3857.1 1360.4 1454.9 1455.5 1465.2 1496.4 15817.1 16055.4 16206.4 16214.8 16217.1 4752.4 4766.5 4783.4 4865.0 4882.1 12833.3 12833.6 12835.2 12848.2 12857.5 5890.9 5989.6 5989.8 5998.3 6007.7 5004.9 5324.3 5353.5 5405.9 5426.5 3941.5 4036.3 4085.1 4108.4 4140.9 1023.8 1026.4 1045.2 1045.2 1056.1 1075.0 1077.5 1082.7 1083.1 1091.9 6852.3 6916.0 6952.8 7087.9 7096.8 1046.5 1081.8 1090.4 1096.8 1119.1 4666.8 4678.3 4789.2 4840.1 4885.7 13586.1 14105.0 14193.0 14972.9 14973.8 12161.9 12467.5 14559.0 14560.1 14561.9 10199.2 10254.7 10365.0 10742.0 10808.9 7258.5 7549.8 7554.4 7642.8 7731.5 12974.1 13084.9 13204.5 13220.5 13238.3 13247.5 13274.5 13334.5 13395.6 13589.6 9813.6 10097.4 10107.3 10109.6 10110.8 10880.2 10886.5 10913.5 10961.7 10988.3 9197.8 9266.7 9353.6 9393.6 9474.5 8985.2 8986.3 9014.1 9060.0 9137.6 8395.7 8445.1 8533.8 8537.2 8538.7 6537.9 6570.0 6600.5 6647.1 6678.3 6407.8 6628.4 6680.7 6681.5 6696.1 18:34:14 INFO - PROCESS | 8154 | 18:34:14 INFO - PROCESS | 8154 | [#4] prototype.html Cycles:180 Average:4846.54 Median:5383.53 stddev:1290.16 (24.0%) stddev-sans-first:1249.90 18:34:14 INFO - PROCESS | 8154 | Values: 401.3 432.8 433.8 436.6 442.2 5351.6 5367.0 5383.6 5401.3 5420.8 5074.3 5303.7 5326.6 5337.6 5351.8 4320.8 4375.5 4384.9 4389.4 4412.3 4353.4 4380.5 4380.5 4410.4 4466.7 4391.0 4412.2 4423.2 4433.8 4466.3 4187.6 4371.7 4406.6 4409.7 4418.3 4189.6 4190.8 4191.3 4191.5 4195.9 5147.3 5185.2 5229.5 5272.9 5294.4 5048.7 5147.6 5156.9 5202.6 5238.2 5635.3 5640.4 5643.9 5650.2 5653.3 4539.0 4650.7 4700.8 4704.9 4717.9 5537.6 5579.7 5585.5 5685.8 5826.3 4606.0 4608.8 4613.4 4721.2 4739.4 4701.2 4762.4 4776.7 4782.7 4804.7 4872.9 4888.2 5060.2 5107.1 5150.2 5551.4 5558.4 5565.6 5577.4 5588.7 1657.3 1679.4 1683.8 1684.6 1691.1 1255.6 1297.9 1306.0 1315.7 1326.0 5160.9 5445.8 5453.8 5455.9 5462.0 5673.8 5712.7 5732.3 5771.0 5798.9 5497.8 5513.6 5514.3 5520.3 5522.9 2840.6 2974.5 2987.4 3002.5 3011.7 5913.0 5923.9 5944.6 5964.6 5994.4 5695.5 5783.8 5843.4 5878.4 5909.3 5883.5 5914.7 5915.4 5919.0 5990.7 5688.7 5709.6 5738.2 5741.0 5752.6 5383.5 5429.8 5553.2 5556.6 5673.0 5597.4 5721.7 5831.0 5934.3 5959.9 4752.2 4752.4 4754.4 4778.4 4778.4 5553.8 5570.6 5760.9 5786.5 5819.7 5457.5 5478.4 5478.9 5501.5 5505.0 5480.8 5502.7 5536.5 5591.4 5592.0 5311.5 5522.6 5543.3 5545.8 5578.6 5481.3 5534.8 5535.5 5547.6 5565.8 5217.5 5410.6 5436.9 5482.0 5606.6 18:34:14 INFO - PROCESS | 8154 | 18:34:14 INFO - PROCESS | 8154 | [#5] yui.html Cycles:180 Average:18438.04 Median:47501.52 stddev:22758.28 (47.9%) stddev-sans-first:22783.76 18:34:14 INFO - PROCESS | 8154 | Values: 841.4 875.2 878.1 889.8 926.1 17115.5 17983.6 18650.2 18685.8 18711.5 17214.7 17320.8 17401.6 17409.0 17440.7 883.3 908.1 909.2 924.8 928.7 917.8 920.4 921.4 922.5 922.8 970.4 971.7 972.1 972.5 972.9 965.5 970.7 971.4 972.5 974.0 715.9 719.4 719.6 720.5 721.1 18238.2 18257.9 18387.0 18849.2 19043.3 16222.4 16457.5 16464.4 16465.3 17065.7 13700.9 13757.0 13990.2 14005.4 14174.8 3970.4 4000.1 4010.2 4067.6 4165.6 47326.8 55610.0 63248.7 80157.6 88290.0 12330.3 12547.7 12623.6 12634.7 12640.1 23386.0 23473.6 23753.2 23778.3 23956.0 8684.5 8751.9 8788.7 9039.4 9040.1 5006.5 5011.8 5096.3 5172.6 5217.4 3843.9 3935.4 3941.3 3947.8 3948.7 667.7 669.2 671.7 687.0 692.9 841.7 868.4 872.8 880.3 882.2 11568.2 11946.6 12027.3 12169.9 12448.4 761.6 762.7 769.1 791.9 795.5 5569.9 5693.6 5849.0 5853.6 5867.1 75692.0 83540.0 84438.0 85156.6 88662.6 72048.0 72389.0 73602.6 74920.3 76161.6 66206.7 66454.7 67521.0 68154.0 69669.7 10957.5 11064.4 11140.4 11375.1 11478.3 47263.0 47676.3 47875.5 48110.0 48311.0 53187.7 53910.5 54481.0 55132.2 56282.0 11905.6 12412.6 12476.4 12899.4 12906.2 14310.6 14670.2 14814.2 15108.3 15188.8 14257.4 14257.5 14444.5 14545.9 14671.0 12250.8 12299.0 12325.0 12530.2 12569.2 13492.8 13694.6 13936.9 14032.3 14049.8 9699.3 10054.2 10186.7 10206.6 10309.5 11763.8 11772.8 11917.2 12032.6 12096.4 18:34:14 INFO - PROCESS | 8154 | -------- Summary: end -------- 18:34:14 INFO - PROCESS | 8154 | 18:34:15 INFO - TEST-INFO | 8154: exit 0 18:34:15 INFO - TEST-OK | dromaeo_css | took 1114751ms 18:34:15 INFO - TEST-START | kraken 18:34:15 INFO - operating with platform_type : linux_ 18:34:15 INFO - Initialising browser for kraken test... 18:34:15 INFO - TEST-INFO | started process 8461 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeNzOGS/profile http://localhost:33241/getInfo.html) 18:34:19 INFO - PROCESS | 8461 | __metrics Screen width/height:1600/1200 18:34:19 INFO - PROCESS | 8461 | colorDepth:24 18:34:19 INFO - PROCESS | 8461 | Browser inner width/height: 1024/697 18:34:19 INFO - PROCESS | 8461 | __metrics 18:34:20 INFO - PROCESS | 8461 | 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" 18:34:20 INFO - PROCESS | 8461 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:34:21 INFO - TEST-INFO | 8461: exit 0 18:34:21 INFO - Browser initialized. 18:34:21 INFO - Running cycle 1/1 for kraken test... 18:34:21 INFO - TEST-INFO | started process 8550 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeNzOGS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:34:33 INFO - PROCESS | 8550 | RSS: Main: 141156352 18:34:33 INFO - PROCESS | 8550 | 18:34:35 INFO - PROCESS | 8550 | Cycle 1(1): loaded http://localhost:33241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33241/tests/kraken/driver.html?testName=audio-beat-detection) 18:34:36 INFO - PROCESS | 8550 | RSS: Main: 184614912 18:34:36 INFO - PROCESS | 8550 | 18:34:38 INFO - PROCESS | 8550 | Cycle 1(1): loaded http://localhost:33241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33241/tests/kraken/driver.html?testName=audio-dft) 18:34:39 INFO - PROCESS | 8550 | RSS: Main: 174178304 18:34:39 INFO - PROCESS | 8550 | 18:34:42 INFO - PROCESS | 8550 | Cycle 1(1): loaded http://localhost:33241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33241/tests/kraken/driver.html?testName=audio-fft) 18:34:42 INFO - PROCESS | 8550 | RSS: Main: 265945088 18:34:42 INFO - PROCESS | 8550 | 18:34:44 INFO - PROCESS | 8550 | Cycle 1(1): loaded http://localhost:33241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33241/tests/kraken/driver.html?testName=audio-oscillator) 18:34:45 INFO - PROCESS | 8550 | RSS: Main: 172023808 18:34:45 INFO - PROCESS | 8550 | 18:34:47 INFO - PROCESS | 8550 | Cycle 1(1): loaded http://localhost:33241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:34:48 INFO - PROCESS | 8550 | RSS: Main: 174047232 18:34:48 INFO - PROCESS | 8550 | 18:34:51 INFO - PROCESS | 8550 | Cycle 1(1): loaded http://localhost:33241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33241/tests/kraken/driver.html?testName=imaging-darkroom) 18:34:51 INFO - PROCESS | 8550 | RSS: Main: 213057536 18:34:51 INFO - PROCESS | 8550 | 18:34:55 INFO - PROCESS | 8550 | Cycle 1(1): loaded http://localhost:33241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33241/tests/kraken/driver.html?testName=imaging-desaturate) 18:34:55 INFO - PROCESS | 8550 | RSS: Main: 213512192 18:34:55 INFO - PROCESS | 8550 | 18:34:58 INFO - PROCESS | 8550 | Cycle 1(1): loaded http://localhost:33241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33241/tests/kraken/driver.html?testName=json-parse-financial) 18:34:59 INFO - PROCESS | 8550 | RSS: Main: 211591168 18:34:59 INFO - PROCESS | 8550 | 18:35:00 INFO - PROCESS | 8550 | Cycle 1(1): loaded http://localhost:33241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:35:01 INFO - PROCESS | 8550 | RSS: Main: 174133248 18:35:01 INFO - PROCESS | 8550 | 18:35:02 INFO - PROCESS | 8550 | Cycle 1(1): loaded http://localhost:33241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33241/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:35:03 INFO - PROCESS | 8550 | RSS: Main: 181047296 18:35:03 INFO - PROCESS | 8550 | 18:35:05 INFO - PROCESS | 8550 | Cycle 1(1): loaded http://localhost:33241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:35:06 INFO - PROCESS | 8550 | RSS: Main: 189636608 18:35:06 INFO - PROCESS | 8550 | 18:35:08 INFO - PROCESS | 8550 | Cycle 1(1): loaded http://localhost:33241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:35:09 INFO - PROCESS | 8550 | RSS: Main: 199385088 18:35:09 INFO - PROCESS | 8550 | 18:35:12 INFO - PROCESS | 8550 | Cycle 1(1): loaded http://localhost:33241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:35:12 INFO - PROCESS | 8550 | RSS: Main: 196214784 18:35:12 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | Cycle 1(1): loaded http://localhost:33241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33241/tests/kraken/driver.html?testName=ai-astar) 18:35:14 INFO - PROCESS | 8550 | RSS: Main: 247750656 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | __start_tp_report 18:35:14 INFO - PROCESS | 8550 | _x_x_mozilla_page_load 18:35:14 INFO - PROCESS | 8550 | _x_x_mozilla_page_load_details 18:35:14 INFO - PROCESS | 8550 | |i|pagename|runs| 18:35:14 INFO - PROCESS | 8550 | |0;ai-astar;104;111;111;112;113;113;112;111;114;112 18:35:14 INFO - PROCESS | 8550 | |1;audio-beat-detection;137;115;118;157;121;119;119;115;138;116 18:35:14 INFO - PROCESS | 8550 | |2;audio-dft;161;160;161;160;163;164;161;163;162;163 18:35:14 INFO - PROCESS | 8550 | |3;audio-fft;74;75;77;119;79;76;76;77;90;77 18:35:14 INFO - PROCESS | 8550 | |4;audio-oscillator;82;82;106;82;83;83;92;83;81;106 18:35:14 INFO - PROCESS | 8550 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;112;114;113 18:35:14 INFO - PROCESS | 8550 | |6;imaging-darkroom;146;145;145;146;147;145;146;146;146;146 18:35:14 INFO - PROCESS | 8550 | |7;imaging-desaturate;95;94;94;94;93;93;93;94;94;94 18:35:14 INFO - PROCESS | 8550 | |8;json-parse-financial;57;55;57;55;55;57;58;56;56;56 18:35:14 INFO - PROCESS | 8550 | |9;json-stringify-tinderbox;55;53;53;52;52;53;54;53;53;53 18:35:14 INFO - PROCESS | 8550 | |10;stanford-crypto-aes;76;84;77;74;76;81;78;78;75;81 18:35:14 INFO - PROCESS | 8550 | |11;stanford-crypto-ccm;131;122;119;119;119;118;117;118;159;120 18:35:14 INFO - PROCESS | 8550 | |12;stanford-crypto-pbkdf2;157;157;163;162;163;161;165;165;164;159 18:35:14 INFO - PROCESS | 8550 | |13;stanford-crypto-sha256-iterative;61;61;61;60;60;60;60;59;59;60 18:35:14 INFO - PROCESS | 8550 | __end_tp_report 18:35:14 INFO - PROCESS | 8550 | __start_cc_report 18:35:14 INFO - PROCESS | 8550 | _x_x_mozilla_cycle_collect,1658 18:35:14 INFO - PROCESS | 8550 | __end_cc_report 18:35:14 INFO - PROCESS | 8550 | __startTimestamp1495503314578__endTimestamp 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | ------- Summary: start ------- 18:35:14 INFO - PROCESS | 8550 | Number of tests: 14 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:2.75 (2.5%) stddev-sans-first:1.05 18:35:14 INFO - PROCESS | 8550 | Values: 104.0 111.0 111.0 112.0 113.0 113.0 112.0 111.0 114.0 112.0 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | [#1] audio-beat-detection Cycles:10 Average:125.50 Median:120.00 stddev:13.95 (11.6%) stddev-sans-first:14.17 18:35:14 INFO - PROCESS | 8550 | Values: 137.0 115.0 118.0 157.0 121.0 119.0 119.0 115.0 138.0 116.0 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.50 stddev:1.40 (0.9%) stddev-sans-first:1.45 18:35:14 INFO - PROCESS | 8550 | Values: 161.0 160.0 161.0 160.0 163.0 164.0 161.0 163.0 162.0 163.0 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | [#3] audio-fft Cycles:10 Average:82.00 Median:77.00 stddev:13.75 (17.9%) stddev-sans-first:14.28 18:35:14 INFO - PROCESS | 8550 | Values: 74.0 75.0 77.0 119.0 79.0 76.0 76.0 77.0 90.0 77.0 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | [#4] audio-oscillator Cycles:10 Average:88.00 Median:82.50 stddev:9.98 (12.1%) stddev-sans-first:10.34 18:35:14 INFO - PROCESS | 8550 | Values: 82.0 82.0 106.0 82.0 83.0 83.0 92.0 83.0 81.0 106.0 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 18:35:14 INFO - PROCESS | 8550 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 112.0 114.0 113.0 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 18:35:14 INFO - PROCESS | 8550 | Values: 146.0 145.0 145.0 146.0 147.0 145.0 146.0 146.0 146.0 146.0 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 18:35:14 INFO - PROCESS | 8550 | Values: 95.0 94.0 94.0 94.0 93.0 93.0 93.0 94.0 94.0 94.0 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:56.50 stddev:1.03 (1.8%) stddev-sans-first:1.05 18:35:14 INFO - PROCESS | 8550 | Values: 57.0 55.0 57.0 55.0 55.0 57.0 58.0 56.0 56.0 56.0 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | [#9] json-stringify-tinderbox Cycles:10 Average:53.10 Median:53.00 stddev:0.88 (1.7%) stddev-sans-first:0.60 18:35:14 INFO - PROCESS | 8550 | Values: 55.0 53.0 53.0 52.0 52.0 53.0 54.0 53.0 53.0 53.0 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.00 stddev:3.13 (4.0%) stddev-sans-first:3.23 18:35:14 INFO - PROCESS | 8550 | Values: 76.0 84.0 77.0 74.0 76.0 81.0 78.0 78.0 75.0 81.0 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | [#11] stanford-crypto-ccm Cycles:10 Average:124.20 Median:119.50 stddev:12.87 (10.8%) stddev-sans-first:13.41 18:35:14 INFO - PROCESS | 8550 | Values: 131.0 122.0 119.0 119.0 119.0 118.0 117.0 118.0 159.0 120.0 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.60 Median:163.00 stddev:3.03 (1.9%) stddev-sans-first:2.71 18:35:14 INFO - PROCESS | 8550 | Values: 157.0 157.0 163.0 162.0 163.0 161.0 165.0 165.0 164.0 159.0 18:35:14 INFO - PROCESS | 8550 | 18:35:14 INFO - PROCESS | 8550 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.10 Median:60.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 18:35:14 INFO - PROCESS | 8550 | Values: 61.0 61.0 61.0 60.0 60.0 60.0 60.0 59.0 59.0 60.0 18:35:14 INFO - PROCESS | 8550 | -------- Summary: end -------- 18:35:14 INFO - PROCESS | 8550 | 18:35:15 INFO - TEST-INFO | 8550: exit 0 18:35:15 INFO - TEST-OK | kraken | took 60344ms 18:35:15 INFO - SUITE-END | took 1175s 18:35:15 INFO - Completed test suite (00:19:35) 18:35:15 INFO - javascript benchmark 18:35:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7852.673469050548, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3381.0871064812495, 3418.538497302865, 3420.777649452786, 3457.2912552926623, 3474.704650104741, 5730.312362516499, 5753.827385178452, 5754.338251101125, 5771.595988280822, 5781.855453613662, 5753.913691294634, 5759.16492108644, 5803.709814509279, 5805.3323867755225, 5807.419258074201, 5146.356705411824, 5152.974235128838, 5160.845174644757, 5166.664166829163, 5220.347456567929, 4945.950540494585, 5000.0999820032375, 5230.34620672416, 5238.319018527586, 5271.9472805272035, 5101.923471147936, 5112.182050871861, 5122.6158038147105, 5126.71803050832, 5154.768035438415, 5207.921881171786, 5217.686938783666, 5225.399079105913, 5230.032443997854, 5251.553617942442, 4889.09551732927, 5068.847934561969, 5139.511746384088, 5188.92216616751, 5200.3759548854005, 5527.226188333637, 5541.418151094158, 5545.195946587723, 5547.1124620061, 5548.389677135512, 5912.763489460416, 5950.7917222896995, 5960.0, 5960.731767070492, 5964.0, 6000.099985002241, 6003.099535069731, 6173.475254603364, 6210.937890621081, 6240.625562466266, 5786.334571524287, 5795.101759227242, 5797.217375654275, 5803.738831752581, 5835.708214589296, 6273.309935907047, 6303.212098487689, 6310.5898116622275, 6314.116023756677, 6326.746930122744, 5781.971090144522, 5834.066549352168, 6015.278166619946, 6051.364606716232, 6064.575479716377, 6179.62922224659, 6190.102435146879, 6193.628382297076, 6195.473384762256, 6201.0, 6163.445289923929, 6167.414095660905, 6168.25980882297, 6171.043488259327, 6174.845628859314, 5875.265591801025, 5905.409459054146, 6056.242969628796, 6083.239595050619, 6138.357744607992, 4705.223638099744, 4725.574698277172, 4726.574608285202, 4729.503402142795, 4747.667663263538, 4188.6230239278, 4196.664266858644, 4200.138971510833, 4206.137741762931, 4228.027553662675, 5619.26949496571, 5659.037963546207, 5827.271591051119, 5858.56060795442, 5864.736086876101, 6174.25908890927, 6176.969115154396, 6180.536459765535, 6182.227221597301, 6183.319834818167, 5799.681017544076, 5850.765969361263, 5852.268466441695, 5877.4122587740885, 5878.5297176225795, 5062.670926389773, 5074.49255074497, 5236.319278493847, 5284.630075894651, 5290.841274761763, 6346.587471814318, 6378.170837791057, 6416.165898433173, 6424.903626445701, 6439.162908822011, 6189.288231853395, 6191.102290167991, 6205.379462053939, 6205.410486003822, 6214.0, 6083.209182806207, 6461.224653041665, 6462.676866156768, 6466.805995820133, 6477.319881412383, 5977.163197152315, 5990.940090599038, 6055.697215139137, 6113.810786210694, 6118.418750218722, 6189.535784816247, 6228.688565571794, 6231.065340199007, 6237.5321850860455, 6238.220222472191, 6029.186059881859, 6047.03247480401, 6053.818385448443, 6057.939420605914, 6061.908856405713, 5749.166370876116, 5793.304803423616, 5965.313988891333, 5986.0, 6011.849703757441, 6004.969975150097, 6027.156198132353, 6060.6666633333825, 6064.363241859718, 6092.147099405998, 5779.2775903012125, 5826.475617194473, 6102.755889764359, 6105.633661980295, 6117.785877494266, 5663.292088488939, 5703.714814259353, 5714.342850572238, 5851.297844258716, 5898.64608123514], "value": 5645.739899915736, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [775.1163673412308, 849.6643825688851, 865.7922098696313, 927.4852456886425, 931.5714771205245, 31807.68192318076, 43597.0, 44565.77717111418, 44570.33144502827, 44713.552864471385, 42264.366034509505, 42417.78791106048, 42527.0, 45956.0, 46285.0, 7746.806329841771, 7937.722179723711, 9292.953535232347, 9404.388714733555, 9409.76475588109, 10676.51955661997, 10860.782784344308, 10861.18541109416, 10868.347899126038, 10869.945650271737, 10918.563257469692, 10988.011079002888, 11003.449827508633, 11013.61452349168, 11027.61403350883, 10890.673279801618, 10923.180761442844, 10933.617323393722, 10934.0, 10935.507902144383, 10753.569857205703, 10802.945985270102, 10811.297265677706, 10812.35125892449, 10813.026827585476, 43550.34674479885, 43649.0, 43729.344059839124, 43750.343744843856, 43790.34314485285, 4280.464941882265, 4381.036172042146, 4446.4886538047895, 4475.932861007088, 4633.027064316498, 27430.0, 27852.442951140863, 28010.5798413024, 28258.858705706545, 28576.14271571856, 4131.566185550504, 4197.832086716528, 4202.348635961431, 4214.346776249687, 4228.154369126156, 242296.0, 242623.0, 242656.0, 242776.0, 243639.0, 45291.77354113208, 45713.31430028552, 45893.31160032602, 46170.53829461662, 46290.0, 47573.0, 47909.28136077962, 47915.84378702967, 47946.76026619914, 48034.51965480301, 15554.22228888551, 15590.0, 15802.841971580367, 15821.920890395704, 15823.604409889615, 11321.26411783248, 11533.826992595117, 13448.0, 13725.245111518763, 13731.382087806072, 12836.550720724917, 13054.282014489294, 13101.737965240642, 13135.080544361803, 13159.736805263841, 5506.256655351556, 5518.144687573433, 5543.750531226105, 5564.610477266552, 5635.098384258581, 931.9953400232955, 954.4654993203828, 957.8611101390399, 958.5063692198523, 972.3485264872411, 26177.607335889978, 26203.73796262013, 26491.735082648927, 26532.469350613264, 26554.46891062168, 1002.4586723169407, 1017.211660962745, 1030.577463240083, 1030.9278350515392, 1033.5504055735778, 13238.404271807793, 13789.724205515833, 13905.86094139066, 13921.930390347983, 14193.85806141946, 331667.0, 333530.0, 334094.0, 334198.0, 336520.0, 58348.416515835146, 58634.7068264656, 58722.0, 58822.41177588255, 58869.0, 1437.1377173695698, 1505.9322330495154, 1518.9544313670388, 1556.2763315058553, 1572.8977616454895, 28984.42031159323, 30064.947726829458, 30129.698703013568, 30530.694693053672, 30575.0, 39114.4132838002, 39229.60770392259, 39301.80349098236, 39311.21377572375, 39319.0, 299337.0, 299813.0, 300276.0, 300286.0, 300542.0, 25731.485370292863, 27901.86049069823, 27924.44151117007, 32203.51694724628, 32268.515972260906, 33532.83233583817, 33551.32897342088, 33770.83114584412, 39721.2055758889, 39892.0, 30743.846280768452, 31348.529772053895, 31486.527702084946, 31552.36895262036, 31701.841490793322, 30529.0, 30771.692283077777, 31067.84466077655, 31161.688383115878, 31546.211344716565, 34933.777317793756, 35007.82496087605, 35054.29891402209, 35298.0, 35739.0, 29077.12768616945, 29278.560821587267, 29368.853155734083, 29380.55929161107, 29863.701362986092, 23389.76610233876, 24202.87898560496, 24229.0, 24503.632445512976, 25137.49725005453], "value": 18393.663307409297, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.61922675339326, 452.902625935424, 454.9317602359646, 469.73929469144633, 473.2191883392402, 16028.35886564536, 16707.91646041768, 20385.796142038576, 22126.0, 22612.321630351096, 20948.58102837946, 21222.257220997268, 21311.786882131175, 21410.892945535288, 21614.0, 6523.347665233474, 6851.246362900077, 6856.554323968926, 6856.897146542806, 6865.416439602614, 4942.827001054946, 6770.796876093725, 6858.72565097398, 7734.265244801735, 7792.298693117618, 7627.542347459143, 8064.27421532062, 8068.47554908929, 8074.394420418463, 8084.353251739847, 7722.111957124918, 7808.375329973588, 7809.960950195269, 7813.531188128731, 7823.608819559056, 7547.981022561994, 7741.458097933147, 7770.883436748453, 7801.336886364666, 7803.453758236926, 25772.226833194847, 26664.0, 26844.731552684418, 27125.864370678217, 27159.321016974536, 3629.6007439181667, 3687.8893633191046, 3698.6116457771814, 3738.4392341148773, 3764.1154328732728, 16382.918085409498, 16662.166891655368, 16978.235979380835, 17022.914885425616, 17282.740758888627, 2160.8055275025167, 2214.2803588833694, 2218.7115674962315, 2280.3614987803276, 2284.7601001894727, 96985.0, 97261.51369243108, 98244.0, 98280.50859745727, 100100.49949750205, 18911.14899829511, 19294.32469863548, 19459.805401946083, 19629.607407852047, 19988.0, 36674.26651466956, 36779.0, 36788.264234715156, 36881.78709277452, 37080.44379334312, 11112.722181945353, 11314.660560183207, 11440.256383335223, 11440.542378304775, 11525.654230373102, 9369.203617692572, 9392.483413412328, 9410.482423466638, 9592.424454532751, 9614.471204083702, 9897.851532226878, 10403.271770976111, 10502.159827213805, 10675.89324106749, 10768.78462430747, 3711.665950064453, 3791.279656865187, 3863.7874916879277, 3958.8218530166214, 4019.577944315805, 833.3125171733344, 894.5169608411385, 986.1765425869336, 996.4668902137322, 1022.867027286448, 22335.888320558293, 22400.77599223987, 22406.215782447536, 22409.991550380273, 22448.438789030406, 994.7264502261936, 995.9601615935281, 996.6810620601337, 1009.6769033909079, 1013.7465633591602, 12129.21160124589, 12597.811032834516, 12901.419436125398, 13001.284929328789, 13011.479540818262, 292781.5360923182, 433065.0, 574661.0, 580056.0, 581885.0, 82148.58925705333, 90036.0, 91159.0, 91870.0, 93298.0, 1264.4120483974996, 1267.1320145700224, 1296.5138073222543, 1302.8957683385495, 1305.654001689534, 18796.248150073843, 19214.0, 19372.903135484798, 19399.612007759482, 19544.90227548853, 45186.32220516629, 51255.48744512507, 56089.43910560842, 56252.71873640606, 56710.14934775899, 66646.0, 66697.0, 68197.0, 68654.0, 69110.30889691038, 11980.981616562342, 12093.213941093802, 12662.113652044269, 12692.809607855705, 12917.806232906327, 13758.31208439554, 13762.793558096837, 13769.655758606115, 13784.931075344559, 13855.0, 11669.591564295548, 11842.0, 13168.539101131797, 13314.800277996032, 13719.0, 12091.093168012258, 12117.394130293274, 12172.695682607651, 12220.694482638006, 12433.254004759743, 12802.0, 12806.743865122458, 12934.094613376974, 12935.0, 13208.471661133448, 12976.416061277076, 12985.935070324966, 13000.804987925378, 13003.544875929298, 13119.212847229197, 12271.57049503263, 12526.436310366238, 12534.874651253736, 12669.049821263257, 13179.341032948507], "value": 11714.711883377702, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1540.0682587034844, 1573.1662219023922, 1577.440008796877, 1582.4224158182253, 1583.0897234090398, 10735.355878647277, 11471.713207169825, 11480.368579728112, 11492.655220343382, 11503.194776365637, 10923.672289831318, 11220.158488113384, 11237.719057023578, 11247.381394023325, 11261.831072533918, 2507.674002379693, 2540.9110681126162, 2544.2875994721508, 2554.540182767101, 2558.9744102559016, 2452.0, 2523.255639586317, 2578.987105064472, 2584.4443444659396, 2588.5081834451394, 2434.3427274635837, 2501.9374515637164, 2512.258883629334, 2531.582288922335, 2531.9746802532013, 2377.1204654278026, 2435.610302351624, 2440.8413453125577, 2482.937926551833, 2484.1429706751323, 2420.6490058941354, 2449.1795248591743, 2459.6679448274613, 2461.483088551406, 2462.2982450001664, 10712.571497140105, 10888.074513666348, 10904.400257985808, 10938.781224375469, 10944.726381840437, 5763.711814409263, 5898.4101589840675, 5904.084866845646, 5909.2908850938165, 5915.970420147914, 3560.7151427885965, 3616.72874534411, 3681.815909204529, 3794.620537946211, 3857.1321452673205, 1360.408222423239, 1454.9418023279056, 1455.468753904817, 1465.2087872548811, 1496.4280739458375, 15817.130057846814, 16055.357785688559, 16206.35174593015, 16214.837851621569, 16217.108059056747, 4752.397150533631, 4766.523347665206, 4783.402074740658, 4865.026994601094, 4882.145624515767, 12833.294168820805, 12833.614991550268, 12835.229886206858, 12848.164869283466, 12857.485700572059, 5890.9410905891245, 5989.610675306091, 5989.790357337472, 5998.25021872266, 6007.699615019319, 5004.9499505005215, 5324.334458192726, 5353.544948679406, 5405.918911216335, 5426.484483974016, 3941.546722126992, 4036.3138266494766, 4085.101277718897, 4108.4453598764385, 4140.937885931713, 1023.8464230365505, 1026.4098143567478, 1045.1795340657548, 1045.2056437107701, 1056.0864851903202, 1074.99462502687, 1077.488193108283, 1082.7022568793486, 1083.111848284415, 1091.8689757229024, 6852.280510546321, 6916.0, 6952.826179345456, 7087.858242835115, 7096.7516136934955, 1046.450613427941, 1081.8160912644869, 1090.4002798460974, 1096.7970925378831, 1119.121489630636, 4666.836660716858, 4678.274867395559, 4789.2337226044365, 4840.10458065252, 4885.682430642068, 13586.116902401312, 14105.0, 14193.0, 14972.850271497364, 14973.775393369107, 12161.878381216251, 12467.5012999479, 14559.0, 14560.126392416278, 14561.92719036398, 10199.18406527476, 10254.692359229384, 10365.0, 10742.0, 10808.94595527033, 7258.528195667264, 7549.811254718676, 7554.395648348229, 7642.8089297768, 7731.536107833548, 12974.091813572959, 13084.934575327063, 13204.537841175512, 13220.47118115303, 13238.338083096, 13247.470101195844, 13274.469021239041, 13334.533291334757, 13395.598132056053, 13589.592312230649, 9813.558389872616, 10097.44464054484, 10107.292489525662, 10109.595616175271, 10110.848337275094, 10880.238383313408, 10886.51010704536, 10913.508892100033, 10961.725956851142, 10988.285761425483, 9197.77005574866, 9266.721998340061, 9353.579088941151, 9393.577289021874, 9474.526273686426, 8985.191332780081, 8986.32602554798, 9014.098590141195, 9059.954700226457, 9137.63449462014, 8395.748127556182, 8445.071042185478, 8533.786655333666, 8537.18896704812, 8538.743837684879, 6537.934620653733, 6570.0, 6600.471962242913, 6647.102641143577, 6678.332166783282, 6407.775727849503, 6628.370304821035, 6680.720924017773, 6681.498887583353, 6696.129503164558], "value": 5450.137552005832, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.3357892687603, 432.7521947781896, 433.763598838633, 436.6463164836481, 442.1554830274176, 5351.625386222956, 5367.0, 5383.573353061437, 5401.297831281938, 5420.810271640494, 5074.264231686403, 5303.65526240794, 5326.627136100465, 5337.599680023995, 5351.822599028217, 4320.7901787499395, 4375.453068366454, 4384.912301753971, 4389.385486031958, 4412.316091005883, 4353.412289340946, 4380.474343078835, 4380.540043295456, 4410.448693913261, 4466.709663871838, 4390.956090439103, 4412.161689279027, 4423.203823311803, 4433.822647094113, 4466.3300504924355, 4187.560306167854, 4371.650267978554, 4406.5593440655675, 4409.706952384774, 4418.3372494125815, 4189.581041895816, 4190.769507677077, 4191.3293872980485, 4191.455110835603, 4195.895102622429, 5147.349115205672, 5185.196294574347, 5229.477052294778, 5272.945410917831, 5294.41761406245, 5048.7223202723835, 5147.613928955343, 5156.948430515685, 5202.621305354084, 5238.192823475894, 5635.295588051494, 5640.379558248589, 5643.887122257573, 5650.237217975562, 5653.293338332708, 4538.99863929365, 4650.74429903924, 4700.788464519105, 4704.858854234379, 4717.9292310615365, 5537.612367134302, 5579.748911298961, 5585.4973052425885, 5685.805980744049, 5826.271716035496, 4606.032733126014, 4608.838690645878, 4613.446386433649, 4721.197396442613, 4739.383880095635, 4701.153792317347, 4762.380890484214, 4776.713397196179, 4782.665213435026, 4804.73573953429, 4872.902541949141, 4888.2178851383605, 5060.2156665716875, 5107.131787596118, 5150.201718733602, 5551.3893471718075, 5558.388577256498, 5565.552956231328, 5577.410438025144, 5588.720563971785, 1657.3453485873013, 1679.3618424998667, 1683.7876728755277, 1684.553593297777, 1691.1037150310356, 1255.5605538061789, 1297.8507471640694, 1305.9934700326437, 1315.6645055510976, 1326.0, 5160.948390516047, 5445.774700692313, 5453.836384908459, 5455.863603409946, 5461.972690136524, 5673.801416950387, 5712.686641747776, 5732.34078081026, 5770.971145144247, 5798.8550286242335, 5497.835064947978, 5513.641613295204, 5514.338279406497, 5520.282363292747, 5522.91715624266, 2840.6449193850767, 2974.479466093425, 2987.3726517431583, 3002.4745669507747, 3011.6536598291477, 5913.048693931079, 5923.889957772118, 5944.64332140073, 5964.642121472725, 5994.400559943971, 5695.5191650171255, 5783.797567085216, 5843.415658434123, 5878.441548052928, 5909.261342332208, 5883.529317654577, 5914.6746928918465, 5915.37888521699, 5919.0, 5990.670513121733, 5688.687122208318, 5709.628874123335, 5738.196652468574, 5740.990653271321, 5752.597318187687, 5383.488568585978, 5429.809956651498, 5553.16702494629, 5556.611037227355, 5672.971635141798, 5597.356304025089, 5721.654121810038, 5831.0, 5934.329551701838, 5959.93063572252, 4752.168370535212, 4752.405949256343, 4754.405699287589, 4778.37881075458, 4778.402699662543, 5553.777848886161, 5570.5543556516195, 5760.876629057294, 5786.537077033743, 5819.748754017737, 5457.50719539503, 5478.369987451335, 5478.945210547843, 5501.5323697486165, 5504.972475137599, 5480.753366098455, 5502.724863756876, 5536.474034966672, 5591.384947655673, 5591.972040139936, 5311.45967669387, 5522.641028333145, 5543.307086614173, 5545.7781688732, 5578.553715702653, 5481.328194900593, 5534.833954981357, 5535.5054135384025, 5547.611667183259, 5565.805196818092, 5217.50433708797, 5410.594205434687, 5436.891262174813, 5482.0, 5606.579506537108], "value": 4474.8437679650115, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [841.43203337747, 875.1685898396521, 878.1218781218781, 889.8353804546158, 926.1386910173538, 17115.48653540393, 17983.64032719348, 18650.160742766573, 18685.813141868577, 18711.532211694714, 17214.741778873296, 17320.826791732077, 17401.564960875952, 17409.0, 17440.738388924176, 883.2977782662798, 908.0783005249676, 909.2226146644623, 924.7688077980505, 928.6517555916531, 917.807260475301, 920.4477313611847, 921.4425272710015, 922.4603606889943, 922.8154369126169, 970.4129001953822, 971.6550624528297, 972.0522490571707, 972.4602845420789, 972.8637990681275, 965.507591128526, 970.7476056225362, 971.4462756228952, 972.5234635028855, 973.990260097397, 715.8675645005691, 719.4172720096739, 719.6150059718026, 720.5208536323339, 721.0662192460776, 18238.179281932345, 18257.908710456497, 18387.0, 18849.246030158778, 19043.333483328155, 16222.351105955748, 16457.506274811654, 16464.42374516886, 16465.34138634453, 17065.65868682632, 13700.93149534256, 13757.0, 13990.230537320444, 14005.439782408692, 14174.787378189336, 3970.4441378207075, 4000.119973605831, 4010.2380547695843, 4067.6135767102373, 4165.604267594574, 47326.763366182946, 55610.0, 63248.683756581384, 80157.59921200415, 88290.0, 12330.321832299309, 12547.686307842378, 12623.621291361274, 12634.747305053848, 12640.11519193666, 23386.0, 23473.647895281585, 23753.16863909755, 23778.286651400485, 23956.0, 8684.478931264144, 8751.868721969047, 8788.692395766118, 9039.367244292836, 9040.141186587263, 5006.4743201964, 5011.799528018912, 5096.337476128154, 5172.5603323717905, 5217.399999000164, 3843.8846834595006, 3935.370340745467, 3941.3299739044432, 3947.782871942071, 3948.7038472114705, 667.7062092679206, 669.223700507409, 671.6809515480205, 687.0, 692.944564434844, 841.7138173021202, 868.3660927522943, 872.829798189347, 880.3265501891059, 882.2456799436518, 11568.190226684219, 11946.641600751991, 12027.338496382608, 12169.939150304192, 12448.439820207932, 761.6153842309598, 762.6568044380051, 769.1347234361343, 791.8930944322443, 795.4749865089013, 5569.860753481195, 5693.601447898607, 5849.0, 5853.560982926298, 5867.061270196748, 75692.0, 83540.0, 84438.0, 85156.57421712852, 88662.55668721744, 72048.0, 72389.0, 73602.63198684079, 74920.25079749242, 76161.61919190368, 66206.66896665582, 66454.66772666106, 67521.0, 68154.0, 69669.65165174243, 10957.452127393439, 11064.391458469861, 11140.387278699749, 11375.146863985068, 11478.253913495597, 47263.0, 47676.284855727885, 47875.5212447885, 48110.0, 48311.0, 53187.734061329444, 53910.46089539054, 54481.0, 55132.17301740397, 56282.0, 11905.642830715093, 12412.62762117138, 12476.376181190722, 12899.419526121159, 12906.161099528501, 14310.642233944234, 14670.193139377438, 14814.185219813015, 15108.320125594155, 15188.848111519186, 14257.358418870968, 14257.50098746539, 14444.494442694455, 14545.927270364004, 14671.0, 12250.75498490043, 12299.0, 12325.0, 12530.185537940004, 12569.183003104768, 13492.797608035691, 13694.589162325146, 13936.930315348764, 14032.298385080501, 14049.789253161414, 9699.321047526888, 10054.245931555017, 10186.694399168036, 10206.642767503101, 10309.48452577353, 11763.823542646696, 11772.823407649063, 11917.22538035025, 12032.639020829389, 12096.395180241128], "value": 7902.449359541346, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1454.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 111.0, 112.0, 113.0, 113.0, 112.0, 111.0, 114.0, 112.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 115.0, 118.0, 157.0, 121.0, 119.0, 119.0, 115.0, 138.0, 116.0], "value": 125.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 163.0, 164.0, 161.0, 163.0, 162.0, 163.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 77.0, 119.0, 79.0, 76.0, 76.0, 77.0, 90.0, 77.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 106.0, 82.0, 83.0, 83.0, 92.0, 83.0, 81.0, 106.0], "value": 88.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 114.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 146.0, 147.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 93.0, 93.0, 93.0, 94.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 57.0, 55.0, 55.0, 57.0, 58.0, 56.0, 56.0, 56.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 52.0, 52.0, 53.0, 54.0, 53.0, 53.0, 53.0], "value": 53.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 84.0, 77.0, 74.0, 76.0, 81.0, 78.0, 78.0, 75.0, 81.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 122.0, 119.0, 119.0, 119.0, 118.0, 117.0, 118.0, 159.0, 120.0], "value": 124.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 157.0, 163.0, 162.0, 163.0, 161.0, 165.0, 165.0, 164.0, 159.0], "value": 161.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 60.0, 60.0, 60.0, 60.0, 59.0, 59.0, 60.0], "value": 60.1, "unit": "score"}]}]} 18:35:15 INFO - Return code: 0 18:35:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:35:15 INFO - # TBPL SUCCESS # 18:35:15 INFO - Running post-action listener: _resource_record_post_action 18:35:15 INFO - [mozharness: 2017-05-23 01:35:15.914884Z] Finished run-tests step (success) 18:35:15 INFO - Running post-run listener: _resource_record_post_run 18:35:15 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 12288; Write bytes: 326606848; Read time: 420; Write time: 235552 18:35:15 INFO - TinderboxPrint: CPU usage
13.1% 18:35:15 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 420 18:35:15 INFO - TinderboxPrint: I/O write bytes / time
326,606,848 / 235,552 18:35:15 INFO - TinderboxPrint: CPU idle
8,249.7 (86.8%) 18:35:15 INFO - TinderboxPrint: CPU user
1,200.9 (12.6%) 18:35:15 INFO - TinderboxPrint: Swap in / out
0 / 0 18:35:15 INFO - install - Wall time: 14s; CPU: 14.0%; Read bytes: 4096; Write bytes: 98861056; Read time: 288; Write time: 102568 18:35:16 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 227139584; Read time: 120; Write time: 132956 18:35:16 INFO - Running post-run listener: _upload_blobber_files 18:35:16 INFO - Blob upload gear active. 18:35:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:35:16 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 18:35:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:35:16 INFO - (blobuploader) - INFO - Open directory for files ... 18:35:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:35:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:35:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:35:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:35:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:35:17 INFO - (blobuploader) - INFO - Done attempting. 18:35:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:35:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:35:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:35:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:35:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:35:18 INFO - (blobuploader) - INFO - Done attempting. 18:35:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:35:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:35:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:35:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:35:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:35:22 INFO - (blobuploader) - INFO - Done attempting. 18:35:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:35:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:35:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:35:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:35:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:35:23 INFO - (blobuploader) - INFO - Done attempting. 18:35:23 INFO - (blobuploader) - INFO - Iteration through files over. 18:35:23 INFO - Return code: 0 18:35:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:35:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:35:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0ba737e1ff67a8bae2d59c66ef7132d81ba99676673f55a7b4d1403f75ea2c5c0aa31c4536cb76740323ff34658f5c8ceec52f8f250b01e7fa6e19d331a7cda6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/718020be9008b8be1a63a55379e29494b2c03a94ec64cb565175add96791522aa6625d46f59b63490c21ba3b40ae8094eee1d9a04b5b44b48a672e085647a250", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/687ccc792cbef9033bf137037df2419058405e3a168d08f1d2824a4570ee337c5457d4ff006e8791e943fcb0345060b5fadde46024f9aaa447b14b1f44578e0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6f4893c06a138286be9edb89a524532083a3296b7510118c9bd1d7f58a89f739b41479df7a42702403bb93c64b430c57423a345e1d263164dec8afc01c7e8788"} 18:35:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:35:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:35:23 INFO - Contents: 18:35:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0ba737e1ff67a8bae2d59c66ef7132d81ba99676673f55a7b4d1403f75ea2c5c0aa31c4536cb76740323ff34658f5c8ceec52f8f250b01e7fa6e19d331a7cda6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/718020be9008b8be1a63a55379e29494b2c03a94ec64cb565175add96791522aa6625d46f59b63490c21ba3b40ae8094eee1d9a04b5b44b48a672e085647a250", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/687ccc792cbef9033bf137037df2419058405e3a168d08f1d2824a4570ee337c5457d4ff006e8791e943fcb0345060b5fadde46024f9aaa447b14b1f44578e0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6f4893c06a138286be9edb89a524532083a3296b7510118c9bd1d7f58a89f739b41479df7a42702403bb93c64b430c57423a345e1d263164dec8afc01c7e8788"} 18:35:23 INFO - Running post-run listener: copy_logs_to_upload_dir 18:35:23 INFO - Copying logs to upload dir... 18:35:23 INFO - mkdir: /builds/slave/test/build/upload/logs 18:35:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.025813 ========= master_lag: 0.18 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-05-22 18:35:22.879448) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 18:35:22.887652) ========= 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=836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0ba737e1ff67a8bae2d59c66ef7132d81ba99676673f55a7b4d1403f75ea2c5c0aa31c4536cb76740323ff34658f5c8ceec52f8f250b01e7fa6e19d331a7cda6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/718020be9008b8be1a63a55379e29494b2c03a94ec64cb565175add96791522aa6625d46f59b63490c21ba3b40ae8094eee1d9a04b5b44b48a672e085647a250", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/687ccc792cbef9033bf137037df2419058405e3a168d08f1d2824a4570ee337c5457d4ff006e8791e943fcb0345060b5fadde46024f9aaa447b14b1f44578e0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6f4893c06a138286be9edb89a524532083a3296b7510118c9bd1d7f58a89f739b41479df7a42702403bb93c64b430c57423a345e1d263164dec8afc01c7e8788"} build_url:https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011261 build_url: 'https://queue.taskcluster.net/v1/task/RRzjm-T_QTaXB7RP6qnjtg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0ba737e1ff67a8bae2d59c66ef7132d81ba99676673f55a7b4d1403f75ea2c5c0aa31c4536cb76740323ff34658f5c8ceec52f8f250b01e7fa6e19d331a7cda6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/718020be9008b8be1a63a55379e29494b2c03a94ec64cb565175add96791522aa6625d46f59b63490c21ba3b40ae8094eee1d9a04b5b44b48a672e085647a250", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/687ccc792cbef9033bf137037df2419058405e3a168d08f1d2824a4570ee337c5457d4ff006e8791e943fcb0345060b5fadde46024f9aaa447b14b1f44578e0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6f4893c06a138286be9edb89a524532083a3296b7510118c9bd1d7f58a89f739b41479df7a42702403bb93c64b430c57423a345e1d263164dec8afc01c7e8788"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 18:35:22.917359) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:35:22.917724) ========= 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=836cfdc75598d54cb747368e000001a9-1495501884.841815-2107797615 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004475 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:35:22.998109) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 18:35:22.998435) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 18:35:22.998817) ========= ========= Total master_lag: 0.34 =========