builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-007 starttime: 1495450668.74 results: success (0) revision: cf1d4e65a1d1b7f2b1d33116669b2298212228c5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 03:57:48.740350) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 03:57:48.743972) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 03:57:48.744255) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 03:57:48.760709) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 03:57:48.761045) ========= 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=923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-22 03:57:50-- 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% 37.4M=0s 2017-05-22 03:57:50 (37.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085147 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 03:57:48.871213) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 03:57:48.871517) ========= 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=923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014356 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 03:57:48.937049) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 03:57:48.937377) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev cf1d4e65a1d1b7f2b1d33116669b2298212228c5 --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 cf1d4e65a1d1b7f2b1d33116669b2298212228c5 --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=923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-22 03:57:50,332 truncating revision to first 12 chars 2017-05-22 03:57:50,332 Setting DEBUG logging. 2017-05-22 03:57:50,332 attempt 1/10 2017-05-22 03:57:50,332 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/cf1d4e65a1d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 03:57:50,717 unpacking tar archive at: mozilla-beta-cf1d4e65a1d1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.705747 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 03:57:49.661970) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 03:57:49.662281) ========= script_repo_revision: cf1d4e65a1d1b7f2b1d33116669b2298212228c5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 03:57:49.662969) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 03:57:49.663238) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 03:57:49.672226) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-05-22 03:57:49.672533) ========= /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=923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:57:51 INFO - MultiFileLogger online at 20170522 03:57:51 in /builds/slave/test 03:57:51 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 03:57:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:57:51 INFO - {'append_to_log': False, 03:57:51 INFO - 'base_work_dir': '/builds/slave/test', 03:57:51 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 03:57:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:57:51 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 03:57:51 INFO - 'buildbot_json_path': 'buildprops.json', 03:57:51 INFO - 'config_files': ('talos/linux_config.py',), 03:57:51 INFO - 'default_actions': ('clobber', 03:57:51 INFO - 'read-buildbot-config', 03:57:51 INFO - 'download-and-extract', 03:57:51 INFO - 'populate-webroot', 03:57:51 INFO - 'create-virtualenv', 03:57:51 INFO - 'install', 03:57:51 INFO - 'run-tests'), 03:57:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:57:51 INFO - 'download_minidump_stackwalk': True, 03:57:51 INFO - 'download_symbols': 'ondemand', 03:57:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:57:51 INFO - 'tooltool.py': '/tools/tooltool.py', 03:57:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:57:51 INFO - '/tools/misc-python/virtualenv.py')}, 03:57:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:57:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:57:51 INFO - 'gecko_profile': False, 03:57:51 INFO - 'gecko_profile_interval': 0, 03:57:51 INFO - 'installer_path': 'installer.exe', 03:57:51 INFO - 'log_level': 'info', 03:57:51 INFO - 'log_name': 'talos', 03:57:51 INFO - 'log_to_console': True, 03:57:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:57:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:57:51 INFO - 'opt_config_files': (), 03:57:51 INFO - 'pip_index': False, 03:57:51 INFO - 'suite': 'dromaeojs', 03:57:51 INFO - 'system_bits': '32', 03:57:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:57:51 INFO - 'title': 'talos-linux64-ix-007', 03:57:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:57:51 INFO - 'use_talos_json': True, 03:57:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:57:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:57:51 INFO - 'work_dir': 'build'} 03:57:51 INFO - [mozharness: 2017-05-22 10:57:51.165502Z] Running clobber step. 03:57:51 INFO - Running pre-action listener: _resource_record_pre_action 03:57:51 INFO - Running main action method: clobber 03:57:51 INFO - rmtree: /builds/slave/test/build 03:57:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:57:52 INFO - Running post-action listener: _resource_record_post_action 03:57:52 INFO - [mozharness: 2017-05-22 10:57:52.714072Z] Finished clobber step (success) 03:57:52 INFO - [mozharness: 2017-05-22 10:57:52.714171Z] Running read-buildbot-config step. 03:57:52 INFO - Running pre-action listener: _resource_record_pre_action 03:57:52 INFO - Running main action method: read_buildbot_config 03:57:52 INFO - Using buildbot properties: 03:57:52 INFO - { 03:57:52 INFO - "project": "", 03:57:52 INFO - "product": "firefox", 03:57:52 INFO - "who": "cbook@mozilla.com", 03:57:52 INFO - "installer_path": "public/build/target.tar.bz2", 03:57:52 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 03:57:52 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 03:57:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 03:57:52 INFO - "stage_platform": "linux64", 03:57:52 INFO - "basedir": "/builds/slave/test", 03:57:52 INFO - "buildnumber": 53, 03:57:52 INFO - "platform": "ubuntu64_hw", 03:57:52 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:57:52 INFO - "slavebuilddir": "test", 03:57:52 INFO - "taskId": "PNnV8Lu1QG2kofdut-TRzQ", 03:57:52 INFO - "branch": "mozilla-beta", 03:57:52 INFO - "script_repo_revision": "production", 03:57:52 INFO - "revision": "cf1d4e65a1d1b7f2b1d33116669b2298212228c5", 03:57:52 INFO - "slavename": "talos-linux64-ix-007", 03:57:52 INFO - "repo_path": "releases/mozilla-beta" 03:57:52 INFO - } 03:57:52 INFO - Finding installer, test and symbols from parent task. 03:57:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PNnV8Lu1QG2kofdut-TRzQ'}, attempt #1 03:57:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PNnV8Lu1QG2kofdut-TRzQ'}, attempt #1 03:57:53 INFO - Task dependencies: NP-E5b0eQ0uER2rjup30xQ 03:57:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ'}, attempt #1 03:57:54 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.tar.bz2 03:57:54 INFO - Set test_url: https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/test_packages.json 03:57:54 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.crashreporter-symbols.zip 03:57:54 INFO - Running post-action listener: _resource_record_post_action 03:57:54 INFO - [mozharness: 2017-05-22 10:57:54.089428Z] Finished read-buildbot-config step (success) 03:57:54 INFO - [mozharness: 2017-05-22 10:57:54.089654Z] Running download-and-extract step. 03:57:54 INFO - Running pre-action listener: _resource_record_pre_action 03:57:54 INFO - Running main action method: download_and_extract 03:57:54 INFO - mkdir: /builds/slave/test/build/tests 03:57:54 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')]} 03:57:54 INFO - trying https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.test_packages.json 03:57:54 INFO - Downloading https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:57:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:57:54 INFO - Downloaded 986 bytes. 03:57:54 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:57:54 INFO - Using the following test package requirements: 03:57:54 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:57:54 INFO - u'common': [u'target.common.tests.zip'], 03:57:54 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:57:54 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:57:54 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:57:54 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:57:54 INFO - u'mozbase': [u'target.common.tests.zip'], 03:57:54 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:57:54 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:57:54 INFO - u'web-platform': [u'target.common.tests.zip', 03:57:54 INFO - u'target.web-platform.tests.zip'], 03:57:54 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:57:54 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:57:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.common.tests.zip 03:57:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:57:54 INFO - Fetch https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.common.tests.zip into memory 03:57:57 INFO - Expected file size: 59718239 03:57:57 INFO - Obtained file size: 59718239 03:58:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:58:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.common.tests.zip 03:58:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:58:06 INFO - Fetch https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.common.tests.zip into memory 03:58:08 INFO - Expected file size: 59718239 03:58:08 INFO - Obtained file size: 59718239 03:58:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.talos.tests.zip 03:58:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:58:19 INFO - Fetch https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.talos.tests.zip into memory 03:58:20 INFO - Expected file size: 14404449 03:58:20 INFO - Obtained file size: 14404449 03:58:20 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')]} 03:58:20 INFO - trying https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.tar.bz2 03:58:20 INFO - Downloading https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:58:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:58:22 INFO - Downloaded 59803456 bytes. 03:58:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.tar.bz2 03:58:22 INFO - mkdir: /builds/slave/test/properties 03:58:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:58:22 INFO - Writing to file /builds/slave/test/properties/build_url 03:58:22 INFO - Contents: 03:58:22 INFO - build_url:https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.tar.bz2 03:58:22 INFO - Running post-action listener: _resource_record_post_action 03:58:22 INFO - Running post-action listener: set_extra_try_arguments 03:58:22 INFO - [mozharness: 2017-05-22 10:58:22.555920Z] Finished download-and-extract step (success) 03:58:22 INFO - [mozharness: 2017-05-22 10:58:22.556124Z] Running populate-webroot step. 03:58:22 INFO - Running pre-action listener: _resource_record_pre_action 03:58:22 INFO - Running main action method: populate_webroot 03:58:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:58:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:58:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:58:22 INFO - '--tppagecycles', 03:58:22 INFO - '7'], 03:58:22 INFO - 'tests': ['tsvgm']}}, 03:58:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:58:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:58:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:58:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:58:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:58:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:58:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:58:22 INFO - 'talos_options': ['--disable-e10s'], 03:58:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:58:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:58:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:58:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:58:22 INFO - 'talos_options': ['--disable-e10s'], 03:58:22 INFO - 'tests': ['damp', 'tps']}, 03:58:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:58:22 INFO - 'tests': ['damp', 'tps']}, 03:58:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:58:22 INFO - 'tests': ['dromaeo_dom']}, 03:58:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:58:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:58:22 INFO - 'tests': ['basic_compositor_video']}, 03:58:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:58:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:58:22 INFO - 'tests': ['a11yr', 03:58:22 INFO - 'ts_paint', 03:58:22 INFO - 'tpaint', 03:58:22 INFO - 'sessionrestore', 03:58:22 INFO - 'sessionrestore_no_auto_restore', 03:58:22 INFO - 'tabpaint']}, 03:58:22 INFO - 'other-e10s': {'tests': ['a11yr', 03:58:22 INFO - 'ts_paint', 03:58:22 INFO - 'tpaint', 03:58:22 INFO - 'sessionrestore', 03:58:22 INFO - 'sessionrestore_no_auto_restore', 03:58:22 INFO - 'tabpaint']}, 03:58:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:58:22 INFO - 'ts_paint', 03:58:22 INFO - 'tpaint', 03:58:22 INFO - 'sessionrestore', 03:58:22 INFO - 'sessionrestore_no_auto_restore', 03:58:22 INFO - 'tabpaint']}, 03:58:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:58:22 INFO - 'ts_paint', 03:58:22 INFO - 'tpaint', 03:58:22 INFO - 'sessionrestore', 03:58:22 INFO - 'sessionrestore_no_auto_restore', 03:58:22 INFO - 'tabpaint']}, 03:58:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:58:22 INFO - 'tests': ['a11yr', 03:58:22 INFO - 'ts_paint', 03:58:22 INFO - 'tpaint', 03:58:22 INFO - 'sessionrestore', 03:58:22 INFO - 'sessionrestore_no_auto_restore', 03:58:22 INFO - 'tabpaint']}, 03:58:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:58:22 INFO - 'tests': ['a11yr', 03:58:22 INFO - 'ts_paint', 03:58:22 INFO - 'tpaint', 03:58:22 INFO - 'sessionrestore', 03:58:22 INFO - 'sessionrestore_no_auto_restore', 03:58:22 INFO - 'tabpaint']}, 03:58:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:58:22 INFO - 'tests': ['tsvgx', 03:58:22 INFO - 'tsvgr_opacity', 03:58:22 INFO - 'tart', 03:58:22 INFO - 'tscrollx', 03:58:22 INFO - 'cart', 03:58:22 INFO - 'tsvg_static']}, 03:58:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:58:22 INFO - 'tsvgr_opacity', 03:58:22 INFO - 'tart', 03:58:22 INFO - 'tscrollx', 03:58:22 INFO - 'cart', 03:58:22 INFO - 'tsvg_static']}, 03:58:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:58:22 INFO - 'talos_options': ['--disable-e10s'], 03:58:22 INFO - 'tests': ['tp5o']}, 03:58:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:58:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:58:22 INFO - 'talos_options': ['--disable-e10s', 03:58:22 INFO - '--xperf_path', 03:58:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:58:22 INFO - 'tests': ['tp5n']}, 03:58:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:58:22 INFO - 'talos_options': ['--xperf_path', 03:58:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:58:22 INFO - 'tests': ['tp5n']}}, 03:58:22 INFO - 'talos.zip': {'path': '', 03:58:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:58:22 INFO - Running post-action listener: _resource_record_post_action 03:58:22 INFO - [mozharness: 2017-05-22 10:58:22.574579Z] Finished populate-webroot step (success) 03:58:22 INFO - [mozharness: 2017-05-22 10:58:22.574668Z] Running create-virtualenv step. 03:58:22 INFO - Running pre-action listener: _resource_record_pre_action 03:58:22 INFO - Running main action method: create_virtualenv 03:58:22 INFO - Creating virtualenv /builds/slave/test/build/venv 03:58:22 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:58:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:58:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:58:22 INFO - Using real prefix '/usr' 03:58:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:58:23 INFO - Installing distribute.............................................................................................................................................................................................done. 03:58:25 INFO - Installing pip.................done. 03:58:25 INFO - Return code: 0 03:58:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:58:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:58:25 INFO - Reading from file tmpfile_stdout 03:58:25 INFO - Output received: 03:58:25 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) 03:58:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:58: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')]} 03:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58: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')]} 03:58:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1914a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b74310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b71400>, '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 0x1b723b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b72840>, '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': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', '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 03:58: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', 'pip>=1.5'] in /builds/slave/test/build 03:58: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 pip>=1.5 03:58:25 INFO - Using env: {'DISPLAY': ':0', 03:58:25 INFO - 'HOME': '/home/cltbld', 03:58:25 INFO - 'LANG': 'en_US.UTF-8', 03:58:25 INFO - 'LANGUAGE': 'en_US:en', 03:58:25 INFO - 'LOGNAME': 'cltbld', 03:58:25 INFO - 'MAIL': '/var/mail/cltbld', 03:58:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:25 INFO - 'MOZ_NO_REMOTE': '1', 03:58:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:58:25 INFO - 'NO_EM_RESTART': '1', 03:58:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:58:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:25 INFO - 'PWD': '/builds/slave/test', 03:58:25 INFO - 'SHELL': '/bin/bash', 03:58:25 INFO - 'SHLVL': '1', 03:58:25 INFO - 'TERM': 'linux', 03:58:25 INFO - 'TMOUT': '86400', 03:58:25 INFO - 'USER': 'cltbld', 03:58:25 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', 03:58:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:25 INFO - '_': '/tools/buildbot/bin/python'} 03:58:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:25 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:58:25 INFO - Cleaning up... 03:58:25 INFO - Return code: 0 03:58:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:58: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')]} 03:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58: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')]} 03:58:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58: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', '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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1914a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b74310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b71400>, '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 0x1b723b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b72840>, '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': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', '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 03:58: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', 'psutil>=3.1.1'] in /builds/slave/test/build 03:58: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 psutil>=3.1.1 03:58:25 INFO - Using env: {'DISPLAY': ':0', 03:58:25 INFO - 'HOME': '/home/cltbld', 03:58:25 INFO - 'LANG': 'en_US.UTF-8', 03:58:25 INFO - 'LANGUAGE': 'en_US:en', 03:58:25 INFO - 'LOGNAME': 'cltbld', 03:58:25 INFO - 'MAIL': '/var/mail/cltbld', 03:58:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:25 INFO - 'MOZ_NO_REMOTE': '1', 03:58:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:58:25 INFO - 'NO_EM_RESTART': '1', 03:58:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:58:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:25 INFO - 'PWD': '/builds/slave/test', 03:58:25 INFO - 'SHELL': '/bin/bash', 03:58:25 INFO - 'SHLVL': '1', 03:58:25 INFO - 'TERM': 'linux', 03:58:25 INFO - 'TMOUT': '86400', 03:58:25 INFO - 'USER': 'cltbld', 03:58:25 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', 03:58:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:25 INFO - '_': '/tools/buildbot/bin/python'} 03:58:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:25 INFO - Downloading/unpacking psutil>=3.1.1 03:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:58:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:58:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:58:27 INFO - Installing collected packages: psutil 03:58:27 INFO - Running setup.py install for psutil 03:58:27 INFO - building 'psutil._psutil_linux' extension 03:58:27 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:58:27 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:58:27 INFO - building 'psutil._psutil_posix' extension 03:58:27 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:58:27 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:58:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:58:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:58:27 INFO - Successfully installed psutil 03:58:27 INFO - Cleaning up... 03:58:27 INFO - Return code: 0 03:58:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:58:27 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')]} 03:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:27 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')]} 03:58:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1914a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b74310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b71400>, '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 0x1b723b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b72840>, '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': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', '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 03:58:27 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 03:58:27 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 03:58:27 INFO - Using env: {'DISPLAY': ':0', 03:58:27 INFO - 'HOME': '/home/cltbld', 03:58:27 INFO - 'LANG': 'en_US.UTF-8', 03:58:27 INFO - 'LANGUAGE': 'en_US:en', 03:58:27 INFO - 'LOGNAME': 'cltbld', 03:58:27 INFO - 'MAIL': '/var/mail/cltbld', 03:58:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:27 INFO - 'MOZ_NO_REMOTE': '1', 03:58:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:58:27 INFO - 'NO_EM_RESTART': '1', 03:58:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:58:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:27 INFO - 'PWD': '/builds/slave/test', 03:58:27 INFO - 'SHELL': '/bin/bash', 03:58:27 INFO - 'SHLVL': '1', 03:58:27 INFO - 'TERM': 'linux', 03:58:27 INFO - 'TMOUT': '86400', 03:58:27 INFO - 'USER': 'cltbld', 03:58:27 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', 03:58:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:27 INFO - '_': '/tools/buildbot/bin/python'} 03:58:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:58:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:58:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:58:28 INFO - Installing collected packages: mozsystemmonitor 03:58:28 INFO - Running setup.py install for mozsystemmonitor 03:58:29 INFO - Successfully installed mozsystemmonitor 03:58:29 INFO - Cleaning up... 03:58:29 INFO - Return code: 0 03:58:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:58:29 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')]} 03:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:29 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')]} 03:58:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1914a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b74310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b71400>, '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 0x1b723b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b72840>, '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': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', '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 03:58:29 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 03:58:29 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 03:58:29 INFO - Using env: {'DISPLAY': ':0', 03:58:29 INFO - 'HOME': '/home/cltbld', 03:58:29 INFO - 'LANG': 'en_US.UTF-8', 03:58:29 INFO - 'LANGUAGE': 'en_US:en', 03:58:29 INFO - 'LOGNAME': 'cltbld', 03:58:29 INFO - 'MAIL': '/var/mail/cltbld', 03:58:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:29 INFO - 'MOZ_NO_REMOTE': '1', 03:58:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:58:29 INFO - 'NO_EM_RESTART': '1', 03:58:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:58:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:29 INFO - 'PWD': '/builds/slave/test', 03:58:29 INFO - 'SHELL': '/bin/bash', 03:58:29 INFO - 'SHLVL': '1', 03:58:29 INFO - 'TERM': 'linux', 03:58:29 INFO - 'TMOUT': '86400', 03:58:29 INFO - 'USER': 'cltbld', 03:58:29 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', 03:58:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:29 INFO - '_': '/tools/buildbot/bin/python'} 03:58:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:29 INFO - Downloading/unpacking jsonschema==2.5.1 03:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:58:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:30 INFO - Downloading functools32-3.2.3-2.tar.gz 03:58:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:58:30 INFO - warning: no files found matching '*.txt' 03:58:30 INFO - no previously-included directories found matching 'build' 03:58:30 INFO - no previously-included directories found matching 'dist' 03:58:30 INFO - no previously-included directories found matching '.git*' 03:58:30 INFO - Installing collected packages: jsonschema, functools32 03:58:30 INFO - Running setup.py install for functools32 03:58:30 INFO - warning: no files found matching '*.txt' 03:58:30 INFO - no previously-included directories found matching 'build' 03:58:30 INFO - no previously-included directories found matching 'dist' 03:58:30 INFO - no previously-included directories found matching '.git*' 03:58:30 INFO - Successfully installed jsonschema functools32 03:58:30 INFO - Cleaning up... 03:58:30 INFO - Return code: 0 03:58:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:58:30 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')]} 03:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:30 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')]} 03:58:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1914a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b74310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b71400>, '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 0x1b723b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b72840>, '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': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', '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 03:58:30 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 03:58:30 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 03:58:30 INFO - Using env: {'DISPLAY': ':0', 03:58:30 INFO - 'HOME': '/home/cltbld', 03:58:30 INFO - 'LANG': 'en_US.UTF-8', 03:58:30 INFO - 'LANGUAGE': 'en_US:en', 03:58:30 INFO - 'LOGNAME': 'cltbld', 03:58:30 INFO - 'MAIL': '/var/mail/cltbld', 03:58:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:30 INFO - 'MOZ_NO_REMOTE': '1', 03:58:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:58:30 INFO - 'NO_EM_RESTART': '1', 03:58:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:58:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:30 INFO - 'PWD': '/builds/slave/test', 03:58:30 INFO - 'SHELL': '/bin/bash', 03:58:30 INFO - 'SHLVL': '1', 03:58:30 INFO - 'TERM': 'linux', 03:58:30 INFO - 'TMOUT': '86400', 03:58:30 INFO - 'USER': 'cltbld', 03:58:30 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', 03:58:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:30 INFO - '_': '/tools/buildbot/bin/python'} 03:58:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:58:30 INFO - Cleaning up... 03:58:30 INFO - Return code: 0 03:58:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:58:30 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')]} 03:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:30 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')]} 03:58:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1914a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b74310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b71400>, '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 0x1b723b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b72840>, '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': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', '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 03:58:30 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 03:58:30 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 03:58:30 INFO - Using env: {'DISPLAY': ':0', 03:58:30 INFO - 'HOME': '/home/cltbld', 03:58:30 INFO - 'LANG': 'en_US.UTF-8', 03:58:30 INFO - 'LANGUAGE': 'en_US:en', 03:58:30 INFO - 'LOGNAME': 'cltbld', 03:58:30 INFO - 'MAIL': '/var/mail/cltbld', 03:58:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:30 INFO - 'MOZ_NO_REMOTE': '1', 03:58:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:58:30 INFO - 'NO_EM_RESTART': '1', 03:58:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:58:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:30 INFO - 'PWD': '/builds/slave/test', 03:58:30 INFO - 'SHELL': '/bin/bash', 03:58:30 INFO - 'SHLVL': '1', 03:58:30 INFO - 'TERM': 'linux', 03:58:30 INFO - 'TMOUT': '86400', 03:58:30 INFO - 'USER': 'cltbld', 03:58:30 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', 03:58:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:30 INFO - '_': '/tools/buildbot/bin/python'} 03:58:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:31 INFO - Downloading/unpacking blobuploader==1.2.4 03:58:31 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 03:58:31 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 03:58:31 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 03:58:31 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 03:58:32 INFO - Downloading blobuploader-1.2.4.tar.gz 03:58:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:58:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:58:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:32 INFO - Downloading docopt-0.6.1.tar.gz 03:58:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:58:32 INFO - Installing collected packages: blobuploader, requests, docopt 03:58:32 INFO - Running setup.py install for blobuploader 03:58:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:58:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:58:32 INFO - Running setup.py install for requests 03:58:33 INFO - Running setup.py install for docopt 03:58:33 INFO - Successfully installed blobuploader requests docopt 03:58:33 INFO - Cleaning up... 03:58:33 INFO - Return code: 0 03:58:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:58:33 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')]} 03:58:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:33 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')]} 03:58:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1914a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b74310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b71400>, '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 0x1b723b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b72840>, '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': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', '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 03:58:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:58:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:58:33 INFO - Using env: {'DISPLAY': ':0', 03:58:33 INFO - 'HOME': '/home/cltbld', 03:58:33 INFO - 'LANG': 'en_US.UTF-8', 03:58:33 INFO - 'LANGUAGE': 'en_US:en', 03:58:33 INFO - 'LOGNAME': 'cltbld', 03:58:33 INFO - 'MAIL': '/var/mail/cltbld', 03:58:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:33 INFO - 'MOZ_NO_REMOTE': '1', 03:58:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:58:33 INFO - 'NO_EM_RESTART': '1', 03:58:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:58:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:33 INFO - 'PWD': '/builds/slave/test', 03:58:33 INFO - 'SHELL': '/bin/bash', 03:58:33 INFO - 'SHLVL': '1', 03:58:33 INFO - 'TERM': 'linux', 03:58:33 INFO - 'TMOUT': '86400', 03:58:33 INFO - 'USER': 'cltbld', 03:58:33 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', 03:58:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:33 INFO - '_': '/tools/buildbot/bin/python'} 03:58:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:58:33 INFO - Running setup.py (path:/tmp/pip-pkhpf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:58:33 INFO - Running setup.py (path:/tmp/pip-tIyvSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:58:33 INFO - Running setup.py (path:/tmp/pip-P2K4Pz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:58:33 INFO - Running setup.py (path:/tmp/pip-6jktuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:58:33 INFO - Running setup.py (path:/tmp/pip-iFeyMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:58:33 INFO - Running setup.py (path:/tmp/pip-Lq9GMw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:58:34 INFO - Running setup.py (path:/tmp/pip-szuNbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:58:34 INFO - Running setup.py (path:/tmp/pip-22zLzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:58:34 INFO - Running setup.py (path:/tmp/pip-X8mk0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:58:34 INFO - Running setup.py (path:/tmp/pip-4GLiBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:58:34 INFO - Running setup.py (path:/tmp/pip-Wa5Yse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:58:34 INFO - Running setup.py (path:/tmp/pip-w0wO6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:58:34 INFO - Running setup.py (path:/tmp/pip-c58BDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:58:34 INFO - Running setup.py (path:/tmp/pip-vE6MU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:58:34 INFO - Running setup.py (path:/tmp/pip-3RAt7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:58:34 INFO - Running setup.py (path:/tmp/pip-e84wO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:58:34 INFO - Running setup.py (path:/tmp/pip-kyyLEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:58:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:58:34 INFO - Running setup.py install for manifestparser 03:58:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:58:34 INFO - Running setup.py install for mozcrash 03:58:35 INFO - Running setup.py install for mozdebug 03:58:35 INFO - Running setup.py install for mozdevice 03:58:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:58:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:58:35 INFO - Running setup.py install for mozfile 03:58:35 INFO - Running setup.py install for mozhttpd 03:58:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:58:35 INFO - Running setup.py install for mozinfo 03:58:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:58:35 INFO - Running setup.py install for mozInstall 03:58:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:58:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:58:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:58:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:58:35 INFO - Running setup.py install for mozleak 03:58:35 INFO - Running setup.py install for mozlog 03:58:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:58:35 INFO - Running setup.py install for moznetwork 03:58:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:58:35 INFO - Running setup.py install for mozprocess 03:58:36 INFO - Running setup.py install for mozprofile 03:58:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:58:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:58:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:58:36 INFO - Running setup.py install for mozrunner 03:58:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:58:36 INFO - Running setup.py install for mozscreenshot 03:58:36 INFO - Running setup.py install for moztest 03:58:36 INFO - Running setup.py install for mozversion 03:58:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:58:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:58:36 INFO - Cleaning up... 03:58:36 INFO - Return code: 0 03:58:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:58:36 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')]} 03:58:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:36 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')]} 03:58:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1914a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b74310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b71400>, '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 0x1b723b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b72840>, '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': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', '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 03:58:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:58:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:58:36 INFO - Using env: {'DISPLAY': ':0', 03:58:36 INFO - 'HOME': '/home/cltbld', 03:58:36 INFO - 'LANG': 'en_US.UTF-8', 03:58:36 INFO - 'LANGUAGE': 'en_US:en', 03:58:36 INFO - 'LOGNAME': 'cltbld', 03:58:36 INFO - 'MAIL': '/var/mail/cltbld', 03:58:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:36 INFO - 'MOZ_NO_REMOTE': '1', 03:58:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:58:36 INFO - 'NO_EM_RESTART': '1', 03:58:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:58:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:36 INFO - 'PWD': '/builds/slave/test', 03:58:36 INFO - 'SHELL': '/bin/bash', 03:58:36 INFO - 'SHLVL': '1', 03:58:36 INFO - 'TERM': 'linux', 03:58:36 INFO - 'TMOUT': '86400', 03:58:36 INFO - 'USER': 'cltbld', 03:58:36 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', 03:58:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:36 INFO - '_': '/tools/buildbot/bin/python'} 03:58:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:58:36 INFO - Running setup.py (path:/tmp/pip-pmvaHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:58:36 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)) 03:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:58:36 INFO - Running setup.py (path:/tmp/pip-2tIld1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:58:37 INFO - Running setup.py (path:/tmp/pip-zdKDA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:58:37 INFO - Running setup.py (path:/tmp/pip-qlzQeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:58:37 INFO - Running setup.py (path:/tmp/pip-PQd2Fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:58:37 INFO - Running setup.py (path:/tmp/pip-9EoxcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:58:37 INFO - Running setup.py (path:/tmp/pip-ClsuOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:58:37 INFO - Running setup.py (path:/tmp/pip-nQezJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:58:37 INFO - Running setup.py (path:/tmp/pip-NninOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:58:37 INFO - Running setup.py (path:/tmp/pip-0xkZzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:58:37 INFO - Running setup.py (path:/tmp/pip-2OqR1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:58:37 INFO - Running setup.py (path:/tmp/pip-110Gf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:58:37 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)) 03:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:58:37 INFO - Running setup.py (path:/tmp/pip-yRotus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:58:37 INFO - Running setup.py (path:/tmp/pip-VWeWF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:58:37 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)) 03:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:58:37 INFO - Running setup.py (path:/tmp/pip-XI2Yfi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:58:37 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)) 03:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:58:37 INFO - Running setup.py (path:/tmp/pip-b0n2YX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:58:38 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)) 03:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:58:38 INFO - Running setup.py (path:/tmp/pip-mfHn7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:58:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:58:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:58:39 INFO - Downloading blessings-1.6.tar.gz 03:58:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:58:39 INFO - Installing collected packages: blessings 03:58:39 INFO - Running setup.py install for blessings 03:58:39 INFO - Successfully installed blessings 03:58:39 INFO - Cleaning up... 03:58:39 INFO - Return code: 0 03:58:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:58:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:58:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:58:39 INFO - Reading from file tmpfile_stdout 03:58:39 INFO - Current package versions: 03:58:39 INFO - argparse == 1.2.1 03:58:39 INFO - blessings == 1.6 03:58:39 INFO - blobuploader == 1.2.4 03:58:39 INFO - docopt == 0.6.1 03:58:39 INFO - functools32 == 3.2.3-2 03:58:39 INFO - jsonschema == 2.5.1 03:58:39 INFO - manifestparser == 1.1 03:58:39 INFO - mozInstall == 1.12 03:58:39 INFO - mozcrash == 1.0 03:58:39 INFO - mozdebug == 0.1 03:58:39 INFO - mozdevice == 0.49 03:58:39 INFO - mozfile == 1.2 03:58:39 INFO - mozhttpd == 0.7 03:58:39 INFO - mozinfo == 0.9 03:58:39 INFO - mozleak == 0.1 03:58:39 INFO - mozlog == 3.4 03:58:39 INFO - moznetwork == 0.27 03:58:39 INFO - mozprocess == 0.25 03:58:39 INFO - mozprofile == 0.28 03:58:39 INFO - mozrunner == 6.13 03:58:39 INFO - mozscreenshot == 0.1 03:58:39 INFO - mozsystemmonitor == 0.3 03:58:39 INFO - moztest == 0.8 03:58:39 INFO - mozversion == 1.4 03:58:39 INFO - psutil == 3.1.1 03:58:39 INFO - requests == 1.2.3 03:58:39 INFO - wsgiref == 0.1.2 03:58:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:58:39 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')]} 03:58:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:39 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')]} 03:58:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:39 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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1914a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b74310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b71400>, '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 0x1b723b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b72840>, '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': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', '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 03:58:39 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 03:58:39 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 03:58:39 INFO - Using env: {'DISPLAY': ':0', 03:58:39 INFO - 'HOME': '/home/cltbld', 03:58:39 INFO - 'LANG': 'en_US.UTF-8', 03:58:39 INFO - 'LANGUAGE': 'en_US:en', 03:58:39 INFO - 'LOGNAME': 'cltbld', 03:58:39 INFO - 'MAIL': '/var/mail/cltbld', 03:58:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:39 INFO - 'MOZ_NO_REMOTE': '1', 03:58:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:58:39 INFO - 'NO_EM_RESTART': '1', 03:58:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:58:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:39 INFO - 'PWD': '/builds/slave/test', 03:58:39 INFO - 'SHELL': '/bin/bash', 03:58:39 INFO - 'SHLVL': '1', 03:58:39 INFO - 'TERM': 'linux', 03:58:39 INFO - 'TMOUT': '86400', 03:58:39 INFO - 'USER': 'cltbld', 03:58:39 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', 03:58:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:39 INFO - '_': '/tools/buildbot/bin/python'} 03:58:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:39 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)) 03:58:39 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)) 03:58:39 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)) 03:58:39 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)) 03:58:39 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)) 03:58:39 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)) 03:58:39 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)) 03:58:39 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)) 03:58:39 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)) 03:58:39 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)) 03:58:39 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)) 03:58:39 INFO - Cleaning up... 03:58:39 INFO - Return code: 0 03:58:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:58:39 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')]} 03:58:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:58:39 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')]} 03:58:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:58:39 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 0x18d61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1914a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b74310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b71400>, '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 0x1b723b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b72840>, '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': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', '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 03:58:39 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 03:58:39 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 03:58:39 INFO - Using env: {'DISPLAY': ':0', 03:58:39 INFO - 'HOME': '/home/cltbld', 03:58:39 INFO - 'LANG': 'en_US.UTF-8', 03:58:39 INFO - 'LANGUAGE': 'en_US:en', 03:58:39 INFO - 'LOGNAME': 'cltbld', 03:58:39 INFO - 'MAIL': '/var/mail/cltbld', 03:58:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:39 INFO - 'MOZ_NO_REMOTE': '1', 03:58:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:58:39 INFO - 'NO_EM_RESTART': '1', 03:58:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:58:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:39 INFO - 'PWD': '/builds/slave/test', 03:58:39 INFO - 'SHELL': '/bin/bash', 03:58:39 INFO - 'SHLVL': '1', 03:58:39 INFO - 'TERM': 'linux', 03:58:39 INFO - 'TMOUT': '86400', 03:58:39 INFO - 'USER': 'cltbld', 03:58:39 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', 03:58:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:39 INFO - '_': '/tools/buildbot/bin/python'} 03:58:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:58:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:58:40 INFO - Cleaning up... 03:58:40 INFO - Return code: 0 03:58:40 INFO - Running post-action listener: _resource_record_post_action 03:58:40 INFO - Running post-action listener: _start_resource_monitoring 03:58:40 INFO - Starting resource monitoring. 03:58:40 INFO - [mozharness: 2017-05-22 10:58:40.125148Z] Finished create-virtualenv step (success) 03:58:40 INFO - [mozharness: 2017-05-22 10:58:40.125742Z] Running install step. 03:58:40 INFO - Running pre-action listener: _resource_record_pre_action 03:58:40 INFO - Running main action method: install 03:58:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:58:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:58:40 INFO - Reading from file tmpfile_stdout 03:58:40 INFO - Detecting whether we're running mozinstall >=1.0... 03:58:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:58:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:58:40 INFO - Reading from file tmpfile_stdout 03:58:40 INFO - Output received: 03:58:40 INFO - Usage: mozinstall [options] installer 03:58:40 INFO - Options: 03:58:40 INFO - -h, --help show this help message and exit 03:58:40 INFO - -d DEST, --destination=DEST 03:58:40 INFO - Directory to install application into. [default: 03:58:40 INFO - "/builds/slave/test"] 03:58:40 INFO - --app=APP Application being installed. [default: firefox] 03:58:40 INFO - mkdir: /builds/slave/test/build/application 03:58:40 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'] 03:58:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:58:54 INFO - Reading from file tmpfile_stdout 03:58:54 INFO - Output received: 03:58:54 INFO - /builds/slave/test/build/application/firefox/firefox 03:58:54 INFO - Running post-action listener: _resource_record_post_action 03:58:54 INFO - [mozharness: 2017-05-22 10:58:54.482181Z] Finished install step (success) 03:58:54 INFO - [mozharness: 2017-05-22 10:58:54.482425Z] Running run-tests step. 03:58:54 INFO - Running pre-action listener: _resource_record_pre_action 03:58:54 INFO - Running main action method: run_tests 03:58:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:58:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:58:54 INFO - Python 2.7.3 03:58:54 INFO - Return code: 0 03:58:54 INFO - grabbing minidump binary from tooltool 03:58:54 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')]} 03:58:54 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 0x1b71400>, '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 0x1b723b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b72840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:58:54 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 03:58:54 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 03:58:54 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 03:58:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:58:54 INFO - Return code: 0 03:58:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:58:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:58:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:58:54 INFO - ENV: RUST_BACKTRACE is now 1 03:58:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:58:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:58:54 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/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 03:58:54 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/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-007 --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 03:58:54 INFO - Using env: {'DISPLAY': ':0', 03:58:54 INFO - 'HOME': '/home/cltbld', 03:58:54 INFO - 'LANG': 'en_US.UTF-8', 03:58:54 INFO - 'LANGUAGE': 'en_US:en', 03:58:54 INFO - 'LOGNAME': 'cltbld', 03:58:54 INFO - 'MAIL': '/var/mail/cltbld', 03:58:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:58:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:58:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:54 INFO - 'MOZ_NO_REMOTE': '1', 03:58:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:58:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:58:54 INFO - 'NO_EM_RESTART': '1', 03:58:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:58:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:54 INFO - 'PWD': '/builds/slave/test', 03:58:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:58:54 INFO - 'RUST_BACKTRACE': '1', 03:58:54 INFO - 'SHELL': '/bin/bash', 03:58:54 INFO - 'SHLVL': '1', 03:58:54 INFO - 'TERM': 'linux', 03:58:54 INFO - 'TMOUT': '86400', 03:58:54 INFO - 'USER': 'cltbld', 03:58:54 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298', 03:58:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:54 INFO - '_': '/tools/buildbot/bin/python'} 03:58:54 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/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 03:58:54 INFO - mozversion application_buildid: 20170522101126 03:58:54 INFO - mozversion application_changeset: cf1d4e65a1d1b7f2b1d33116669b2298212228c5 03:58:54 INFO - mozversion application_display_name: Firefox 03:58:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:58:54 INFO - mozversion application_name: Firefox 03:58:54 INFO - mozversion application_remotingname: firefox 03:58:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 03:58:54 INFO - mozversion application_vendor: Mozilla 03:58:54 INFO - mozversion application_version: 54.0 03:58:54 INFO - mozversion platform_buildid: 20170522101126 03:58:54 INFO - mozversion platform_changeset: cf1d4e65a1d1b7f2b1d33116669b2298212228c5 03:58:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 03:58:54 INFO - mozversion platform_version: 54.0 03:58:54 INFO - using testdate: 1495450734 03:58:54 INFO - actual date: 1495450734 03:58:54 INFO - starting webserver on 'localhost:49412' 03:58:54 INFO - SUITE-START | Running 2 tests 03:58:54 INFO - TEST-START | dromaeo_css 03:58:54 INFO - operating with platform_type : linux_ 03:58:54 INFO - Initialising browser for dromaeo_css test... 03:58:54 INFO - TEST-INFO | started process 14900 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkGciDL/profile http://localhost:49412/getInfo.html) 03:58:59 INFO - PROCESS | 14900 | __metrics Screen width/height:1600/1200 03:58:59 INFO - PROCESS | 14900 | colorDepth:24 03:58:59 INFO - PROCESS | 14900 | Browser inner width/height: 1024/697 03:58:59 INFO - PROCESS | 14900 | __metrics 03:59:00 INFO - PROCESS | 14900 | 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" 03:59:00 INFO - PROCESS | 14900 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:59:01 INFO - TEST-INFO | 14900: exit 0 03:59:01 INFO - Browser initialized. 03:59:01 INFO - Running cycle 1/1 for dromaeo_css test... 03:59:01 INFO - TEST-INFO | started process 14991 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkGciDL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:59:12 INFO - PROCESS | 14991 | RSS: Main: 141119488 03:59:12 INFO - PROCESS | 14991 | 04:02:14 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:49412/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49412/tests/dromaeo/cssquery-ext.html) 04:02:15 INFO - PROCESS | 14991 | RSS: Main: 275406848 04:02:15 INFO - PROCESS | 14991 | 04:05:18 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:49412/tests/dromaeo/cssquery-ext.html (next: http://localhost:49412/tests/dromaeo/cssquery-jquery.html) 04:05:18 INFO - PROCESS | 14991 | RSS: Main: 215519232 04:05:18 INFO - PROCESS | 14991 | 04:08:20 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:49412/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49412/tests/dromaeo/cssquery-mootools.html) 04:08:21 INFO - PROCESS | 14991 | RSS: Main: 202895360 04:08:21 INFO - PROCESS | 14991 | 04:11:23 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:49412/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49412/tests/dromaeo/cssquery-prototype.html) 04:11:23 INFO - PROCESS | 14991 | RSS: Main: 171749376 04:11:23 INFO - PROCESS | 14991 | 04:14:26 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:49412/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49412/tests/dromaeo/cssquery-yui.html) 04:14:27 INFO - PROCESS | 14991 | RSS: Main: 175788032 04:14:27 INFO - PROCESS | 14991 | 04:17:29 INFO - PROCESS | 14991 | Cycle 1(1): loaded http://localhost:49412/tests/dromaeo/cssquery-yui.html (next: http://localhost:49412/tests/dromaeo/cssquery-dojo.html) 04:17:30 INFO - PROCESS | 14991 | RSS: Main: 268406784 04:17:30 INFO - PROCESS | 14991 | 04:17:30 INFO - PROCESS | 14991 | __start_tp_report 04:17:30 INFO - PROCESS | 14991 | _x_x_mozilla_page_load 04:17:30 INFO - PROCESS | 14991 | _x_x_mozilla_page_load_details 04:17:30 INFO - PROCESS | 14991 | |i|pagename|runs| 04:17:30 INFO - PROCESS | 14991 | |0;dojo.html;3388.830558472076;3408.795472271662;3413.0784688134204;3458.463938089597;3478.008767501263;5697.715114244287;5786.10315401113;5791.507721843648;5794.362620111784;5814;5810.680412577307;5865.29616446026;5870.70646467677;5880.117982302657;5883.47048765611;5330.28041214435;5355.866103347428;5363.758630861621;5424.159255315432;5435.83692489224;5151.948480515184;5181.455947125554;5291.629585928987;5431.158170483582;5443.537299329542;5363.892722145574;5408.8377348679605;5414.702191379472;5417.26866872971;5419.078756611366;5393.406725260218;5399.9730001349935;5400.459954004587;5404.756785944642;5407.756650950717;5157.329547158845;5185.766640501187;5245.029669511153;5300.469953004707;5398.217258497496;5694.402087780806;5746.310442746897;5798.7100644968;5806.796762113306;5818.796342128047;6287.2140982377205;6298.3701629837105;6322.336154703736;6340.017297318923;6340.968295158541;6136.9079463808075;6372.808815735535;6397.872042559169;6399.26408463028;6403.615783053032;5770.105633626795;5796.478316951495;5839.153322768175;5839.211706419622;5856.0630299151935;6367.904481432783;6383.074454204115;6388.776392826228;6413.807585772341;6438.356164383618;6172.413620706116;6179.598326108879;6181.783637572753;6185.103160041769;6186.907196392058;6044.274687037584;6085.056816193497;6110.953680641517;6167.506599472032;6172;6257.530685198628;6294.8111556653375;6304.65324407162;6309.463695585927;6314.905276420857;6010.3689112643415;6036.0644100164545;6043.516518678495;6123.83172914054;6182.196314479089;4247.660187185018;4304.16068866568;4306.397104405386;4319.373690814814;4329.545397733255;3844.8462061517785;3847.634474724897;3896.922061558753;3897.8440862365187;3940.9014774630864;5961.433663801932;6000.729967151488;6001.219841420675;6025.3974602540275;6025.487833534199;6318.778842740482;6322.304546499881;6337.207849018872;6348.238211414659;6358;5824.534037277008;6010.068439391901;6021.789237376671;6027.095935609606;6029.065494848305;5207.29701490294;5234.476552344811;5257.842264732064;5281.339832520935;5285.101532739443;6528.053432252392;6559.573627714183;6575.210974682973;6575.638339891352;6576.408123268929;6263.154474146114;6270.6551139685935;6310.337414571404;6327.082573026977;6331.525135614939;6324.494040476843;6364.554481186273;6427.775027874002;6446.226452825504;6581.07864898905;6218.657973811484;6222.844428889133;6227.408396202353;6232.626042437287;6232.75068997235;6334.714937827716;6342.207224096988;6343.714532846126;6353.68231588428;6355.30091689923;5960.612560183574;6011.849703757266;6050.90923636137;6168.198134242699;6182.072689096672;6039.033754599243;6075.908861366995;6134.693265336626;6135.7238924249405;6137.570370074052;6111.522611241784;6139.395453409942;6146.508279337553;6147.231596050494;6164.3835616438;5946.821595352147;5969.820905372846;6006.039033754579;6016.819495415145;6017.87964240704;5881.852953676193;5927.229460170151;5940.495057919952;5953.6427814329545;5956.404359564009 04:17:30 INFO - PROCESS | 14991 | |1;ext.html;786.6656670914863;852.5481494807752;869.6304070769924;923.2936803345442;940.3511577011866;32177.67822321776;32479.67520324796;34728.65271347286;34731.47902781454;45483;47040;47286.763566182206;47498;47568.76215618926;47600;9242.537873106334;9374.953125234364;9377.67178148765;9379.24966002718;9379.624815007426;7673.577953212571;7835.764927052169;9166.037566055567;10804.459777011158;10997.945010274938;11131.109511239081;11157.442127893613;11157.832632510519;11168.609098681547;11171;11097.1122310215;11112.277701949428;11133.832992505078;11138;11152.498137583747;10775.245732798629;10776.892231077667;10800.405977671226;10836.837447438294;10847;44509.332360014625;44540;44763.104737905385;44833.32750008752;44879;4341.848035318749;4374.934375984363;4387.561243875619;4507.954920450787;4534.546545345472;30805.691943080732;30952.380952381052;31121.533177002362;31486.37027259442;33085.6691433085;4467.865964021052;4493.977530112361;4535.538632397257;4542.751813533111;4544.363789069533;238183;240612;241043;241675;242074;54598.18102728462;54864;55033.724831376385;55041.72479137579;55042;57501.712491437276;57515.42484575184;57518.42481575131;57532;57661.135082973786;16350.427735029454;16690.082045487372;16692.666146677;16699.165041747863;16704;14598.34307456167;14638.780418293734;14642.414303427744;14653.926730366493;14654.85345146535;11995.340256285988;12237.75524489523;13486.460541578226;13564.593062208149;13797.24115173655;5292.259083728281;5445.945540544623;5447.428020057837;5470.835874923759;5506.284183056177;1004.1264100232844;1016.6289304403839;1026.3929618768245;1049.5014867937668;1053.204830353094;28270;28742.425151496853;28921.85539072291;29089.41821163565;29311.706882931325;1104.4974536585835;1111.366521082991;1117.9105671546256;1122.9887701122884;1137.6643890052455;14827.703445931236;15233.3906643733;15296.541103766906;15490.612734681497;15520.5343839685;312998;313450;313581;313801;313927;57606.71196644074;58004.129938050966;58113.70943145342;58133.4186658128;58266.7086664564;1460.7370673278913;1499.1754535005703;1502.323954220627;1527.7250094982787;1539.4304107480305;27779.30551736142;28135.296617584725;28312;29064.504800392282;29108.417831643674;24263.636045459687;24344.391390215387;24401.38996525101;24403.26790196297;24415.38961525976;328969;330574;331101;331632;331871;25864.741352586232;29701.5544766833;30289.394212116076;30760;30823.53764693576;33149.83425082859;42129.78935105305;43279.567204327555;43579.34630980601;43613.34579981239;31966;31976.840115799274;32085.67914320827;32122.678773212905;32206.67793322037;30442.83112541441;33530.497042543895;33692.66307336895;33764.49353259654;34294.31411371809;36533.45199821952;36791.81604091962;36880.81559592185;37088.44367334438;37200.25599488049;30365.392692145593;30513.694863051973;30534.541981870734;30794.846025769726;31462.842685786425;24671.506569868143;24707.505849883262;25408.364790880376;25529.23412297634;25596.104136355138 04:17:30 INFO - PROCESS | 14991 | |2;jquery.html;460.4083752378195;474.70093840880264;484.2518309212269;492.8940277840265;495.52182144230824;19534.70697939532;20156.69764953525;22148;23185.536289274205;23396;22803.201887933927;22860.54278914421;23299.301020969397;23478.765212347873;23504.529909401845;6592.901106483407;6728.158980127484;6782.1183246177925;6815.931840681579;6821.863562728742;6712.328767123297;6825.453963682893;7268.6729097190755;7540.886886696677;7579.621018949058;7485.887711684329;7751.496152750082;7755.650995705207;7755.883661745078;7764.805879852993;7640.388768898475;7842.098158711764;7887.487313324616;7896.368290536743;7906.130325664172;7372.520786148883;7509.061367329084;7551.471397002212;7557.735479258255;7559.508631938907;25309.746902531107;25887.870560647265;26028.609570856454;26448.735512645013;27090;3204.150900011499;3302.4220761366905;3513.789172649649;3589.515415418937;3676.5588129424373;15980.28088735998;16755.748663770053;16984.15079246033;17119.31522739089;17183.31266749341;2347.33101066197;2406.061635961976;2411.455914010718;2468.4075821802817;2487.9004839806566;106731;108975.45512272388;109118.45440772825;109698.9999999992;112666.43666781696;17803.73294400585;18287.54281142982;18424.723629145574;18605.348812791486;18691.345802896834;31205.843970780003;31239.219019524695;31254.281014543965;31275.84362078175;31440.842795785873;10348.5343159558;10390.688279351632;10393.58425662965;10495.632652857266;10535.578576856993;8480.406371553932;8581.013183483854;8646.3947523674;8799.824003519894;8853;9137.63449462014;9436.292278079172;9618.374805637752;9708.174805141502;9747.415155090717;3698.8890333290046;3833.5591406987996;3848.461215429842;3973.8013099344917;3975.264576053439;964.5852283518154;966.2608104799833;968.8352979993418;969.6063776781266;989.3569180032893;21301.68047479258;21627.891860540596;21705.565888682453;21719;21729.130834766747;977.5161295158941;987.3138168972639;993.9006099390003;1013.5236438873718;1020.4846552490944;11225.719357015976;11566.67199616275;12077.214981026205;12178.451969661386;12183;589795;612786;625843;631567;634576;88355.55822220848;96379.51810240904;105455;111255;112264;1286.6783304173957;1291.3994992328612;1314.3953781260536;1318.7296604196117;1354.8983826213073;19535.316263930694;19741.901290493457;19912.402627921187;19954.700679489528;19993.700094498305;42894;54771.72614137063;54858;54875.45124548863;55283.17075243794;70564;71339.28660713533;71741.28258717555;71947.64026179835;72302.27697722956;11917.702057448632;12330.75338493243;12470.68823279389;12473.750524989631;12510.87489125097;13487.32563371808;13697;13952.511662091778;14130.78803817964;14207.360668769723;11207.327560346404;11257;11260.774784504429;11279.477270568368;11529.135314851588;10811.35131892089;11007.559697612327;11193.832092518456;11243.381614011309;11729.296242225493;11891.583794567148;11922.165448418875;12090.45592948337;12101.394930253628;12177.512899483922;12451.128421010442;12631.684207894876;12634.557790477289;12777.552785652457;12956.67608309762;11242.381669008282;11797.056235501312;11839.76320473603;12110.333931633844;12170.939145304217 04:17:30 INFO - PROCESS | 14991 | |3;mootools.html;1235.3761350517987;1564.6635973265752;1573.9291731872065;1588.8808339374543;1596.0902285697152;9702.4178549287;11023.283486573368;11379.772404551904;11385.829212561797;11390.715232119192;11465.484053217604;11573.36346500942;11578.53685852565;11588.130890183249;11606.245594036382;1964.489232799468;2023.0188358646044;2467.2721547143547;2483.5032993401346;2521.231024537515;2552.5150221457957;2572.1383336582267;2580.187241019082;2580.187241019082;2581.6772903387077;2464.162184857148;2501.487195124995;2518.697756269241;2522.155078048856;2524.4572416930355;2346.577616029114;2394.317619478449;2402;2402.6636270922086;2403.158894386968;2365.31405892291;2387.4150833046015;2389.1518510928718;2393.293978276395;2394.341556072077;11518.596849110241;11749;11809.468573914195;11811.114166437552;11827.822582661267;5917.319508256563;6035.577509574332;6057.939420605781;6062.63624182546;6077.817665470043;3362.923864363405;3418.2141554633145;3472.7742696724886;3473.4095203815414;3560.6439356064448;1308.725167714781;1325.6023193042145;1332.4403750424844;1364.8225730654951;1365.208179256029;16885.66228675432;17772.644547109114;17859;17879.642407151914;17886.910565447088;4768.117898188777;4820.373351464287;4896.36347274852;4939.555440010345;4947;13398.531051413154;13515;13532.661683457993;13534.187948723109;13549.729005419837;6076.817695469143;6076.966915624354;6077.513798896078;6080.361562036011;6088.817335479943;4521.434820647419;4635.860924172281;5493.258410114583;5598.776048958078;5608.607397482218;4068.069974301299;4092.7339722917914;4105.158442519277;4147.564505726855;4148.357004664282;1078.8435676826816;1081.291753901196;1087.5160553553592;1095.479647167605;1098.758273179899;1056.4295280548572;1064.744461329291;1067.3969207397795;1077.7036315013434;1079.7894410589859;7153;7220.711171553184;7226.458015648742;7307.817304567428;7357.5217610855125;1083.588236470152;1130.112861403794;1137.1016896651718;1144;1150.7698460307972;4769.880752981134;4785.9760701196965;4835;4945.406551213805;4947.381577302837;14160.36278367476;14711.92644036773;14715.264236788118;14768.040077394935;14776.556703298918;14387.92806035963;14416.351264193136;14429.278536073154;14435.278236088154;14540.345684444226;10531.15750739939;10963.725906852233;11004.724881877857;11029.724256893642;11060.668179954615;7286.817829554304;7336.559806411632;7414.777556673308;7429.851402971803;7614.124375696644;13247.271400073088;13261.933690331487;13264.53574124901;13512.189268643913;13532.59402217935;13480.123791953492;13505.257210853497;13506.39221235027;13517.188968661518;13551.796723048965;8075.475094118863;9003.54982250898;9425.151736343762;9605.615775368908;9950.402975821475;11115;11250.21248512629;11363.602273920373;11391.886081139413;11488.540458381905;9401.623935042795;9428.95285523568;9435.528223588655;9452.338336316392;9453.101955314234;8845.336599754915;8983.101689830964;9013.414128081653;9245;9381.484018378782;8416.747497575083;8472.364572656952;8624.310055195443;8632.136786321318;8641.95679021626;6447.484201263794;6556.475481961527;6558.114654521579;6596.406323430914;6613.2394774601535;6568.540202185801;6666.2333831609985;6703.4972377070935;6710.899336510054;6831.282715315019 04:17:30 INFO - PROCESS | 14991 | |4;prototype.html;419.4358587699547;446.2413896376161;446.3527884567378;446.7408902836355;449.346201277142;5150.871228219296;5187.325647665808;5223.399309079457;5237.528622423981;5238.202303330084;4942.961977984619;5183.652250414891;5191.247269145972;5193.896122077565;5210.5310522053005;4337.219300525904;4386.539413361583;4386.859416551703;4405.625521830648;4405.8237670493145;4352.759463552888;4392.430428199507;4414.29371300592;4420.160169567772;4420.491643461011;4381.255186618282;4464.325877795826;4487.618552423048;4503.527129651388;4516.570925762047;4325.745533795196;4438.689291749579;4440.178566965122;4445.955200527874;4463.575960283768;4158.604932531435;4190.8533201337805;4210.494740631113;4211.642010429086;4229.513605935295;5102.846914592531;5111.182210846284;5124.436312005676;5134.5378915897745;5172.715500647463;4886.389201349832;5080.187170052774;5122.180451127839;5139.640225184238;5166.243477217727;5466.808661696861;5471.510462739484;5511;5514.7187423483365;5518.310211223597;4593.333966574828;4610;4627.722336659777;4628.352030715702;4649.790759415834;5398.2982212312145;5436.21174929637;5532.937362313478;5562.802692936268;5636.436356364372;4656.534346565384;4676.532346765296;4679.508651591602;4680.368150299666;4690.976545117321;4715.3869996900185;4771.71369717818;4786.611882554021;4804.279358096243;4813.3983252093485;4872.903596690716;5021.84934451967;5029.5724863386295;5077.822276220315;5132.178851383761;5382.730863456812;5436.266104075979;5451.809186678447;5460.699661518575;5466.9179962300595;1631.7226071567625;1634.3625985865522;1646.1851383565213;1651.1991684033235;1661.310556119197;1267.6957530192687;1281.2376635901624;1288.0854593238905;1297.94866158412;1312.5471712259255;5247.2653828464045;5270.683758974397;5345.65253258537;5449.621234834349;5477.794885125266;5666.575006874501;5691.373948865621;5705.771769129188;5725.971370143123;5734.541236701054;5224.817131400383;5323.866903327448;5332.3867755207875;5356.651817631841;5401.189821526802;2926.326944802707;2961.9485082795727;2966.24360788002;2966.2732630505666;2967.4865818432754;5689.459501347365;5743.224664670216;5842;5860.970695146497;5886.646801191942;5594.636348637326;5689.089745640677;5690.829275121753;5707.942920570741;5751.971240143773;5766.740496677576;5858.50202732764;5858.560607954335;5883.823485295448;5917.733701983335;5296.258523806747;5516.365617953987;5590.608657393944;5594.328680558359;5624.353199382123;5331.866703332293;5553.805616803393;5573.972130139324;5580.190872323572;5602.683369408267;5413.350397952271;5666.13860000697;5669.659820410789;5708.885822283614;5721.025066965071;4585.633149347977;4652.767361631973;4679.181143299841;4680.789364478538;4699.6371052394425;5527.50252477279;5606.803761868315;5630.943690563206;5651.378348381592;5668.291463567054;5461;5462.590305727007;5471.343438787371;5478.822053258568;5521.668699878021;5473.753681084281;5476.835694929158;5487.012337779079;5549.278593782782;5568.387477377404;5349.732513374393;5434.972825135849;5449.7002664852225;5493.285872836505;5507.642003269774;5371.462853714597;5379.811706590251;5437.308532276548;5451.945480545144;5462.508374246337;5240.973795131;5310.203469479455;5390.568754499709;5398.784048638167;5404.810831621032 04:17:30 INFO - PROCESS | 14991 | |5;yui.html;830.7009476588427;863.4344504349654;927.9118483744043;948.8861336639604;962.9518524073796;17333.65332693347;19339.129739161755;19513.902430487862;19553.60892782147;19644.70532942007;17985.820141798577;18199.81800181998;18580.814191858077;18699.53251168718;18734.625307493876;907.3829795738895;933.3093510801991;935.6163972771166;936.204226407555;938.9671361502349;904.669795524632;915.3180880244228;915.4187091197072;918.7703074231442;935.5135329628589;966.1015255812092;967.632299726103;969.5491596407669;973.2019743810079;974.1232890398627;952.6713283917038;956.5504213019905;958.8465845464692;964.4599024546277;964.9131578157965;712.401582670554;712.4122598855913;714.0110946339357;715.3526058916689;715.3811952660963;18664.44006679802;18710.71933920992;18927.810721892743;19892;20195.697064544045;16560.42038528658;16757.99452032882;16826.32694692211;16829.242684079123;17018.82981170185;13825.239611821346;13876.791848122286;14176.716465670628;14571.854281457156;14952.850471495254;4166.729162604421;4191.685623578213;4202.873913782591;4224.260754367973;4370.825166993348;93464.53267733686;94576.52711736466;94806;97339.99999999929;99380;13217.14088584239;13277.867221327857;13281.734365312639;13294.135881167693;13354.599362019155;25083.498330033297;25136.12023579166;25316.493670126496;25319.62020569693;25633.74366256351;9068.909310906938;9175.403598766059;9255;9319.953400232955;9341.486218257925;5157.742112894341;5220.686758794484;5246.580273578104;5260.395054568774;5341.198820177005;3777.660010599059;3829.1384438501113;3897.7661340319105;3901.141748815256;3921.156951255494;653.9313372095957;658.5225711359323;665.7403612591094;670.4435318685479;672.1363048482676;657.980260592183;672.1396612336217;692.3837784371913;693.4833549006023;696.1472196559275;11403.600873969372;11817.207249881476;12555;12592.118551701476;12996.675083122998;847.4449235750593;855.5593869157389;859.3769517100052;886.8625363068735;893.7050773244814;5615.971920140373;5617.9438205618235;6014.699265036731;6259.499240060784;6295.5907865988565;81960.18039819645;82499;83506.58246708849;84481.57759211164;85703;73402.63298683579;74438;74838.25161748422;76067.61966190244;76883;68047.65976170187;68524;68534.62930741358;68768.6561567199;70021;12040.939795300967;12594.622161335175;12666.60703104052;12820.679483013;12943.805842912552;44807;45062.32406513971;46284.7685761569;46288.30567541557;47140;54325.185122223986;55036.44963550313;55158.44841551533;55613.443865560825;56674.7166264166;14630.414783408545;14947.925260373628;15060;15064.548063558112;15228.923855380652;17224.827751722823;17388.913055435147;17474.650506990045;17689;17715.29138834432;16440.917795410947;16613.169341532634;16625;16914.238859251116;17679.558011049827;15577.532674019798;15638.765418518504;17001;17004.914975425043;17014.48956531306;15569.455069072528;16228.67542649164;16971.406000789913;16977.49067527976;16991.7451238234;11547.942260288644;11974.341411222467;12180;12425.75148497007;12461.252324860538;13340.866591333963;13462.730745385234;13544.390502427217;13559.186448813103;13663.043586948817 04:17:30 INFO - PROCESS | 14991 | __end_tp_report 04:17:30 INFO - PROCESS | 14991 | __start_cc_report 04:17:30 INFO - PROCESS | 14991 | _x_x_mozilla_cycle_collect,1325 04:17:30 INFO - PROCESS | 14991 | __end_cc_report 04:17:30 INFO - PROCESS | 14991 | __startTimestamp1495451850002__endTimestamp 04:17:30 INFO - PROCESS | 14991 | 04:17:30 INFO - PROCESS | 14991 | ------- Summary: start ------- 04:17:30 INFO - PROCESS | 14991 | Number of tests: 6 04:17:30 INFO - PROCESS | 14991 | 04:17:30 INFO - PROCESS | 14991 | [#0] dojo.html Cycles:165 Average:5788.18 Median:6017.88 stddev:716.61 (11.9%) stddev-sans-first:693.64 04:17:30 INFO - PROCESS | 14991 | Values: 3388.8 3408.8 3413.1 3458.5 3478.0 5697.7 5786.1 5791.5 5794.4 5814.0 5810.7 5865.3 5870.7 5880.1 5883.5 5330.3 5355.9 5363.8 5424.2 5435.8 5151.9 5181.5 5291.6 5431.2 5443.5 5363.9 5408.8 5414.7 5417.3 5419.1 5393.4 5400.0 5400.5 5404.8 5407.8 5157.3 5185.8 5245.0 5300.5 5398.2 5694.4 5746.3 5798.7 5806.8 5818.8 6287.2 6298.4 6322.3 6340.0 6341.0 6136.9 6372.8 6397.9 6399.3 6403.6 5770.1 5796.5 5839.2 5839.2 5856.1 6367.9 6383.1 6388.8 6413.8 6438.4 6172.4 6179.6 6181.8 6185.1 6186.9 6044.3 6085.1 6111.0 6167.5 6172.0 6257.5 6294.8 6304.7 6309.5 6314.9 6010.4 6036.1 6043.5 6123.8 6182.2 4247.7 4304.2 4306.4 4319.4 4329.5 3844.8 3847.6 3896.9 3897.8 3940.9 5961.4 6000.7 6001.2 6025.4 6025.5 6318.8 6322.3 6337.2 6348.2 6358.0 5824.5 6010.1 6021.8 6027.1 6029.1 5207.3 5234.5 5257.8 5281.3 5285.1 6528.1 6559.6 6575.2 6575.6 6576.4 6263.2 6270.7 6310.3 6327.1 6331.5 6324.5 6364.6 6427.8 6446.2 6581.1 6218.7 6222.8 6227.4 6232.6 6232.8 6334.7 6342.2 6343.7 6353.7 6355.3 5960.6 6011.8 6050.9 6168.2 6182.1 6039.0 6075.9 6134.7 6135.7 6137.6 6111.5 6139.4 6146.5 6147.2 6164.4 5946.8 5969.8 6006.0 6016.8 6017.9 5881.9 5927.2 5940.5 5953.6 5956.4 04:17:30 INFO - PROCESS | 14991 | 04:17:30 INFO - PROCESS | 14991 | [#1] ext.html Cycles:180 Average:45991.18 Median:313224.00 stddev:77893.09 (24.9%) stddev-sans-first:78037.65 04:17:30 INFO - PROCESS | 14991 | Values: 786.7 852.5 869.6 923.3 940.4 32177.7 32479.7 34728.7 34731.5 45483.0 47040.0 47286.8 47498.0 47568.8 47600.0 9242.5 9375.0 9377.7 9379.2 9379.6 7673.6 7835.8 9166.0 10804.5 10997.9 11131.1 11157.4 11157.8 11168.6 11171.0 11097.1 11112.3 11133.8 11138.0 11152.5 10775.2 10776.9 10800.4 10836.8 10847.0 44509.3 44540.0 44763.1 44833.3 44879.0 4341.8 4374.9 4387.6 4508.0 4534.5 30805.7 30952.4 31121.5 31486.4 33085.7 4467.9 4494.0 4535.5 4542.8 4544.4 238183.0 240612.0 241043.0 241675.0 242074.0 54598.2 54864.0 55033.7 55041.7 55042.0 57501.7 57515.4 57518.4 57532.0 57661.1 16350.4 16690.1 16692.7 16699.2 16704.0 14598.3 14638.8 14642.4 14653.9 14654.9 11995.3 12237.8 13486.5 13564.6 13797.2 5292.3 5445.9 5447.4 5470.8 5506.3 1004.1 1016.6 1026.4 1049.5 1053.2 28270.0 28742.4 28921.9 29089.4 29311.7 1104.5 1111.4 1117.9 1123.0 1137.7 14827.7 15233.4 15296.5 15490.6 15520.5 312998.0 313450.0 313581.0 313801.0 313927.0 57606.7 58004.1 58113.7 58133.4 58266.7 1460.7 1499.2 1502.3 1527.7 1539.4 27779.3 28135.3 28312.0 29064.5 29108.4 24263.6 24344.4 24401.4 24403.3 24415.4 328969.0 330574.0 331101.0 331632.0 331871.0 25864.7 29701.6 30289.4 30760.0 30823.5 33149.8 42129.8 43279.6 43579.3 43613.3 31966.0 31976.8 32085.7 32122.7 32206.7 30442.8 33530.5 33692.7 33764.5 34294.3 36533.5 36791.8 36880.8 37088.4 37200.3 30365.4 30513.7 30534.5 30794.8 31462.8 24671.5 24707.5 25408.4 25529.2 25596.1 04:17:30 INFO - PROCESS | 14991 | 04:17:30 INFO - PROCESS | 14991 | [#2] jquery.html Cycles:180 Average:36400.07 Median:12955.30 stddev:101946.85 (786.9%) stddev-sans-first:102197.12 04:17:30 INFO - PROCESS | 14991 | Values: 460.4 474.7 484.3 492.9 495.5 19534.7 20156.7 22148.0 23185.5 23396.0 22803.2 22860.5 23299.3 23478.8 23504.5 6592.9 6728.2 6782.1 6815.9 6821.9 6712.3 6825.5 7268.7 7540.9 7579.6 7485.9 7751.5 7755.7 7755.9 7764.8 7640.4 7842.1 7887.5 7896.4 7906.1 7372.5 7509.1 7551.5 7557.7 7559.5 25309.7 25887.9 26028.6 26448.7 27090.0 3204.2 3302.4 3513.8 3589.5 3676.6 15980.3 16755.7 16984.2 17119.3 17183.3 2347.3 2406.1 2411.5 2468.4 2487.9 106731.0 108975.5 109118.5 109699.0 112666.4 17803.7 18287.5 18424.7 18605.3 18691.3 31205.8 31239.2 31254.3 31275.8 31440.8 10348.5 10390.7 10393.6 10495.6 10535.6 8480.4 8581.0 8646.4 8799.8 8853.0 9137.6 9436.3 9618.4 9708.2 9747.4 3698.9 3833.6 3848.5 3973.8 3975.3 964.6 966.3 968.8 969.6 989.4 21301.7 21627.9 21705.6 21719.0 21729.1 977.5 987.3 993.9 1013.5 1020.5 11225.7 11566.7 12077.2 12178.5 12183.0 589795.0 612786.0 625843.0 631567.0 634576.0 88355.6 96379.5 105455.0 111255.0 112264.0 1286.7 1291.4 1314.4 1318.7 1354.9 19535.3 19741.9 19912.4 19954.7 19993.7 42894.0 54771.7 54858.0 54875.5 55283.2 70564.0 71339.3 71741.3 71947.6 72302.3 11917.7 12330.8 12470.7 12473.8 12510.9 13487.3 13697.0 13952.5 14130.8 14207.4 11207.3 11257.0 11260.8 11279.5 11529.1 10811.4 11007.6 11193.8 11243.4 11729.3 11891.6 11922.2 12090.5 12101.4 12177.5 12451.1 12631.7 12634.6 12777.6 12956.7 11242.4 11797.1 11839.8 12110.3 12170.9 04:17:30 INFO - PROCESS | 14991 | 04:17:30 INFO - PROCESS | 14991 | [#3] mootools.html Cycles:180 Average:7242.06 Median:2433.66 stddev:4636.14 (190.5%) stddev-sans-first:4627.18 04:17:30 INFO - PROCESS | 14991 | Values: 1235.4 1564.7 1573.9 1588.9 1596.1 9702.4 11023.3 11379.8 11385.8 11390.7 11465.5 11573.4 11578.5 11588.1 11606.2 1964.5 2023.0 2467.3 2483.5 2521.2 2552.5 2572.1 2580.2 2580.2 2581.7 2464.2 2501.5 2518.7 2522.2 2524.5 2346.6 2394.3 2402.0 2402.7 2403.2 2365.3 2387.4 2389.2 2393.3 2394.3 11518.6 11749.0 11809.5 11811.1 11827.8 5917.3 6035.6 6057.9 6062.6 6077.8 3362.9 3418.2 3472.8 3473.4 3560.6 1308.7 1325.6 1332.4 1364.8 1365.2 16885.7 17772.6 17859.0 17879.6 17886.9 4768.1 4820.4 4896.4 4939.6 4947.0 13398.5 13515.0 13532.7 13534.2 13549.7 6076.8 6077.0 6077.5 6080.4 6088.8 4521.4 4635.9 5493.3 5598.8 5608.6 4068.1 4092.7 4105.2 4147.6 4148.4 1078.8 1081.3 1087.5 1095.5 1098.8 1056.4 1064.7 1067.4 1077.7 1079.8 7153.0 7220.7 7226.5 7307.8 7357.5 1083.6 1130.1 1137.1 1144.0 1150.8 4769.9 4786.0 4835.0 4945.4 4947.4 14160.4 14711.9 14715.3 14768.0 14776.6 14387.9 14416.4 14429.3 14435.3 14540.3 10531.2 10963.7 11004.7 11029.7 11060.7 7286.8 7336.6 7414.8 7429.9 7614.1 13247.3 13261.9 13264.5 13512.2 13532.6 13480.1 13505.3 13506.4 13517.2 13551.8 8075.5 9003.5 9425.2 9605.6 9950.4 11115.0 11250.2 11363.6 11391.9 11488.5 9401.6 9429.0 9435.5 9452.3 9453.1 8845.3 8983.1 9013.4 9245.0 9381.5 8416.7 8472.4 8624.3 8632.1 8642.0 6447.5 6556.5 6558.1 6596.4 6613.2 6568.5 6666.2 6703.5 6710.9 6831.3 04:17:30 INFO - PROCESS | 14991 | 04:17:30 INFO - PROCESS | 14991 | [#4] prototype.html Cycles:180 Average:4784.35 Median:5239.59 stddev:1256.63 (24.0%) stddev-sans-first:1216.70 04:17:30 INFO - PROCESS | 14991 | Values: 419.4 446.2 446.4 446.7 449.3 5150.9 5187.3 5223.4 5237.5 5238.2 4943.0 5183.7 5191.2 5193.9 5210.5 4337.2 4386.5 4386.9 4405.6 4405.8 4352.8 4392.4 4414.3 4420.2 4420.5 4381.3 4464.3 4487.6 4503.5 4516.6 4325.7 4438.7 4440.2 4446.0 4463.6 4158.6 4190.9 4210.5 4211.6 4229.5 5102.8 5111.2 5124.4 5134.5 5172.7 4886.4 5080.2 5122.2 5139.6 5166.2 5466.8 5471.5 5511.0 5514.7 5518.3 4593.3 4610.0 4627.7 4628.4 4649.8 5398.3 5436.2 5532.9 5562.8 5636.4 4656.5 4676.5 4679.5 4680.4 4691.0 4715.4 4771.7 4786.6 4804.3 4813.4 4872.9 5021.8 5029.6 5077.8 5132.2 5382.7 5436.3 5451.8 5460.7 5466.9 1631.7 1634.4 1646.2 1651.2 1661.3 1267.7 1281.2 1288.1 1297.9 1312.5 5247.3 5270.7 5345.7 5449.6 5477.8 5666.6 5691.4 5705.8 5726.0 5734.5 5224.8 5323.9 5332.4 5356.7 5401.2 2926.3 2961.9 2966.2 2966.3 2967.5 5689.5 5743.2 5842.0 5861.0 5886.6 5594.6 5689.1 5690.8 5707.9 5752.0 5766.7 5858.5 5858.6 5883.8 5917.7 5296.3 5516.4 5590.6 5594.3 5624.4 5331.9 5553.8 5574.0 5580.2 5602.7 5413.4 5666.1 5669.7 5708.9 5721.0 4585.6 4652.8 4679.2 4680.8 4699.6 5527.5 5606.8 5630.9 5651.4 5668.3 5461.0 5462.6 5471.3 5478.8 5521.7 5473.8 5476.8 5487.0 5549.3 5568.4 5349.7 5435.0 5449.7 5493.3 5507.6 5371.5 5379.8 5437.3 5451.9 5462.5 5241.0 5310.2 5390.6 5398.8 5404.8 04:17:30 INFO - PROCESS | 14991 | 04:17:30 INFO - PROCESS | 14991 | [#5] yui.html Cycles:180 Average:20005.15 Median:44934.66 stddev:24739.39 (55.1%) stddev-sans-first:24766.89 04:17:30 INFO - PROCESS | 14991 | Values: 830.7 863.4 927.9 948.9 963.0 17333.7 19339.1 19513.9 19553.6 19644.7 17985.8 18199.8 18580.8 18699.5 18734.6 907.4 933.3 935.6 936.2 939.0 904.7 915.3 915.4 918.8 935.5 966.1 967.6 969.5 973.2 974.1 952.7 956.6 958.8 964.5 964.9 712.4 712.4 714.0 715.4 715.4 18664.4 18710.7 18927.8 19892.0 20195.7 16560.4 16758.0 16826.3 16829.2 17018.8 13825.2 13876.8 14176.7 14571.9 14952.9 4166.7 4191.7 4202.9 4224.3 4370.8 93464.5 94576.5 94806.0 97340.0 99380.0 13217.1 13277.9 13281.7 13294.1 13354.6 25083.5 25136.1 25316.5 25319.6 25633.7 9068.9 9175.4 9255.0 9320.0 9341.5 5157.7 5220.7 5246.6 5260.4 5341.2 3777.7 3829.1 3897.8 3901.1 3921.2 653.9 658.5 665.7 670.4 672.1 658.0 672.1 692.4 693.5 696.1 11403.6 11817.2 12555.0 12592.1 12996.7 847.4 855.6 859.4 886.9 893.7 5616.0 5617.9 6014.7 6259.5 6295.6 81960.2 82499.0 83506.6 84481.6 85703.0 73402.6 74438.0 74838.3 76067.6 76883.0 68047.7 68524.0 68534.6 68768.7 70021.0 12040.9 12594.6 12666.6 12820.7 12943.8 44807.0 45062.3 46284.8 46288.3 47140.0 54325.2 55036.4 55158.4 55613.4 56674.7 14630.4 14947.9 15060.0 15064.5 15228.9 17224.8 17388.9 17474.7 17689.0 17715.3 16440.9 16613.2 16625.0 16914.2 17679.6 15577.5 15638.8 17001.0 17004.9 17014.5 15569.5 16228.7 16971.4 16977.5 16991.7 11547.9 11974.3 12180.0 12425.8 12461.3 13340.9 13462.7 13544.4 13559.2 13663.0 04:17:30 INFO - PROCESS | 14991 | -------- Summary: end -------- 04:17:30 INFO - PROCESS | 14991 | 04:17:30 INFO - TEST-INFO | 14991: exit 0 04:17:30 INFO - TEST-OK | dromaeo_css | took 1116007ms 04:17:30 INFO - TEST-START | kraken 04:17:30 INFO - operating with platform_type : linux_ 04:17:30 INFO - Initialising browser for kraken test... 04:17:30 INFO - TEST-INFO | started process 15296 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQigFqh/profile http://localhost:49412/getInfo.html) 04:17:34 INFO - PROCESS | 15296 | __metrics Screen width/height:1600/1200 04:17:34 INFO - PROCESS | 15296 | colorDepth:24 04:17:34 INFO - PROCESS | 15296 | Browser inner width/height: 1024/697 04:17:34 INFO - PROCESS | 15296 | __metrics 04:17:35 INFO - PROCESS | 15296 | 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" 04:17:35 INFO - PROCESS | 15296 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:17:36 INFO - TEST-INFO | 15296: exit 0 04:17:36 INFO - Browser initialized. 04:17:36 INFO - Running cycle 1/1 for kraken test... 04:17:36 INFO - TEST-INFO | started process 15390 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQigFqh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:17:47 INFO - PROCESS | 15390 | RSS: Main: 142069760 04:17:47 INFO - PROCESS | 15390 | 04:17:50 INFO - PROCESS | 15390 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49412/tests/kraken/driver.html?testName=audio-beat-detection) 04:17:51 INFO - PROCESS | 15390 | RSS: Main: 183775232 04:17:51 INFO - PROCESS | 15390 | 04:17:53 INFO - PROCESS | 15390 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49412/tests/kraken/driver.html?testName=audio-dft) 04:17:54 INFO - PROCESS | 15390 | RSS: Main: 173944832 04:17:54 INFO - PROCESS | 15390 | 04:17:56 INFO - PROCESS | 15390 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49412/tests/kraken/driver.html?testName=audio-fft) 04:17:57 INFO - PROCESS | 15390 | RSS: Main: 265846784 04:17:57 INFO - PROCESS | 15390 | 04:17:59 INFO - PROCESS | 15390 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49412/tests/kraken/driver.html?testName=audio-oscillator) 04:18:00 INFO - PROCESS | 15390 | RSS: Main: 171872256 04:18:00 INFO - PROCESS | 15390 | 04:18:02 INFO - PROCESS | 15390 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49412/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:18:02 INFO - PROCESS | 15390 | RSS: Main: 174104576 04:18:02 INFO - PROCESS | 15390 | 04:18:05 INFO - PROCESS | 15390 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49412/tests/kraken/driver.html?testName=imaging-darkroom) 04:18:06 INFO - PROCESS | 15390 | RSS: Main: 212312064 04:18:06 INFO - PROCESS | 15390 | 04:18:09 INFO - PROCESS | 15390 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49412/tests/kraken/driver.html?testName=imaging-desaturate) 04:18:10 INFO - PROCESS | 15390 | RSS: Main: 213282816 04:18:10 INFO - PROCESS | 15390 | 04:18:13 INFO - PROCESS | 15390 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49412/tests/kraken/driver.html?testName=json-parse-financial) 04:18:13 INFO - PROCESS | 15390 | RSS: Main: 211529728 04:18:13 INFO - PROCESS | 15390 | 04:18:15 INFO - PROCESS | 15390 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49412/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:18:15 INFO - PROCESS | 15390 | RSS: Main: 173748224 04:18:15 INFO - PROCESS | 15390 | 04:18:17 INFO - PROCESS | 15390 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:18:18 INFO - PROCESS | 15390 | RSS: Main: 180731904 04:18:18 INFO - PROCESS | 15390 | 04:18:20 INFO - PROCESS | 15390 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:18:20 INFO - PROCESS | 15390 | RSS: Main: 190377984 04:18:20 INFO - PROCESS | 15390 | 04:18:23 INFO - PROCESS | 15390 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:18:24 INFO - PROCESS | 15390 | RSS: Main: 199327744 04:18:24 INFO - PROCESS | 15390 | 04:18:26 INFO - PROCESS | 15390 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:18:27 INFO - PROCESS | 15390 | RSS: Main: 195399680 04:18:27 INFO - PROCESS | 15390 | 04:18:28 INFO - PROCESS | 15390 | Cycle 1(1): loaded http://localhost:49412/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49412/tests/kraken/driver.html?testName=ai-astar) 04:18:29 INFO - PROCESS | 15390 | RSS: Main: 247230464 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | __start_tp_report 04:18:29 INFO - PROCESS | 15390 | _x_x_mozilla_page_load 04:18:29 INFO - PROCESS | 15390 | _x_x_mozilla_page_load_details 04:18:29 INFO - PROCESS | 15390 | |i|pagename|runs| 04:18:29 INFO - PROCESS | 15390 | |0;ai-astar;103;112;112;113;113;113;112;113;112;113 04:18:29 INFO - PROCESS | 15390 | |1;audio-beat-detection;142;115;115;155;117;121;120;113;134;115 04:18:29 INFO - PROCESS | 15390 | |2;audio-dft;162;161;160;160;160;162;162;163;161;161 04:18:29 INFO - PROCESS | 15390 | |3;audio-fft;74;76;75;108;77;76;77;75;93;75 04:18:29 INFO - PROCESS | 15390 | |4;audio-oscillator;82;82;99;83;82;82;98;82;83;104 04:18:29 INFO - PROCESS | 15390 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;113;112;113 04:18:29 INFO - PROCESS | 15390 | |6;imaging-darkroom;147;146;146;146;146;145;146;145;146;146 04:18:29 INFO - PROCESS | 15390 | |7;imaging-desaturate;93;93;94;94;93;94;94;93;94;93 04:18:29 INFO - PROCESS | 15390 | |8;json-parse-financial;54;56;55;60;56;55;54;54;54;55 04:18:29 INFO - PROCESS | 15390 | |9;json-stringify-tinderbox;53;53;54;55;54;54;54;53;54;54 04:18:29 INFO - PROCESS | 15390 | |10;stanford-crypto-aes;74;75;79;75;74;78;78;78;74;75 04:18:29 INFO - PROCESS | 15390 | |11;stanford-crypto-ccm;126;132;120;121;128;123;118;118;164;119 04:18:29 INFO - PROCESS | 15390 | |12;stanford-crypto-pbkdf2;160;157;158;163;160;157;159;158;154;157 04:18:29 INFO - PROCESS | 15390 | |13;stanford-crypto-sha256-iterative;60;61;59;60;59;61;60;60;59;58 04:18:29 INFO - PROCESS | 15390 | __end_tp_report 04:18:29 INFO - PROCESS | 15390 | __start_cc_report 04:18:29 INFO - PROCESS | 15390 | _x_x_mozilla_cycle_collect,1669 04:18:29 INFO - PROCESS | 15390 | __end_cc_report 04:18:29 INFO - PROCESS | 15390 | __startTimestamp1495451909164__endTimestamp 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | ------- Summary: start ------- 04:18:29 INFO - PROCESS | 15390 | Number of tests: 14 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | [#0] ai-astar Cycles:10 Average:111.60 Median:113.00 stddev:3.06 (2.7%) stddev-sans-first:0.53 04:18:29 INFO - PROCESS | 15390 | Values: 103.0 112.0 112.0 113.0 113.0 113.0 112.0 113.0 112.0 113.0 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | [#1] audio-beat-detection Cycles:10 Average:124.70 Median:120.50 stddev:14.21 (11.8%) stddev-sans-first:13.63 04:18:29 INFO - PROCESS | 15390 | Values: 142.0 115.0 115.0 155.0 117.0 121.0 120.0 113.0 134.0 115.0 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.50 stddev:1.03 (0.6%) stddev-sans-first:1.05 04:18:29 INFO - PROCESS | 15390 | Values: 162.0 161.0 160.0 160.0 160.0 162.0 162.0 163.0 161.0 161.0 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | [#3] audio-fft Cycles:10 Average:80.60 Median:76.00 stddev:11.11 (14.6%) stddev-sans-first:11.52 04:18:29 INFO - PROCESS | 15390 | Values: 74.0 76.0 75.0 108.0 77.0 76.0 77.0 75.0 93.0 75.0 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | [#4] audio-oscillator Cycles:10 Average:87.70 Median:82.50 stddev:8.86 (10.7%) stddev-sans-first:9.15 04:18:29 INFO - PROCESS | 15390 | Values: 82.0 82.0 99.0 83.0 82.0 82.0 98.0 82.0 83.0 104.0 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:18:29 INFO - PROCESS | 15390 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 112.0 113.0 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.44 04:18:29 INFO - PROCESS | 15390 | Values: 147.0 146.0 146.0 146.0 146.0 145.0 146.0 145.0 146.0 146.0 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | [#7] imaging-desaturate Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 04:18:29 INFO - PROCESS | 15390 | Values: 93.0 93.0 94.0 94.0 93.0 94.0 94.0 93.0 94.0 93.0 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.00 stddev:1.83 (3.3%) stddev-sans-first:1.88 04:18:29 INFO - PROCESS | 15390 | Values: 54.0 56.0 55.0 60.0 56.0 55.0 54.0 54.0 54.0 55.0 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | [#9] json-stringify-tinderbox Cycles:10 Average:53.80 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.60 04:18:29 INFO - PROCESS | 15390 | Values: 53.0 53.0 54.0 55.0 54.0 54.0 54.0 53.0 54.0 54.0 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:76.50 stddev:2.00 (2.6%) stddev-sans-first:1.99 04:18:29 INFO - PROCESS | 15390 | Values: 74.0 75.0 79.0 75.0 74.0 78.0 78.0 78.0 74.0 75.0 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | [#11] stanford-crypto-ccm Cycles:10 Average:126.90 Median:124.50 stddev:13.84 (11.1%) stddev-sans-first:14.67 04:18:29 INFO - PROCESS | 15390 | Values: 126.0 132.0 120.0 121.0 128.0 123.0 118.0 118.0 164.0 119.0 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.30 Median:158.50 stddev:2.41 (1.5%) stddev-sans-first:2.47 04:18:29 INFO - PROCESS | 15390 | Values: 160.0 157.0 158.0 163.0 160.0 157.0 159.0 158.0 154.0 157.0 04:18:29 INFO - PROCESS | 15390 | 04:18:29 INFO - PROCESS | 15390 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.70 Median:60.00 stddev:0.95 (1.6%) stddev-sans-first:1.00 04:18:29 INFO - PROCESS | 15390 | Values: 60.0 61.0 59.0 60.0 59.0 61.0 60.0 60.0 59.0 58.0 04:18:29 INFO - PROCESS | 15390 | -------- Summary: end -------- 04:18:29 INFO - PROCESS | 15390 | 04:18:30 INFO - TEST-INFO | 15390: exit 0 04:18:30 INFO - TEST-OK | kraken | took 59398ms 04:18:30 INFO - SUITE-END | took 1175s 04:18:30 INFO - Completed test suite (00:19:35) 04:18:30 INFO - javascript benchmark 04:18:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7964.894428502304, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3388.830558472076, 3408.795472271662, 3413.0784688134204, 3458.463938089597, 3478.008767501263, 5697.715114244287, 5786.10315401113, 5791.507721843648, 5794.362620111784, 5814.0, 5810.680412577307, 5865.29616446026, 5870.70646467677, 5880.117982302657, 5883.47048765611, 5330.28041214435, 5355.866103347428, 5363.758630861621, 5424.159255315432, 5435.83692489224, 5151.948480515184, 5181.455947125554, 5291.629585928987, 5431.158170483582, 5443.537299329542, 5363.892722145574, 5408.8377348679605, 5414.702191379472, 5417.26866872971, 5419.078756611366, 5393.406725260218, 5399.9730001349935, 5400.459954004587, 5404.756785944642, 5407.756650950717, 5157.329547158845, 5185.766640501187, 5245.029669511153, 5300.469953004707, 5398.217258497496, 5694.402087780806, 5746.310442746897, 5798.7100644968, 5806.796762113306, 5818.796342128047, 6287.2140982377205, 6298.3701629837105, 6322.336154703736, 6340.017297318923, 6340.968295158541, 6136.9079463808075, 6372.808815735535, 6397.872042559169, 6399.26408463028, 6403.615783053032, 5770.105633626795, 5796.478316951495, 5839.153322768175, 5839.211706419622, 5856.0630299151935, 6367.904481432783, 6383.074454204115, 6388.776392826228, 6413.807585772341, 6438.356164383618, 6172.413620706116, 6179.598326108879, 6181.783637572753, 6185.103160041769, 6186.907196392058, 6044.274687037584, 6085.056816193497, 6110.953680641517, 6167.506599472032, 6172.0, 6257.530685198628, 6294.8111556653375, 6304.65324407162, 6309.463695585927, 6314.905276420857, 6010.3689112643415, 6036.0644100164545, 6043.516518678495, 6123.83172914054, 6182.196314479089, 4247.660187185018, 4304.16068866568, 4306.397104405386, 4319.373690814814, 4329.545397733255, 3844.8462061517785, 3847.634474724897, 3896.922061558753, 3897.8440862365187, 3940.9014774630864, 5961.433663801932, 6000.729967151488, 6001.219841420675, 6025.3974602540275, 6025.487833534199, 6318.778842740482, 6322.304546499881, 6337.207849018872, 6348.238211414659, 6358.0, 5824.534037277008, 6010.068439391901, 6021.789237376671, 6027.095935609606, 6029.065494848305, 5207.29701490294, 5234.476552344811, 5257.842264732064, 5281.339832520935, 5285.101532739443, 6528.053432252392, 6559.573627714183, 6575.210974682973, 6575.638339891352, 6576.408123268929, 6263.154474146114, 6270.6551139685935, 6310.337414571404, 6327.082573026977, 6331.525135614939, 6324.494040476843, 6364.554481186273, 6427.775027874002, 6446.226452825504, 6581.07864898905, 6218.657973811484, 6222.844428889133, 6227.408396202353, 6232.626042437287, 6232.75068997235, 6334.714937827716, 6342.207224096988, 6343.714532846126, 6353.68231588428, 6355.30091689923, 5960.612560183574, 6011.849703757266, 6050.90923636137, 6168.198134242699, 6182.072689096672, 6039.033754599243, 6075.908861366995, 6134.693265336626, 6135.7238924249405, 6137.570370074052, 6111.522611241784, 6139.395453409942, 6146.508279337553, 6147.231596050494, 6164.3835616438, 5946.821595352147, 5969.820905372846, 6006.039033754579, 6016.819495415145, 6017.87964240704, 5881.852953676193, 5927.229460170151, 5940.495057919952, 5953.6427814329545, 5956.404359564009], "value": 5735.20279821128, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.6656670914863, 852.5481494807752, 869.6304070769924, 923.2936803345442, 940.3511577011866, 32177.67822321776, 32479.67520324796, 34728.65271347286, 34731.47902781454, 45483.0, 47040.0, 47286.763566182206, 47498.0, 47568.76215618926, 47600.0, 9242.537873106334, 9374.953125234364, 9377.67178148765, 9379.24966002718, 9379.624815007426, 7673.577953212571, 7835.764927052169, 9166.037566055567, 10804.459777011158, 10997.945010274938, 11131.109511239081, 11157.442127893613, 11157.832632510519, 11168.609098681547, 11171.0, 11097.1122310215, 11112.277701949428, 11133.832992505078, 11138.0, 11152.498137583747, 10775.245732798629, 10776.892231077667, 10800.405977671226, 10836.837447438294, 10847.0, 44509.332360014625, 44540.0, 44763.104737905385, 44833.32750008752, 44879.0, 4341.848035318749, 4374.934375984363, 4387.561243875619, 4507.954920450787, 4534.546545345472, 30805.691943080732, 30952.380952381052, 31121.533177002362, 31486.37027259442, 33085.6691433085, 4467.865964021052, 4493.977530112361, 4535.538632397257, 4542.751813533111, 4544.363789069533, 238183.0, 240612.0, 241043.0, 241675.0, 242074.0, 54598.18102728462, 54864.0, 55033.724831376385, 55041.72479137579, 55042.0, 57501.712491437276, 57515.42484575184, 57518.42481575131, 57532.0, 57661.135082973786, 16350.427735029454, 16690.082045487372, 16692.666146677, 16699.165041747863, 16704.0, 14598.34307456167, 14638.780418293734, 14642.414303427744, 14653.926730366493, 14654.85345146535, 11995.340256285988, 12237.75524489523, 13486.460541578226, 13564.593062208149, 13797.24115173655, 5292.259083728281, 5445.945540544623, 5447.428020057837, 5470.835874923759, 5506.284183056177, 1004.1264100232844, 1016.6289304403839, 1026.3929618768245, 1049.5014867937668, 1053.204830353094, 28270.0, 28742.425151496853, 28921.85539072291, 29089.41821163565, 29311.706882931325, 1104.4974536585835, 1111.366521082991, 1117.9105671546256, 1122.9887701122884, 1137.6643890052455, 14827.703445931236, 15233.3906643733, 15296.541103766906, 15490.612734681497, 15520.5343839685, 312998.0, 313450.0, 313581.0, 313801.0, 313927.0, 57606.71196644074, 58004.129938050966, 58113.70943145342, 58133.4186658128, 58266.7086664564, 1460.7370673278913, 1499.1754535005703, 1502.323954220627, 1527.7250094982787, 1539.4304107480305, 27779.30551736142, 28135.296617584725, 28312.0, 29064.504800392282, 29108.417831643674, 24263.636045459687, 24344.391390215387, 24401.38996525101, 24403.26790196297, 24415.38961525976, 328969.0, 330574.0, 331101.0, 331632.0, 331871.0, 25864.741352586232, 29701.5544766833, 30289.394212116076, 30760.0, 30823.53764693576, 33149.83425082859, 42129.78935105305, 43279.567204327555, 43579.34630980601, 43613.34579981239, 31966.0, 31976.840115799274, 32085.67914320827, 32122.678773212905, 32206.67793322037, 30442.83112541441, 33530.497042543895, 33692.66307336895, 33764.49353259654, 34294.31411371809, 36533.45199821952, 36791.81604091962, 36880.81559592185, 37088.44367334438, 37200.25599488049, 30365.392692145593, 30513.694863051973, 30534.541981870734, 30794.846025769726, 31462.842685786425, 24671.506569868143, 24707.505849883262, 25408.364790880376, 25529.23412297634, 25596.104136355138], "value": 18810.977702969092, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.4083752378195, 474.70093840880264, 484.2518309212269, 492.8940277840265, 495.52182144230824, 19534.70697939532, 20156.69764953525, 22148.0, 23185.536289274205, 23396.0, 22803.201887933927, 22860.54278914421, 23299.301020969397, 23478.765212347873, 23504.529909401845, 6592.901106483407, 6728.158980127484, 6782.1183246177925, 6815.931840681579, 6821.863562728742, 6712.328767123297, 6825.453963682893, 7268.6729097190755, 7540.886886696677, 7579.621018949058, 7485.887711684329, 7751.496152750082, 7755.650995705207, 7755.883661745078, 7764.805879852993, 7640.388768898475, 7842.098158711764, 7887.487313324616, 7896.368290536743, 7906.130325664172, 7372.520786148883, 7509.061367329084, 7551.471397002212, 7557.735479258255, 7559.508631938907, 25309.746902531107, 25887.870560647265, 26028.609570856454, 26448.735512645013, 27090.0, 3204.150900011499, 3302.4220761366905, 3513.789172649649, 3589.515415418937, 3676.5588129424373, 15980.28088735998, 16755.748663770053, 16984.15079246033, 17119.31522739089, 17183.31266749341, 2347.33101066197, 2406.061635961976, 2411.455914010718, 2468.4075821802817, 2487.9004839806566, 106731.0, 108975.45512272388, 109118.45440772825, 109698.9999999992, 112666.43666781696, 17803.73294400585, 18287.54281142982, 18424.723629145574, 18605.348812791486, 18691.345802896834, 31205.843970780003, 31239.219019524695, 31254.281014543965, 31275.84362078175, 31440.842795785873, 10348.5343159558, 10390.688279351632, 10393.58425662965, 10495.632652857266, 10535.578576856993, 8480.406371553932, 8581.013183483854, 8646.3947523674, 8799.824003519894, 8853.0, 9137.63449462014, 9436.292278079172, 9618.374805637752, 9708.174805141502, 9747.415155090717, 3698.8890333290046, 3833.5591406987996, 3848.461215429842, 3973.8013099344917, 3975.264576053439, 964.5852283518154, 966.2608104799833, 968.8352979993418, 969.6063776781266, 989.3569180032893, 21301.68047479258, 21627.891860540596, 21705.565888682453, 21719.0, 21729.130834766747, 977.5161295158941, 987.3138168972639, 993.9006099390003, 1013.5236438873718, 1020.4846552490944, 11225.719357015976, 11566.67199616275, 12077.214981026205, 12178.451969661386, 12183.0, 589795.0, 612786.0, 625843.0, 631567.0, 634576.0, 88355.55822220848, 96379.51810240904, 105455.0, 111255.0, 112264.0, 1286.6783304173957, 1291.3994992328612, 1314.3953781260536, 1318.7296604196117, 1354.8983826213073, 19535.316263930694, 19741.901290493457, 19912.402627921187, 19954.700679489528, 19993.700094498305, 42894.0, 54771.72614137063, 54858.0, 54875.45124548863, 55283.17075243794, 70564.0, 71339.28660713533, 71741.28258717555, 71947.64026179835, 72302.27697722956, 11917.702057448632, 12330.75338493243, 12470.68823279389, 12473.750524989631, 12510.87489125097, 13487.32563371808, 13697.0, 13952.511662091778, 14130.78803817964, 14207.360668769723, 11207.327560346404, 11257.0, 11260.774784504429, 11279.477270568368, 11529.135314851588, 10811.35131892089, 11007.559697612327, 11193.832092518456, 11243.381614011309, 11729.296242225493, 11891.583794567148, 11922.165448418875, 12090.45592948337, 12101.394930253628, 12177.512899483922, 12451.128421010442, 12631.684207894876, 12634.557790477289, 12777.552785652457, 12956.67608309762, 11242.381669008282, 11797.056235501312, 11839.76320473603, 12110.333931633844, 12170.939145304217], "value": 11658.273599255386, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1235.3761350517987, 1564.6635973265752, 1573.9291731872065, 1588.8808339374543, 1596.0902285697152, 9702.4178549287, 11023.283486573368, 11379.772404551904, 11385.829212561797, 11390.715232119192, 11465.484053217604, 11573.36346500942, 11578.53685852565, 11588.130890183249, 11606.245594036382, 1964.489232799468, 2023.0188358646044, 2467.2721547143547, 2483.5032993401346, 2521.231024537515, 2552.5150221457957, 2572.1383336582267, 2580.187241019082, 2580.187241019082, 2581.6772903387077, 2464.162184857148, 2501.487195124995, 2518.697756269241, 2522.155078048856, 2524.4572416930355, 2346.577616029114, 2394.317619478449, 2402.0, 2402.6636270922086, 2403.158894386968, 2365.31405892291, 2387.4150833046015, 2389.1518510928718, 2393.293978276395, 2394.341556072077, 11518.596849110241, 11749.0, 11809.468573914195, 11811.114166437552, 11827.822582661267, 5917.319508256563, 6035.577509574332, 6057.939420605781, 6062.63624182546, 6077.817665470043, 3362.923864363405, 3418.2141554633145, 3472.7742696724886, 3473.4095203815414, 3560.6439356064448, 1308.725167714781, 1325.6023193042145, 1332.4403750424844, 1364.8225730654951, 1365.208179256029, 16885.66228675432, 17772.644547109114, 17859.0, 17879.642407151914, 17886.910565447088, 4768.117898188777, 4820.373351464287, 4896.36347274852, 4939.555440010345, 4947.0, 13398.531051413154, 13515.0, 13532.661683457993, 13534.187948723109, 13549.729005419837, 6076.817695469143, 6076.966915624354, 6077.513798896078, 6080.361562036011, 6088.817335479943, 4521.434820647419, 4635.860924172281, 5493.258410114583, 5598.776048958078, 5608.607397482218, 4068.069974301299, 4092.7339722917914, 4105.158442519277, 4147.564505726855, 4148.357004664282, 1078.8435676826816, 1081.291753901196, 1087.5160553553592, 1095.479647167605, 1098.758273179899, 1056.4295280548572, 1064.744461329291, 1067.3969207397795, 1077.7036315013434, 1079.7894410589859, 7153.0, 7220.711171553184, 7226.458015648742, 7307.817304567428, 7357.5217610855125, 1083.588236470152, 1130.112861403794, 1137.1016896651718, 1144.0, 1150.7698460307972, 4769.880752981134, 4785.9760701196965, 4835.0, 4945.406551213805, 4947.381577302837, 14160.36278367476, 14711.92644036773, 14715.264236788118, 14768.040077394935, 14776.556703298918, 14387.92806035963, 14416.351264193136, 14429.278536073154, 14435.278236088154, 14540.345684444226, 10531.15750739939, 10963.725906852233, 11004.724881877857, 11029.724256893642, 11060.668179954615, 7286.817829554304, 7336.559806411632, 7414.777556673308, 7429.851402971803, 7614.124375696644, 13247.271400073088, 13261.933690331487, 13264.53574124901, 13512.189268643913, 13532.59402217935, 13480.123791953492, 13505.257210853497, 13506.39221235027, 13517.188968661518, 13551.796723048965, 8075.475094118863, 9003.54982250898, 9425.151736343762, 9605.615775368908, 9950.402975821475, 11115.0, 11250.21248512629, 11363.602273920373, 11391.886081139413, 11488.540458381905, 9401.623935042795, 9428.95285523568, 9435.528223588655, 9452.338336316392, 9453.101955314234, 8845.336599754915, 8983.101689830964, 9013.414128081653, 9245.0, 9381.484018378782, 8416.747497575083, 8472.364572656952, 8624.310055195443, 8632.136786321318, 8641.95679021626, 6447.484201263794, 6556.475481961527, 6558.114654521579, 6596.406323430914, 6613.2394774601535, 6568.540202185801, 6666.2333831609985, 6703.4972377070935, 6710.899336510054, 6831.282715315019], "value": 5475.897457420002, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.4358587699547, 446.2413896376161, 446.3527884567378, 446.7408902836355, 449.346201277142, 5150.871228219296, 5187.325647665808, 5223.399309079457, 5237.528622423981, 5238.202303330084, 4942.961977984619, 5183.652250414891, 5191.247269145972, 5193.896122077565, 5210.5310522053005, 4337.219300525904, 4386.539413361583, 4386.859416551703, 4405.625521830648, 4405.8237670493145, 4352.759463552888, 4392.430428199507, 4414.29371300592, 4420.160169567772, 4420.491643461011, 4381.255186618282, 4464.325877795826, 4487.618552423048, 4503.527129651388, 4516.570925762047, 4325.745533795196, 4438.689291749579, 4440.178566965122, 4445.955200527874, 4463.575960283768, 4158.604932531435, 4190.8533201337805, 4210.494740631113, 4211.642010429086, 4229.513605935295, 5102.846914592531, 5111.182210846284, 5124.436312005676, 5134.5378915897745, 5172.715500647463, 4886.389201349832, 5080.187170052774, 5122.180451127839, 5139.640225184238, 5166.243477217727, 5466.808661696861, 5471.510462739484, 5511.0, 5514.7187423483365, 5518.310211223597, 4593.333966574828, 4610.0, 4627.722336659777, 4628.352030715702, 4649.790759415834, 5398.2982212312145, 5436.21174929637, 5532.937362313478, 5562.802692936268, 5636.436356364372, 4656.534346565384, 4676.532346765296, 4679.508651591602, 4680.368150299666, 4690.976545117321, 4715.3869996900185, 4771.71369717818, 4786.611882554021, 4804.279358096243, 4813.3983252093485, 4872.903596690716, 5021.84934451967, 5029.5724863386295, 5077.822276220315, 5132.178851383761, 5382.730863456812, 5436.266104075979, 5451.809186678447, 5460.699661518575, 5466.9179962300595, 1631.7226071567625, 1634.3625985865522, 1646.1851383565213, 1651.1991684033235, 1661.310556119197, 1267.6957530192687, 1281.2376635901624, 1288.0854593238905, 1297.94866158412, 1312.5471712259255, 5247.2653828464045, 5270.683758974397, 5345.65253258537, 5449.621234834349, 5477.794885125266, 5666.575006874501, 5691.373948865621, 5705.771769129188, 5725.971370143123, 5734.541236701054, 5224.817131400383, 5323.866903327448, 5332.3867755207875, 5356.651817631841, 5401.189821526802, 2926.326944802707, 2961.9485082795727, 2966.24360788002, 2966.2732630505666, 2967.4865818432754, 5689.459501347365, 5743.224664670216, 5842.0, 5860.970695146497, 5886.646801191942, 5594.636348637326, 5689.089745640677, 5690.829275121753, 5707.942920570741, 5751.971240143773, 5766.740496677576, 5858.50202732764, 5858.560607954335, 5883.823485295448, 5917.733701983335, 5296.258523806747, 5516.365617953987, 5590.608657393944, 5594.328680558359, 5624.353199382123, 5331.866703332293, 5553.805616803393, 5573.972130139324, 5580.190872323572, 5602.683369408267, 5413.350397952271, 5666.13860000697, 5669.659820410789, 5708.885822283614, 5721.025066965071, 4585.633149347977, 4652.767361631973, 4679.181143299841, 4680.789364478538, 4699.6371052394425, 5527.50252477279, 5606.803761868315, 5630.943690563206, 5651.378348381592, 5668.291463567054, 5461.0, 5462.590305727007, 5471.343438787371, 5478.822053258568, 5521.668699878021, 5473.753681084281, 5476.835694929158, 5487.012337779079, 5549.278593782782, 5568.387477377404, 5349.732513374393, 5434.972825135849, 5449.7002664852225, 5493.285872836505, 5507.642003269774, 5371.462853714597, 5379.811706590251, 5437.308532276548, 5451.945480545144, 5462.508374246337, 5240.973795131, 5310.203469479455, 5390.568754499709, 5398.784048638167, 5404.810831621032], "value": 4425.496682596458, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.7009476588427, 863.4344504349654, 927.9118483744043, 948.8861336639604, 962.9518524073796, 17333.65332693347, 19339.129739161755, 19513.902430487862, 19553.60892782147, 19644.70532942007, 17985.820141798577, 18199.81800181998, 18580.814191858077, 18699.53251168718, 18734.625307493876, 907.3829795738895, 933.3093510801991, 935.6163972771166, 936.204226407555, 938.9671361502349, 904.669795524632, 915.3180880244228, 915.4187091197072, 918.7703074231442, 935.5135329628589, 966.1015255812092, 967.632299726103, 969.5491596407669, 973.2019743810079, 974.1232890398627, 952.6713283917038, 956.5504213019905, 958.8465845464692, 964.4599024546277, 964.9131578157965, 712.401582670554, 712.4122598855913, 714.0110946339357, 715.3526058916689, 715.3811952660963, 18664.44006679802, 18710.71933920992, 18927.810721892743, 19892.0, 20195.697064544045, 16560.42038528658, 16757.99452032882, 16826.32694692211, 16829.242684079123, 17018.82981170185, 13825.239611821346, 13876.791848122286, 14176.716465670628, 14571.854281457156, 14952.850471495254, 4166.729162604421, 4191.685623578213, 4202.873913782591, 4224.260754367973, 4370.825166993348, 93464.53267733686, 94576.52711736466, 94806.0, 97339.99999999929, 99380.0, 13217.14088584239, 13277.867221327857, 13281.734365312639, 13294.135881167693, 13354.599362019155, 25083.498330033297, 25136.12023579166, 25316.493670126496, 25319.62020569693, 25633.74366256351, 9068.909310906938, 9175.403598766059, 9255.0, 9319.953400232955, 9341.486218257925, 5157.742112894341, 5220.686758794484, 5246.580273578104, 5260.395054568774, 5341.198820177005, 3777.660010599059, 3829.1384438501113, 3897.7661340319105, 3901.141748815256, 3921.156951255494, 653.9313372095957, 658.5225711359323, 665.7403612591094, 670.4435318685479, 672.1363048482676, 657.980260592183, 672.1396612336217, 692.3837784371913, 693.4833549006023, 696.1472196559275, 11403.600873969372, 11817.207249881476, 12555.0, 12592.118551701476, 12996.675083122998, 847.4449235750593, 855.5593869157389, 859.3769517100052, 886.8625363068735, 893.7050773244814, 5615.971920140373, 5617.9438205618235, 6014.699265036731, 6259.499240060784, 6295.5907865988565, 81960.18039819645, 82499.0, 83506.58246708849, 84481.57759211164, 85703.0, 73402.63298683579, 74438.0, 74838.25161748422, 76067.61966190244, 76883.0, 68047.65976170187, 68524.0, 68534.62930741358, 68768.6561567199, 70021.0, 12040.939795300967, 12594.622161335175, 12666.60703104052, 12820.679483013, 12943.805842912552, 44807.0, 45062.32406513971, 46284.7685761569, 46288.30567541557, 47140.0, 54325.185122223986, 55036.44963550313, 55158.44841551533, 55613.443865560825, 56674.7166264166, 14630.414783408545, 14947.925260373628, 15060.0, 15064.548063558112, 15228.923855380652, 17224.827751722823, 17388.913055435147, 17474.650506990045, 17689.0, 17715.29138834432, 16440.917795410947, 16613.169341532634, 16625.0, 16914.238859251116, 17679.558011049827, 15577.532674019798, 15638.765418518504, 17001.0, 17004.914975425043, 17014.48956531306, 15569.455069072528, 16228.67542649164, 16971.406000789913, 16977.49067527976, 16991.7451238234, 11547.942260288644, 11974.341411222467, 12180.0, 12425.75148497007, 12461.252324860538, 13340.866591333963, 13462.730745385234, 13544.390502427217, 13559.186448813103, 13663.043586948817], "value": 8375.875863314779, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1448.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 112.0, 113.0, 113.0, 113.0, 112.0, 113.0, 112.0, 113.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 115.0, 115.0, 155.0, 117.0, 121.0, 120.0, 113.0, 134.0, 115.0], "value": 124.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 160.0, 160.0, 162.0, 162.0, 163.0, 161.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 108.0, 77.0, 76.0, 77.0, 75.0, 93.0, 75.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 99.0, 83.0, 82.0, 82.0, 98.0, 82.0, 83.0, 104.0], "value": 87.7, "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, 113.0, 112.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 93.0], "value": 93.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 55.0, 60.0, 56.0, 55.0, 54.0, 54.0, 54.0, 55.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 55.0, 54.0, 54.0, 54.0, 53.0, 54.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 79.0, 75.0, 74.0, 78.0, 78.0, 78.0, 74.0, 75.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 132.0, 120.0, 121.0, 128.0, 123.0, 118.0, 118.0, 164.0, 119.0], "value": 126.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 157.0, 158.0, 163.0, 160.0, 157.0, 159.0, 158.0, 154.0, 157.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 59.0, 60.0, 59.0, 61.0, 60.0, 60.0, 59.0, 58.0], "value": 59.7, "unit": "score"}]}]} 04:18:30 INFO - Return code: 0 04:18:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:18:30 INFO - # TBPL SUCCESS # 04:18:30 INFO - Running post-action listener: _resource_record_post_action 04:18:30 INFO - [mozharness: 2017-05-22 11:18:30.494778Z] Finished run-tests step (success) 04:18:30 INFO - Running post-run listener: _resource_record_post_run 04:18:30 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 322744320; Read time: 0; Write time: 174736 04:18:30 INFO - TinderboxPrint: CPU usage
13.1% 04:18:30 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 04:18:30 INFO - TinderboxPrint: I/O write bytes / time
322,744,320 / 174,736 04:18:30 INFO - TinderboxPrint: CPU idle
8,253.8 (86.9%) 04:18:30 INFO - TinderboxPrint: CPU user
1,200.7 (12.6%) 04:18:30 INFO - TinderboxPrint: Swap in / out
0 / 0 04:18:30 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 5906432; Read time: 0; Write time: 336 04:18:30 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 307056640; Read time: 0; Write time: 173508 04:18:31 INFO - Running post-run listener: _upload_blobber_files 04:18:31 INFO - Blob upload gear active. 04:18:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:18:31 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 04:18:31 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'] 04:18:31 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 04:18:31 INFO - (blobuploader) - INFO - Open directory for files ... 04:18:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:18:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:18:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:32 INFO - (blobuploader) - INFO - Done attempting. 04:18:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:18:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:18:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:38 INFO - (blobuploader) - INFO - Done attempting. 04:18:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:18:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:18:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:39 INFO - (blobuploader) - INFO - Done attempting. 04:18:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:18:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:18:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:41 INFO - (blobuploader) - INFO - Done attempting. 04:18:41 INFO - (blobuploader) - INFO - Iteration through files over. 04:18:41 INFO - Return code: 0 04:18:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:18:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:18:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1d20ce501da2193e6f2e7b495282bb4fc56139b0f85edf4837f80a2dab997e2cc096e89b2f7a32ee436588fd9abdd19aee504a2fd20a34591210201457f4061a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a7e12b6542537b5677e60d724697067446a481ece0807fb0582ec384ab3ced53fbfdcb06786642898bf90b506ec710091a68a05c5c48cacfed11ad0adef7187e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/37f58112540ab19648a7bbceec9bcde7221bd8cfa2ca90ff52f999b8023d0f8cdb4853f040b059364b58a43ac4090bc64b70fc0fc5f3b38ca6eb21a1c110952a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1c9b6980ee54b348335c24f0da9d7df7b178ac95343f13d792bff0cb8e7ebfb8d56118103c2ccd8255dffab496ef85f21bf5598e90bad851ca78f52ab7353cd7"} 04:18:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:18:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:18:41 INFO - Contents: 04:18:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1d20ce501da2193e6f2e7b495282bb4fc56139b0f85edf4837f80a2dab997e2cc096e89b2f7a32ee436588fd9abdd19aee504a2fd20a34591210201457f4061a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a7e12b6542537b5677e60d724697067446a481ece0807fb0582ec384ab3ced53fbfdcb06786642898bf90b506ec710091a68a05c5c48cacfed11ad0adef7187e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/37f58112540ab19648a7bbceec9bcde7221bd8cfa2ca90ff52f999b8023d0f8cdb4853f040b059364b58a43ac4090bc64b70fc0fc5f3b38ca6eb21a1c110952a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1c9b6980ee54b348335c24f0da9d7df7b178ac95343f13d792bff0cb8e7ebfb8d56118103c2ccd8255dffab496ef85f21bf5598e90bad851ca78f52ab7353cd7"} 04:18:41 INFO - Running post-run listener: copy_logs_to_upload_dir 04:18:41 INFO - Copying logs to upload dir... 04:18:41 INFO - mkdir: /builds/slave/test/build/upload/logs 04:18:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.071710 ========= master_lag: 0.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-05-22 04:18:40.064394) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 04:18:40.072182) ========= 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=923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298 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/1d20ce501da2193e6f2e7b495282bb4fc56139b0f85edf4837f80a2dab997e2cc096e89b2f7a32ee436588fd9abdd19aee504a2fd20a34591210201457f4061a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a7e12b6542537b5677e60d724697067446a481ece0807fb0582ec384ab3ced53fbfdcb06786642898bf90b506ec710091a68a05c5c48cacfed11ad0adef7187e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/37f58112540ab19648a7bbceec9bcde7221bd8cfa2ca90ff52f999b8023d0f8cdb4853f040b059364b58a43ac4090bc64b70fc0fc5f3b38ca6eb21a1c110952a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1c9b6980ee54b348335c24f0da9d7df7b178ac95343f13d792bff0cb8e7ebfb8d56118103c2ccd8255dffab496ef85f21bf5598e90bad851ca78f52ab7353cd7"} build_url:https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011390 build_url: 'https://queue.taskcluster.net/v1/task/NP-E5b0eQ0uER2rjup30xQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1d20ce501da2193e6f2e7b495282bb4fc56139b0f85edf4837f80a2dab997e2cc096e89b2f7a32ee436588fd9abdd19aee504a2fd20a34591210201457f4061a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a7e12b6542537b5677e60d724697067446a481ece0807fb0582ec384ab3ced53fbfdcb06786642898bf90b506ec710091a68a05c5c48cacfed11ad0adef7187e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/37f58112540ab19648a7bbceec9bcde7221bd8cfa2ca90ff52f999b8023d0f8cdb4853f040b059364b58a43ac4090bc64b70fc0fc5f3b38ca6eb21a1c110952a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/1c9b6980ee54b348335c24f0da9d7df7b178ac95343f13d792bff0cb8e7ebfb8d56118103c2ccd8255dffab496ef85f21bf5598e90bad851ca78f52ab7353cd7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 04:18:40.103434) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 04:18:40.103790) ========= 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=923d835db2ba3686ebe126b700000192-1495441719.461796-2128384298 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004537 ========= master_lag: 0.22 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 04:18:40.323481) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 04:18:40.327848) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 04:18:40.328162) ========= ========= Total master_lag: 0.65 =========