builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-018 starttime: 1494536124.56 results: success (0) revision: 8b7e2a303954352189cf0e9772f35aed06953fa5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 13:55:24.556470) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 13:55:24.557016) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 13:55:24.557342) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 13:55:24.572365) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 13:55:24.572731) ========= 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=62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-11 13:55:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 219M=0s 2017-05-11 13:55:24 (219 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.376695 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 13:55:24.965031) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 13:55:24.965657) ========= 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=62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020925 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 13:55:25.015868) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 13:55:25.016228) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 8b7e2a303954352189cf0e9772f35aed06953fa5 --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 8b7e2a303954352189cf0e9772f35aed06953fa5 --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=62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-11 13:55:25,088 truncating revision to first 12 chars 2017-05-11 13:55:25,088 Setting DEBUG logging. 2017-05-11 13:55:25,088 attempt 1/10 2017-05-11 13:55:25,088 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/8b7e2a303954?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 13:55:25,679 unpacking tar archive at: mozilla-beta-8b7e2a303954/testing/mozharness/ program finished with exit code 0 elapsedTime=0.923792 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 13:55:25.955558) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 13:55:25.956138) ========= script_repo_revision: 8b7e2a303954352189cf0e9772f35aed06953fa5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 13:55:25.956825) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 13:55:25.957325) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 13:55:25.969922) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-05-11 13:55:25.970553) ========= /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=62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:55:26 INFO - MultiFileLogger online at 20170511 13:55:26 in /builds/slave/test 13:55:26 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 13:55:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:55:26 INFO - {'append_to_log': False, 13:55:26 INFO - 'base_work_dir': '/builds/slave/test', 13:55:26 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 13:55:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:55:26 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 13:55:26 INFO - 'buildbot_json_path': 'buildprops.json', 13:55:26 INFO - 'config_files': ('talos/linux_config.py',), 13:55:26 INFO - 'default_actions': ('clobber', 13:55:26 INFO - 'read-buildbot-config', 13:55:26 INFO - 'download-and-extract', 13:55:26 INFO - 'populate-webroot', 13:55:26 INFO - 'create-virtualenv', 13:55:26 INFO - 'install', 13:55:26 INFO - 'run-tests'), 13:55:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:55:26 INFO - 'download_minidump_stackwalk': True, 13:55:26 INFO - 'download_symbols': 'ondemand', 13:55:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:55:26 INFO - 'tooltool.py': '/tools/tooltool.py', 13:55:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:55:26 INFO - '/tools/misc-python/virtualenv.py')}, 13:55:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:55:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:55:26 INFO - 'gecko_profile': False, 13:55:26 INFO - 'gecko_profile_interval': 0, 13:55:26 INFO - 'installer_path': 'installer.exe', 13:55:26 INFO - 'log_level': 'info', 13:55:26 INFO - 'log_name': 'talos', 13:55:26 INFO - 'log_to_console': True, 13:55:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:55:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:55:26 INFO - 'opt_config_files': (), 13:55:26 INFO - 'pip_index': False, 13:55:26 INFO - 'suite': 'dromaeojs', 13:55:26 INFO - 'system_bits': '32', 13:55:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:55:26 INFO - 'title': 'talos-linux64-ix-018', 13:55:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:55:26 INFO - 'use_talos_json': True, 13:55:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:55:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:55:26 INFO - 'work_dir': 'build'} 13:55:26 INFO - [mozharness: 2017-05-11 20:55:26.138144Z] Running clobber step. 13:55:26 INFO - Running pre-action listener: _resource_record_pre_action 13:55:26 INFO - Running main action method: clobber 13:55:26 INFO - rmtree: /builds/slave/test/build 13:55:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:55:27 INFO - Running post-action listener: _resource_record_post_action 13:55:27 INFO - [mozharness: 2017-05-11 20:55:27.183150Z] Finished clobber step (success) 13:55:27 INFO - [mozharness: 2017-05-11 20:55:27.183250Z] Running read-buildbot-config step. 13:55:27 INFO - Running pre-action listener: _resource_record_pre_action 13:55:27 INFO - Running main action method: read_buildbot_config 13:55:27 INFO - Using buildbot properties: 13:55:27 INFO - { 13:55:27 INFO - "project": "", 13:55:27 INFO - "product": "firefox", 13:55:27 INFO - "who": "ryanvm@gmail.com", 13:55:27 INFO - "installer_path": "public/build/target.tar.bz2", 13:55:27 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 13:55:27 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 13:55:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 13:55:27 INFO - "stage_platform": "linux64", 13:55:27 INFO - "basedir": "/builds/slave/test", 13:55:27 INFO - "buildnumber": 43, 13:55:27 INFO - "platform": "ubuntu64_hw", 13:55:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 13:55:27 INFO - "slavebuilddir": "test", 13:55:27 INFO - "taskId": "QShij7yZSNirQ-wJvk7f1g", 13:55:27 INFO - "branch": "mozilla-beta", 13:55:27 INFO - "script_repo_revision": "production", 13:55:27 INFO - "revision": "8b7e2a303954352189cf0e9772f35aed06953fa5", 13:55:27 INFO - "slavename": "talos-linux64-ix-018", 13:55:27 INFO - "repo_path": "releases/mozilla-beta" 13:55:27 INFO - } 13:55:27 INFO - Finding installer, test and symbols from parent task. 13:55:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QShij7yZSNirQ-wJvk7f1g'}, attempt #1 13:55:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QShij7yZSNirQ-wJvk7f1g'}, attempt #1 13:55:28 INFO - Task dependencies: N6Lx78ZrTpyMHP89jrXK4w 13:55:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w'}, attempt #1 13:55:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.tar.bz2 13:55:28 INFO - Set test_url: https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/test_packages.json 13:55:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.crashreporter-symbols.zip 13:55:28 INFO - Running post-action listener: _resource_record_post_action 13:55:28 INFO - [mozharness: 2017-05-11 20:55:28.531236Z] Finished read-buildbot-config step (success) 13:55:28 INFO - [mozharness: 2017-05-11 20:55:28.531454Z] Running download-and-extract step. 13:55:28 INFO - Running pre-action listener: _resource_record_pre_action 13:55:28 INFO - Running main action method: download_and_extract 13:55:28 INFO - mkdir: /builds/slave/test/build/tests 13:55:28 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')]} 13:55:28 INFO - trying https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.test_packages.json 13:55:28 INFO - Downloading https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 13:55:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 13:55:29 INFO - Downloaded 986 bytes. 13:55:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 13:55:29 INFO - Using the following test package requirements: 13:55:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 13:55:29 INFO - u'common': [u'target.common.tests.zip'], 13:55:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 13:55:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 13:55:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 13:55:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 13:55:29 INFO - u'mozbase': [u'target.common.tests.zip'], 13:55:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 13:55:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 13:55:29 INFO - u'web-platform': [u'target.common.tests.zip', 13:55:29 INFO - u'target.web-platform.tests.zip'], 13:55:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 13:55:29 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 13:55:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.common.tests.zip 13:55:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:55:29 INFO - Fetch https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.common.tests.zip into memory 13:55:30 INFO - Expected file size: 59542867 13:55:30 INFO - Obtained file size: 59542867 13:55:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 13:55:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.common.tests.zip 13:55:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:55:39 INFO - Fetch https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.common.tests.zip into memory 13:55:41 INFO - Expected file size: 59542867 13:55:41 INFO - Obtained file size: 59542867 13:55:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.talos.tests.zip 13:55:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 13:55:51 INFO - Fetch https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.talos.tests.zip into memory 13:55:52 INFO - Expected file size: 14404449 13:55:52 INFO - Obtained file size: 14404449 13:55:52 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')]} 13:55:52 INFO - trying https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.tar.bz2 13:55:52 INFO - Downloading https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 13:55:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 13:55:53 INFO - Downloaded 59728853 bytes. 13:55:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.tar.bz2 13:55:53 INFO - mkdir: /builds/slave/test/properties 13:55:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:55:53 INFO - Writing to file /builds/slave/test/properties/build_url 13:55:53 INFO - Contents: 13:55:53 INFO - build_url:https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.tar.bz2 13:55:53 INFO - Running post-action listener: _resource_record_post_action 13:55:53 INFO - Running post-action listener: set_extra_try_arguments 13:55:53 INFO - [mozharness: 2017-05-11 20:55:53.667322Z] Finished download-and-extract step (success) 13:55:53 INFO - [mozharness: 2017-05-11 20:55:53.667419Z] Running populate-webroot step. 13:55:53 INFO - Running pre-action listener: _resource_record_pre_action 13:55:53 INFO - Running main action method: populate_webroot 13:55:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:55:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:55:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:55:53 INFO - '--tppagecycles', 13:55:53 INFO - '7'], 13:55:53 INFO - 'tests': ['tsvgm']}}, 13:55:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:55:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:55:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:55:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:55:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:55:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:55:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:55:53 INFO - 'talos_options': ['--disable-e10s'], 13:55:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:55:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:55:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:55:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:55:53 INFO - 'talos_options': ['--disable-e10s'], 13:55:53 INFO - 'tests': ['damp', 'tps']}, 13:55:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:55:53 INFO - 'tests': ['damp', 'tps']}, 13:55:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:55:53 INFO - 'tests': ['dromaeo_dom']}, 13:55:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:55:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:55:53 INFO - 'tests': ['basic_compositor_video']}, 13:55:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:55:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:55:53 INFO - 'tests': ['a11yr', 13:55:53 INFO - 'ts_paint', 13:55:53 INFO - 'tpaint', 13:55:53 INFO - 'sessionrestore', 13:55:53 INFO - 'sessionrestore_no_auto_restore', 13:55:53 INFO - 'tabpaint']}, 13:55:53 INFO - 'other-e10s': {'tests': ['a11yr', 13:55:53 INFO - 'ts_paint', 13:55:53 INFO - 'tpaint', 13:55:53 INFO - 'sessionrestore', 13:55:53 INFO - 'sessionrestore_no_auto_restore', 13:55:53 INFO - 'tabpaint']}, 13:55:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:55:53 INFO - 'ts_paint', 13:55:53 INFO - 'tpaint', 13:55:53 INFO - 'sessionrestore', 13:55:53 INFO - 'sessionrestore_no_auto_restore', 13:55:53 INFO - 'tabpaint']}, 13:55:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:55:53 INFO - 'ts_paint', 13:55:53 INFO - 'tpaint', 13:55:53 INFO - 'sessionrestore', 13:55:53 INFO - 'sessionrestore_no_auto_restore', 13:55:53 INFO - 'tabpaint']}, 13:55:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:55:53 INFO - 'tests': ['a11yr', 13:55:53 INFO - 'ts_paint', 13:55:53 INFO - 'tpaint', 13:55:53 INFO - 'sessionrestore', 13:55:53 INFO - 'sessionrestore_no_auto_restore', 13:55:53 INFO - 'tabpaint']}, 13:55:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:55:53 INFO - 'tests': ['a11yr', 13:55:53 INFO - 'ts_paint', 13:55:53 INFO - 'tpaint', 13:55:53 INFO - 'sessionrestore', 13:55:53 INFO - 'sessionrestore_no_auto_restore', 13:55:53 INFO - 'tabpaint']}, 13:55:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:55:53 INFO - 'tests': ['tsvgx', 13:55:53 INFO - 'tsvgr_opacity', 13:55:53 INFO - 'tart', 13:55:53 INFO - 'tscrollx', 13:55:53 INFO - 'cart', 13:55:53 INFO - 'tsvg_static']}, 13:55:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:55:53 INFO - 'tsvgr_opacity', 13:55:53 INFO - 'tart', 13:55:53 INFO - 'tscrollx', 13:55:53 INFO - 'cart', 13:55:53 INFO - 'tsvg_static']}, 13:55:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:55:53 INFO - 'talos_options': ['--disable-e10s'], 13:55:53 INFO - 'tests': ['tp5o']}, 13:55:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:55:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:55:53 INFO - 'talos_options': ['--disable-e10s', 13:55:53 INFO - '--xperf_path', 13:55:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:55:53 INFO - 'tests': ['tp5n']}, 13:55:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:55:53 INFO - 'talos_options': ['--xperf_path', 13:55:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:55:53 INFO - 'tests': ['tp5n']}}, 13:55:53 INFO - 'talos.zip': {'path': '', 13:55:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:55:53 INFO - Running post-action listener: _resource_record_post_action 13:55:53 INFO - [mozharness: 2017-05-11 20:55:53.680560Z] Finished populate-webroot step (success) 13:55:53 INFO - [mozharness: 2017-05-11 20:55:53.680650Z] Running create-virtualenv step. 13:55:53 INFO - Running pre-action listener: _resource_record_pre_action 13:55:53 INFO - Running main action method: create_virtualenv 13:55:53 INFO - Creating virtualenv /builds/slave/test/build/venv 13:55:53 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 13:55:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:55:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:55:53 INFO - Using real prefix '/usr' 13:55:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:55:54 INFO - Installing distribute.............................................................................................................................................................................................done. 13:55:56 INFO - Installing pip.................done. 13:55:56 INFO - Return code: 0 13:55:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:55:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:55:56 INFO - Reading from file tmpfile_stdout 13:55:56 INFO - Output received: 13:55:56 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) 13:55:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:55:56 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')]} 13:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:56 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')]} 13:55:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:56 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 0x270d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ab310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8400>, '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 0x29a93b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a9840>, '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': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', '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 13:55:56 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 13:55:56 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 13:55:56 INFO - Using env: {'DISPLAY': ':0', 13:55:56 INFO - 'HOME': '/home/cltbld', 13:55:56 INFO - 'LANG': 'en_US.UTF-8', 13:55:56 INFO - 'LANGUAGE': 'en_US:en', 13:55:56 INFO - 'LOGNAME': 'cltbld', 13:55:56 INFO - 'MAIL': '/var/mail/cltbld', 13:55:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:56 INFO - 'MOZ_NO_REMOTE': '1', 13:55:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:55:56 INFO - 'NO_EM_RESTART': '1', 13:55:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:55:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:56 INFO - 'PWD': '/builds/slave/test', 13:55:56 INFO - 'SHELL': '/bin/bash', 13:55:56 INFO - 'SHLVL': '1', 13:55:56 INFO - 'TERM': 'linux', 13:55:56 INFO - 'TMOUT': '86400', 13:55:56 INFO - 'USER': 'cltbld', 13:55:56 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', 13:55:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:56 INFO - '_': '/tools/buildbot/bin/python'} 13:55:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:56 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 13:55:56 INFO - Cleaning up... 13:55:56 INFO - Return code: 0 13:55:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:55:56 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')]} 13:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:56 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')]} 13:55:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:56 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 0x270d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ab310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8400>, '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 0x29a93b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a9840>, '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': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', '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 13:55:56 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 13:55:56 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 13:55:56 INFO - Using env: {'DISPLAY': ':0', 13:55:56 INFO - 'HOME': '/home/cltbld', 13:55:56 INFO - 'LANG': 'en_US.UTF-8', 13:55:56 INFO - 'LANGUAGE': 'en_US:en', 13:55:56 INFO - 'LOGNAME': 'cltbld', 13:55:56 INFO - 'MAIL': '/var/mail/cltbld', 13:55:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:56 INFO - 'MOZ_NO_REMOTE': '1', 13:55:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:55:56 INFO - 'NO_EM_RESTART': '1', 13:55:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:55:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:56 INFO - 'PWD': '/builds/slave/test', 13:55:56 INFO - 'SHELL': '/bin/bash', 13:55:56 INFO - 'SHLVL': '1', 13:55:56 INFO - 'TERM': 'linux', 13:55:56 INFO - 'TMOUT': '86400', 13:55:56 INFO - 'USER': 'cltbld', 13:55:56 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', 13:55:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:56 INFO - '_': '/tools/buildbot/bin/python'} 13:55:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:56 INFO - Downloading/unpacking psutil>=3.1.1 13:55:56 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 13:55:56 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 13:55:56 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 13:55:56 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 13:55:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:55:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:55:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:55:58 INFO - Installing collected packages: psutil 13:55:58 INFO - Running setup.py install for psutil 13:55:58 INFO - building 'psutil._psutil_linux' extension 13:55:58 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 13:55:58 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 13:55:58 INFO - building 'psutil._psutil_posix' extension 13:55:58 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 13:55:58 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 13:55:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:55:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:55:58 INFO - Successfully installed psutil 13:55:58 INFO - Cleaning up... 13:55:58 INFO - Return code: 0 13:55:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:55:58 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')]} 13:55:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:58 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')]} 13:55:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:58 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 0x270d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ab310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8400>, '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 0x29a93b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a9840>, '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': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', '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 13:55:58 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 13:55:58 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 13:55:58 INFO - Using env: {'DISPLAY': ':0', 13:55:58 INFO - 'HOME': '/home/cltbld', 13:55:58 INFO - 'LANG': 'en_US.UTF-8', 13:55:58 INFO - 'LANGUAGE': 'en_US:en', 13:55:58 INFO - 'LOGNAME': 'cltbld', 13:55:58 INFO - 'MAIL': '/var/mail/cltbld', 13:55:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:58 INFO - 'MOZ_NO_REMOTE': '1', 13:55:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:55:58 INFO - 'NO_EM_RESTART': '1', 13:55:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:55:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:58 INFO - 'PWD': '/builds/slave/test', 13:55:58 INFO - 'SHELL': '/bin/bash', 13:55:58 INFO - 'SHLVL': '1', 13:55:58 INFO - 'TERM': 'linux', 13:55:58 INFO - 'TMOUT': '86400', 13:55:58 INFO - 'USER': 'cltbld', 13:55:58 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', 13:55:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:58 INFO - '_': '/tools/buildbot/bin/python'} 13:55:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:55:58 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 13:55:58 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 13:55:58 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 13:55:58 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 13:55:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:55:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:55:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:55:59 INFO - Installing collected packages: mozsystemmonitor 13:55:59 INFO - Running setup.py install for mozsystemmonitor 13:56:00 INFO - Successfully installed mozsystemmonitor 13:56:00 INFO - Cleaning up... 13:56:00 INFO - Return code: 0 13:56:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:56:00 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')]} 13:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:00 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')]} 13:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:00 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 0x270d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ab310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8400>, '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 0x29a93b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a9840>, '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': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', '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 13:56:00 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 13:56:00 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 13:56:00 INFO - Using env: {'DISPLAY': ':0', 13:56:00 INFO - 'HOME': '/home/cltbld', 13:56:00 INFO - 'LANG': 'en_US.UTF-8', 13:56:00 INFO - 'LANGUAGE': 'en_US:en', 13:56:00 INFO - 'LOGNAME': 'cltbld', 13:56:00 INFO - 'MAIL': '/var/mail/cltbld', 13:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:00 INFO - 'MOZ_NO_REMOTE': '1', 13:56:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:00 INFO - 'NO_EM_RESTART': '1', 13:56:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:00 INFO - 'PWD': '/builds/slave/test', 13:56:00 INFO - 'SHELL': '/bin/bash', 13:56:00 INFO - 'SHLVL': '1', 13:56:00 INFO - 'TERM': 'linux', 13:56:00 INFO - 'TMOUT': '86400', 13:56:00 INFO - 'USER': 'cltbld', 13:56:00 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', 13:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:00 INFO - '_': '/tools/buildbot/bin/python'} 13:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:00 INFO - Downloading/unpacking jsonschema==2.5.1 13:56:00 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 13:56:00 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 13:56:00 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 13:56:00 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 13:56:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:56:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:56:01 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 13:56:01 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 13:56:01 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 13:56:01 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 13:56:01 INFO - Downloading functools32-3.2.3-2.tar.gz 13:56:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:56:01 INFO - warning: no files found matching '*.txt' 13:56:01 INFO - no previously-included directories found matching 'build' 13:56:01 INFO - no previously-included directories found matching 'dist' 13:56:01 INFO - no previously-included directories found matching '.git*' 13:56:01 INFO - Installing collected packages: jsonschema, functools32 13:56:01 INFO - Running setup.py install for functools32 13:56:01 INFO - warning: no files found matching '*.txt' 13:56:01 INFO - no previously-included directories found matching 'build' 13:56:01 INFO - no previously-included directories found matching 'dist' 13:56:01 INFO - no previously-included directories found matching '.git*' 13:56:01 INFO - Successfully installed jsonschema functools32 13:56:01 INFO - Cleaning up... 13:56:01 INFO - Return code: 0 13:56:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:56:01 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')]} 13:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:01 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')]} 13:56:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:01 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 0x270d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ab310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8400>, '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 0x29a93b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a9840>, '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': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', '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 13:56:01 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 13:56:01 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 13:56:01 INFO - Using env: {'DISPLAY': ':0', 13:56:01 INFO - 'HOME': '/home/cltbld', 13:56:01 INFO - 'LANG': 'en_US.UTF-8', 13:56:01 INFO - 'LANGUAGE': 'en_US:en', 13:56:01 INFO - 'LOGNAME': 'cltbld', 13:56:01 INFO - 'MAIL': '/var/mail/cltbld', 13:56:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:01 INFO - 'MOZ_NO_REMOTE': '1', 13:56:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:01 INFO - 'NO_EM_RESTART': '1', 13:56:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:01 INFO - 'PWD': '/builds/slave/test', 13:56:01 INFO - 'SHELL': '/bin/bash', 13:56:01 INFO - 'SHLVL': '1', 13:56:01 INFO - 'TERM': 'linux', 13:56:01 INFO - 'TMOUT': '86400', 13:56:01 INFO - 'USER': 'cltbld', 13:56:01 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', 13:56:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:01 INFO - '_': '/tools/buildbot/bin/python'} 13:56:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:56:01 INFO - Cleaning up... 13:56:01 INFO - Return code: 0 13:56:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:56:02 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')]} 13:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:02 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')]} 13:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:02 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 0x270d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ab310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8400>, '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 0x29a93b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a9840>, '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': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', '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 13:56:02 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 13:56:02 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 13:56:02 INFO - Using env: {'DISPLAY': ':0', 13:56:02 INFO - 'HOME': '/home/cltbld', 13:56:02 INFO - 'LANG': 'en_US.UTF-8', 13:56:02 INFO - 'LANGUAGE': 'en_US:en', 13:56:02 INFO - 'LOGNAME': 'cltbld', 13:56:02 INFO - 'MAIL': '/var/mail/cltbld', 13:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:02 INFO - 'MOZ_NO_REMOTE': '1', 13:56:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:02 INFO - 'NO_EM_RESTART': '1', 13:56:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:02 INFO - 'PWD': '/builds/slave/test', 13:56:02 INFO - 'SHELL': '/bin/bash', 13:56:02 INFO - 'SHLVL': '1', 13:56:02 INFO - 'TERM': 'linux', 13:56:02 INFO - 'TMOUT': '86400', 13:56:02 INFO - 'USER': 'cltbld', 13:56:02 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', 13:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:02 INFO - '_': '/tools/buildbot/bin/python'} 13:56:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:02 INFO - Downloading/unpacking blobuploader==1.2.4 13:56:02 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 13:56:02 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 13:56:02 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 13:56:02 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 13:56:03 INFO - Downloading blobuploader-1.2.4.tar.gz 13:56:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:56:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:56:03 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 13:56:03 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 13:56:03 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 13:56:03 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 13:56:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:56:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:56:03 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 13:56:03 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 13:56:03 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 13:56:03 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 13:56:03 INFO - Downloading docopt-0.6.1.tar.gz 13:56:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:56:03 INFO - Installing collected packages: blobuploader, requests, docopt 13:56:03 INFO - Running setup.py install for blobuploader 13:56:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:56:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:56:04 INFO - Running setup.py install for requests 13:56:04 INFO - Running setup.py install for docopt 13:56:04 INFO - Successfully installed blobuploader requests docopt 13:56:04 INFO - Cleaning up... 13:56:04 INFO - Return code: 0 13:56:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:56:04 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')]} 13:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:04 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')]} 13:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:04 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 0x270d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ab310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8400>, '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 0x29a93b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a9840>, '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': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', '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 13:56:04 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 13:56:04 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 13:56:04 INFO - Using env: {'DISPLAY': ':0', 13:56:04 INFO - 'HOME': '/home/cltbld', 13:56:04 INFO - 'LANG': 'en_US.UTF-8', 13:56:04 INFO - 'LANGUAGE': 'en_US:en', 13:56:04 INFO - 'LOGNAME': 'cltbld', 13:56:04 INFO - 'MAIL': '/var/mail/cltbld', 13:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:04 INFO - 'MOZ_NO_REMOTE': '1', 13:56:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:04 INFO - 'NO_EM_RESTART': '1', 13:56:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:04 INFO - 'PWD': '/builds/slave/test', 13:56:04 INFO - 'SHELL': '/bin/bash', 13:56:04 INFO - 'SHLVL': '1', 13:56:04 INFO - 'TERM': 'linux', 13:56:04 INFO - 'TMOUT': '86400', 13:56:04 INFO - 'USER': 'cltbld', 13:56:04 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', 13:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:04 INFO - '_': '/tools/buildbot/bin/python'} 13:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:56:04 INFO - Running setup.py (path:/tmp/pip-aXLC2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:56:04 INFO - Running setup.py (path:/tmp/pip-B2f11K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:56:04 INFO - Running setup.py (path:/tmp/pip-kTACFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:56:04 INFO - Running setup.py (path:/tmp/pip-PlJuKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:56:05 INFO - Running setup.py (path:/tmp/pip-vl37CG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:56:05 INFO - Running setup.py (path:/tmp/pip-drUw3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:56:05 INFO - Running setup.py (path:/tmp/pip-YIofq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:56:05 INFO - Running setup.py (path:/tmp/pip-wo0FzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:56:05 INFO - Running setup.py (path:/tmp/pip-2pjdU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:56:05 INFO - Running setup.py (path:/tmp/pip-Ykxfdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:56:05 INFO - Running setup.py (path:/tmp/pip-MQ2Y2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:56:05 INFO - Running setup.py (path:/tmp/pip-e7lp5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:56:05 INFO - Running setup.py (path:/tmp/pip-npy950-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:56:05 INFO - Running setup.py (path:/tmp/pip-8MvF3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:56:05 INFO - Running setup.py (path:/tmp/pip-Lem0Es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:56:05 INFO - Running setup.py (path:/tmp/pip-ppb76z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:56:05 INFO - Running setup.py (path:/tmp/pip-Kqj9fG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:56:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:56:06 INFO - Running setup.py install for manifestparser 13:56:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:56:06 INFO - Running setup.py install for mozcrash 13:56:06 INFO - Running setup.py install for mozdebug 13:56:06 INFO - Running setup.py install for mozdevice 13:56:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:56:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:56:06 INFO - Running setup.py install for mozfile 13:56:06 INFO - Running setup.py install for mozhttpd 13:56:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:56:06 INFO - Running setup.py install for mozinfo 13:56:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:56:06 INFO - Running setup.py install for mozInstall 13:56:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:56:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:56:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:56:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:56:06 INFO - Running setup.py install for mozleak 13:56:06 INFO - Running setup.py install for mozlog 13:56:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:56:07 INFO - Running setup.py install for moznetwork 13:56:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:56:07 INFO - Running setup.py install for mozprocess 13:56:07 INFO - Running setup.py install for mozprofile 13:56:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:56:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:56:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:56:07 INFO - Running setup.py install for mozrunner 13:56:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:56:07 INFO - Running setup.py install for mozscreenshot 13:56:07 INFO - Running setup.py install for moztest 13:56:07 INFO - Running setup.py install for mozversion 13:56:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:56:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:56:07 INFO - Cleaning up... 13:56:07 INFO - Return code: 0 13:56:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:56:07 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')]} 13:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:07 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')]} 13:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:07 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 0x270d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ab310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8400>, '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 0x29a93b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a9840>, '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': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', '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 13:56:07 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 13:56:07 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 13:56:07 INFO - Using env: {'DISPLAY': ':0', 13:56:07 INFO - 'HOME': '/home/cltbld', 13:56:07 INFO - 'LANG': 'en_US.UTF-8', 13:56:07 INFO - 'LANGUAGE': 'en_US:en', 13:56:07 INFO - 'LOGNAME': 'cltbld', 13:56:07 INFO - 'MAIL': '/var/mail/cltbld', 13:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:07 INFO - 'MOZ_NO_REMOTE': '1', 13:56:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:07 INFO - 'NO_EM_RESTART': '1', 13:56:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:07 INFO - 'PWD': '/builds/slave/test', 13:56:07 INFO - 'SHELL': '/bin/bash', 13:56:07 INFO - 'SHLVL': '1', 13:56:07 INFO - 'TERM': 'linux', 13:56:07 INFO - 'TMOUT': '86400', 13:56:07 INFO - 'USER': 'cltbld', 13:56:07 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', 13:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:07 INFO - '_': '/tools/buildbot/bin/python'} 13:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:56:08 INFO - Running setup.py (path:/tmp/pip-EMrOkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:56:08 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)) 13:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:56:08 INFO - Running setup.py (path:/tmp/pip-SVRypD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:56:08 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)) 13:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:56:08 INFO - Running setup.py (path:/tmp/pip-ZVHcb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:56:08 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)) 13:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:56:08 INFO - Running setup.py (path:/tmp/pip-8iFiUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:56:08 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)) 13:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:56:08 INFO - Running setup.py (path:/tmp/pip-l9490T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:56:08 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)) 13:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:56:08 INFO - Running setup.py (path:/tmp/pip-pGEyCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:56:08 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)) 13:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:56:08 INFO - Running setup.py (path:/tmp/pip-IAR8he-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:56:08 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)) 13:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:56:08 INFO - Running setup.py (path:/tmp/pip-vmso6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:56:08 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)) 13:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:56:08 INFO - Running setup.py (path:/tmp/pip-zsXUM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:56:08 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)) 13:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:56:08 INFO - Running setup.py (path:/tmp/pip-YThs2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:56:08 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)) 13:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:56:08 INFO - Running setup.py (path:/tmp/pip-YQyeW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:56:08 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)) 13:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:56:08 INFO - Running setup.py (path:/tmp/pip-MR2wHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:56:08 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)) 13:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:56:08 INFO - Running setup.py (path:/tmp/pip-lXE4XI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:56:08 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)) 13:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:56:09 INFO - Running setup.py (path:/tmp/pip-LH8UC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:56:09 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)) 13:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:56:09 INFO - Running setup.py (path:/tmp/pip-s5lg0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:56:09 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)) 13:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:56:09 INFO - Running setup.py (path:/tmp/pip-YiNdLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:56:09 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)) 13:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:56:09 INFO - Running setup.py (path:/tmp/pip-J8zAMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:56:09 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)) 13:56:09 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)) 13:56:09 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)) 13:56:09 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)) 13:56:09 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)) 13:56:09 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)) 13:56:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:56:09 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 13:56:09 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 13:56:09 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 13:56:09 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 13:56:10 INFO - Downloading blessings-1.6.tar.gz 13:56:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:56:10 INFO - Installing collected packages: blessings 13:56:10 INFO - Running setup.py install for blessings 13:56:10 INFO - Successfully installed blessings 13:56:10 INFO - Cleaning up... 13:56:10 INFO - Return code: 0 13:56:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:56:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:56:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:56:10 INFO - Reading from file tmpfile_stdout 13:56:10 INFO - Current package versions: 13:56:10 INFO - argparse == 1.2.1 13:56:10 INFO - blessings == 1.6 13:56:10 INFO - blobuploader == 1.2.4 13:56:10 INFO - docopt == 0.6.1 13:56:10 INFO - functools32 == 3.2.3-2 13:56:10 INFO - jsonschema == 2.5.1 13:56:10 INFO - manifestparser == 1.1 13:56:10 INFO - mozInstall == 1.12 13:56:10 INFO - mozcrash == 1.0 13:56:10 INFO - mozdebug == 0.1 13:56:10 INFO - mozdevice == 0.49 13:56:10 INFO - mozfile == 1.2 13:56:10 INFO - mozhttpd == 0.7 13:56:10 INFO - mozinfo == 0.9 13:56:10 INFO - mozleak == 0.1 13:56:10 INFO - mozlog == 3.4 13:56:10 INFO - moznetwork == 0.27 13:56:10 INFO - mozprocess == 0.25 13:56:10 INFO - mozprofile == 0.28 13:56:10 INFO - mozrunner == 6.13 13:56:10 INFO - mozscreenshot == 0.1 13:56:10 INFO - mozsystemmonitor == 0.3 13:56:10 INFO - moztest == 0.8 13:56:10 INFO - mozversion == 1.4 13:56:10 INFO - psutil == 3.1.1 13:56:10 INFO - requests == 1.2.3 13:56:10 INFO - wsgiref == 0.1.2 13:56:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:10 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 0x270d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ab310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8400>, '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 0x29a93b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a9840>, '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': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', '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 13:56:10 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 13:56:10 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 13:56:10 INFO - Using env: {'DISPLAY': ':0', 13:56:10 INFO - 'HOME': '/home/cltbld', 13:56:10 INFO - 'LANG': 'en_US.UTF-8', 13:56:10 INFO - 'LANGUAGE': 'en_US:en', 13:56:10 INFO - 'LOGNAME': 'cltbld', 13:56:10 INFO - 'MAIL': '/var/mail/cltbld', 13:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:10 INFO - 'MOZ_NO_REMOTE': '1', 13:56:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:10 INFO - 'NO_EM_RESTART': '1', 13:56:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:10 INFO - 'PWD': '/builds/slave/test', 13:56:10 INFO - 'SHELL': '/bin/bash', 13:56:10 INFO - 'SHLVL': '1', 13:56:10 INFO - 'TERM': 'linux', 13:56:10 INFO - 'TMOUT': '86400', 13:56:10 INFO - 'USER': 'cltbld', 13:56:10 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', 13:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:10 INFO - '_': '/tools/buildbot/bin/python'} 13:56:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:11 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)) 13:56:11 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)) 13:56:11 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)) 13:56:11 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)) 13:56:11 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)) 13:56:11 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)) 13:56:11 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)) 13:56:11 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)) 13:56:11 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)) 13:56:11 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)) 13:56:11 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)) 13:56:11 INFO - Cleaning up... 13:56:11 INFO - Return code: 0 13:56:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:56:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:56:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x274ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ab310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29a8400>, '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 0x29a93b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a9840>, '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': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', '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 13:56:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:56:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:56:11 INFO - Using env: {'DISPLAY': ':0', 13:56:11 INFO - 'HOME': '/home/cltbld', 13:56:11 INFO - 'LANG': 'en_US.UTF-8', 13:56:11 INFO - 'LANGUAGE': 'en_US:en', 13:56:11 INFO - 'LOGNAME': 'cltbld', 13:56:11 INFO - 'MAIL': '/var/mail/cltbld', 13:56:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:11 INFO - 'MOZ_NO_REMOTE': '1', 13:56:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:11 INFO - 'NO_EM_RESTART': '1', 13:56:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:11 INFO - 'PWD': '/builds/slave/test', 13:56:11 INFO - 'SHELL': '/bin/bash', 13:56:11 INFO - 'SHLVL': '1', 13:56:11 INFO - 'TERM': 'linux', 13:56:11 INFO - 'TMOUT': '86400', 13:56:11 INFO - 'USER': 'cltbld', 13:56:11 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', 13:56:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:11 INFO - '_': '/tools/buildbot/bin/python'} 13:56:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:56:11 INFO - Cleaning up... 13:56:11 INFO - Return code: 0 13:56:11 INFO - Running post-action listener: _resource_record_post_action 13:56:11 INFO - Running post-action listener: _start_resource_monitoring 13:56:11 INFO - Starting resource monitoring. 13:56:11 INFO - [mozharness: 2017-05-11 20:56:11.294797Z] Finished create-virtualenv step (success) 13:56:11 INFO - [mozharness: 2017-05-11 20:56:11.295259Z] Running install step. 13:56:11 INFO - Running pre-action listener: _resource_record_pre_action 13:56:11 INFO - Running main action method: install 13:56:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:56:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:56:11 INFO - Reading from file tmpfile_stdout 13:56:11 INFO - Detecting whether we're running mozinstall >=1.0... 13:56:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:56:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:56:11 INFO - Reading from file tmpfile_stdout 13:56:11 INFO - Output received: 13:56:11 INFO - Usage: mozinstall [options] installer 13:56:11 INFO - Options: 13:56:11 INFO - -h, --help show this help message and exit 13:56:11 INFO - -d DEST, --destination=DEST 13:56:11 INFO - Directory to install application into. [default: 13:56:11 INFO - "/builds/slave/test"] 13:56:11 INFO - --app=APP Application being installed. [default: firefox] 13:56:11 INFO - mkdir: /builds/slave/test/build/application 13:56:11 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'] 13:56:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 13:56:25 INFO - Reading from file tmpfile_stdout 13:56:25 INFO - Output received: 13:56:25 INFO - /builds/slave/test/build/application/firefox/firefox 13:56:25 INFO - Running post-action listener: _resource_record_post_action 13:56:25 INFO - [mozharness: 2017-05-11 20:56:25.672016Z] Finished install step (success) 13:56:25 INFO - [mozharness: 2017-05-11 20:56:25.672132Z] Running run-tests step. 13:56:25 INFO - Running pre-action listener: _resource_record_pre_action 13:56:25 INFO - Running main action method: run_tests 13:56:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:56:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:56:25 INFO - Python 2.7.3 13:56:25 INFO - Return code: 0 13:56:25 INFO - grabbing minidump binary from tooltool 13:56: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')]} 13:56:25 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 0x29a8400>, '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 0x29a93b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29a9840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:56:25 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 13:56:25 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 13:56:25 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 13:56:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:56:25 INFO - Return code: 0 13:56:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:56:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:56:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:56:25 INFO - ENV: RUST_BACKTRACE is now 1 13:56:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:56:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:56:25 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/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 13:56:25 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/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 13:56:25 INFO - Using env: {'DISPLAY': ':0', 13:56:25 INFO - 'HOME': '/home/cltbld', 13:56:25 INFO - 'LANG': 'en_US.UTF-8', 13:56:25 INFO - 'LANGUAGE': 'en_US:en', 13:56:25 INFO - 'LOGNAME': 'cltbld', 13:56:25 INFO - 'MAIL': '/var/mail/cltbld', 13:56:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:56:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:25 INFO - 'MOZ_NO_REMOTE': '1', 13:56:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:56:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:25 INFO - 'NO_EM_RESTART': '1', 13:56:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:25 INFO - 'PWD': '/builds/slave/test', 13:56:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:56:25 INFO - 'RUST_BACKTRACE': '1', 13:56:25 INFO - 'SHELL': '/bin/bash', 13:56:25 INFO - 'SHLVL': '1', 13:56:25 INFO - 'TERM': 'linux', 13:56:25 INFO - 'TMOUT': '86400', 13:56:25 INFO - 'USER': 'cltbld', 13:56:25 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733', 13:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:25 INFO - '_': '/tools/buildbot/bin/python'} 13:56:25 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/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 13:56:25 INFO - mozversion application_buildid: 20170511200247 13:56:25 INFO - mozversion application_changeset: 8b7e2a303954352189cf0e9772f35aed06953fa5 13:56:25 INFO - mozversion application_display_name: Firefox 13:56:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:56:25 INFO - mozversion application_name: Firefox 13:56:25 INFO - mozversion application_remotingname: firefox 13:56:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:56:25 INFO - mozversion application_vendor: Mozilla 13:56:25 INFO - mozversion application_version: 54.0 13:56:25 INFO - mozversion platform_buildid: 20170511200247 13:56:25 INFO - mozversion platform_changeset: 8b7e2a303954352189cf0e9772f35aed06953fa5 13:56:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:56:25 INFO - mozversion platform_version: 54.0 13:56:25 INFO - using testdate: 1494536185 13:56:25 INFO - actual date: 1494536185 13:56:25 INFO - starting webserver on 'localhost:52471' 13:56:25 INFO - SUITE-START | Running 2 tests 13:56:25 INFO - TEST-START | dromaeo_css 13:56:25 INFO - operating with platform_type : linux_ 13:56:25 INFO - Initialising browser for dromaeo_css test... 13:56:25 INFO - TEST-INFO | started process 2314 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp35IzQ7/profile http://localhost:52471/getInfo.html) 13:56:30 INFO - PROCESS | 2314 | __metrics Screen width/height:1600/1200 13:56:30 INFO - PROCESS | 2314 | colorDepth:24 13:56:30 INFO - PROCESS | 2314 | Browser inner width/height: 1024/697 13:56:30 INFO - PROCESS | 2314 | __metrics 13:56:31 INFO - PROCESS | 2314 | 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" 13:56:31 INFO - PROCESS | 2314 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:56:32 INFO - TEST-INFO | 2314: exit 0 13:56:32 INFO - Browser initialized. 13:56:32 INFO - Running cycle 1/1 for dromaeo_css test... 13:56:32 INFO - TEST-INFO | started process 2409 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp35IzQ7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:56:44 INFO - PROCESS | 2409 | RSS: Main: 141074432 13:56:44 INFO - PROCESS | 2409 | 13:59:45 INFO - PROCESS | 2409 | Cycle 1(1): loaded http://localhost:52471/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52471/tests/dromaeo/cssquery-ext.html) 13:59:46 INFO - PROCESS | 2409 | RSS: Main: 245821440 13:59:46 INFO - PROCESS | 2409 | 14:02:49 INFO - PROCESS | 2409 | Cycle 1(1): loaded http://localhost:52471/tests/dromaeo/cssquery-ext.html (next: http://localhost:52471/tests/dromaeo/cssquery-jquery.html) 14:02:49 INFO - PROCESS | 2409 | RSS: Main: 230952960 14:02:49 INFO - PROCESS | 2409 | 14:05:51 INFO - PROCESS | 2409 | Cycle 1(1): loaded http://localhost:52471/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52471/tests/dromaeo/cssquery-mootools.html) 14:05:52 INFO - PROCESS | 2409 | RSS: Main: 217980928 14:05:52 INFO - PROCESS | 2409 | 14:08:53 INFO - PROCESS | 2409 | Cycle 1(1): loaded http://localhost:52471/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52471/tests/dromaeo/cssquery-prototype.html) 14:08:54 INFO - PROCESS | 2409 | RSS: Main: 173969408 14:08:54 INFO - PROCESS | 2409 | 14:11:57 INFO - PROCESS | 2409 | Cycle 1(1): loaded http://localhost:52471/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52471/tests/dromaeo/cssquery-yui.html) 14:11:57 INFO - PROCESS | 2409 | RSS: Main: 175792128 14:11:57 INFO - PROCESS | 2409 | 14:15:00 INFO - PROCESS | 2409 | Cycle 1(1): loaded http://localhost:52471/tests/dromaeo/cssquery-yui.html (next: http://localhost:52471/tests/dromaeo/cssquery-dojo.html) 14:15:00 INFO - PROCESS | 2409 | RSS: Main: 260608000 14:15:00 INFO - PROCESS | 2409 | 14:15:00 INFO - PROCESS | 2409 | __start_tp_report 14:15:00 INFO - PROCESS | 2409 | _x_x_mozilla_page_load 14:15:00 INFO - PROCESS | 2409 | _x_x_mozilla_page_load_details 14:15:00 INFO - PROCESS | 2409 | |i|pagename|runs| 14:15:00 INFO - PROCESS | 2409 | |0;dojo.html;3406.659334066593;3450.309938012398;3456.706179974702;3462.8614855405785;3494.4758286257056;5748.741306641201;5792.739326730297;5796.188533605298;5809.941900580993;5816.447437493442;5783.21926539917;5836.037053886103;5854.443827836349;5856.5021973132225;5863.736131874065;5205.115130427836;5210.869728256805;5218.791248350061;5260.316158899357;5292.550133238679;5071.873203169932;5104.591632669396;5139.743012849362;5297.947020529803;5310.097283461801;5252.080885844981;5256.526912577867;5262;5285.920711189336;5287.101192797234;5271.288376069239;5275.709835959021;5276.419593844674;5278.181881808307;5299.788008479656;5007.5993920486635;5091.287219789232;5182.740862956837;5194.935038317136;5247.02929957959;5612.214289999403;5621.7189140542805;5674.120511320752;5695.174199741055;5696.8006119785605;6104.816855494342;6138.570300078996;6149.538784591174;6161.229846269216;6162.952298109288;6057.30341010785;6170.568060235785;6192.659403732793;6199.690015499207;6203.906941395882;5699.629524080921;5723.685197314146;5732.082866741343;5810.593258471951;5846.883062338729;6307.274663413675;6316.021016742412;6320.178376810985;6326.114343991845;6327.873442531123;6078.848028799315;6131.540134489842;6132.601380910227;6142.232220972379;6143;6027.6986150693165;6072.908906366408;6108.6759897029115;6139.001538246403;6220.844478888064;6191.473724733448;6237.688115594202;6239.875202495925;6241.937580624136;6244.594101383395;5976.551758618121;6001.2498437695285;6012.128241404972;6100.359462256488;6128.540359472968;4656.278276867091;4718.127146477913;4737.6683632146105;4745.596624286905;4746.264329029006;4206.095689426788;4225.323948168279;4239.003834098984;4243.045314804144;4254.31930891056;5864.296284445893;5901.970490147522;5973.402659733992;5975.820725378158;5988.550858685616;6155.692215389213;6159.045347971071;6163.907541386883;6164.506839452833;6182.876342473125;6057.939420605738;6067.1202675611785;6078.331383547807;6082.939170608237;6089.54328425361;5290.550303224269;5334.199870019528;5338.145896656516;5340.679559226459;5346.224797404355;6160.8767824643255;6208.348123447154;6432.71052802625;6506.41442270198;6515.54391192612;6140.565599444434;6173.81478555644;6273.654948977667;6281.183446151971;6284.654344011124;6429.324920883427;6454.741810327534;6475.158229430144;6483.675816209076;6484.059811327425;5981.551383646157;6163.3836616338;6166.075088736726;6169.598976066721;6174.25908890936;6134.191681013941;6135.07955838608;6139.079138129317;6234;6248.312685604671;6080.209572755513;6080.908786368296;6081.969590152198;6086.272008928084;6112.297085835008;6021.518278537794;6084.787032453843;6087.969560152171;6092.390760923872;6092.908606370819;5833.854153646193;5847.619904706181;5975.43233392827;5990.430909063632;6083.847903802263;6136.103574230307;6140.048292514667;6142.08251080733;6144.648177400964;6145.784897528565;5786;5811.79658711943;5977.581569290108;5986.910196347146;6009.819705408845 14:15:00 INFO - PROCESS | 2409 | |1;ext.html;785.1598789295456;835.8202986357933;856.2293935458086;922.7600823785818;938.7371535969922;32247.032589022307;32647.347053058955;35200;35223.29553408933;48728.2690759638;47494.76252618732;47696;47716;47759;47882.28176577354;9214.861777073349;9372;9374;9387.155156035955;9390;7526.698932042739;7773.611319434034;10696.51865666047;10862.782744345108;11279.661610151708;11029.724256893602;11044.226904116715;11069.557217711283;11071.280366776175;11078.058365038982;11014.008739213468;11057.668269951873;11060.612878549293;11064.17018723599;11067.557297708041;10736.355818650905;10737.24839261252;10767.78464430707;10801.297915635458;10810.945945270301;43313;43619.78190109061;43662;43730.12539749219;44026;4327.32926396409;4349.13017396522;4383.780810959439;4449.26587113126;4529.411176547028;31238.843805781053;31469.213269668213;31604.683953160635;32066.839665801755;32668;4419.204543182226;4493.910121797546;4542.38677778499;4598.9080218395775;4611.7694115294435;240146;240252;242545.99999999822;243012;243679;55658.72170639121;55987.16019259714;56040.71979640157;56257;56267.43732562704;56752.432475674716;56882.71558642263;57179.42820571741;57267.42732572704;57392;17172.91413542949;17334.65330693379;17361.7395739064;17411.3035478582;17439.21523531444;15375.077495350315;15506.844931550766;15506.844931550766;15516.146611936452;15556.299966501534;12199.207051541622;12527.686807829878;13774.311284435738;14176;14211;5775.826725198251;5800.303963524404;5814.418558144152;5901.645901245939;5952.345242023374;1034.6183911136332;1039.1323245090282;1045.8431235314763;1047.9633212837514;1055.208593554834;29237.26906827304;29244;29439.705602944126;29494.705052949626;29677.703222967924;1111.6720567432626;1113.4711012269108;1119.5018216893568;1129.4352823588206;1145.1068166829887;14690.779638305434;15575.143367114692;15665.295061722021;15728;15783.684326313409;312696;312940;312991;313521;313617;59340;59454;59541;59666;59791.70104149451;1512.138081293652;1520.2246854104487;1541.4142625802124;1560.001598976656;1564.1866229560792;28178.85910570434;29008;29401.705982939897;29582.852085739432;30039.249018774706;36657.63342366542;36727.449088263165;36819.263614728094;36821.63178368182;36836.81581592075;283828;284929;285440;285765;287446;30068.849655751583;30582.541261881535;31334;32894.67105328916;33365.499517506774;39939.40090898697;40052.1989560213;40291.1941761169;40667.3899891496;43875.561244388424;31775;32099.518507221943;32102.518462223554;32315.51526727054;32349;31347;31986.36027279488;32260.83869580637;32577.18557036093;32623.673763262064;35700.46449303314;35793.642063580075;35924;36313.45529817002;36781.26437471289;30459.543106852972;30500.84749576238;30616.693833062276;30718.078457646305;30843.537346939363;24568.385790355384;25701.87149064243;25855.324802807943;25903.481930361664;25980.740192597834 14:15:00 INFO - PROCESS | 2409 | |2;jquery.html;453.7345652793116;456.24946962247094;470.75285475125554;475.8524857294239;478.5334299058418;20251.291204807836;21758.34724958252;22292.88853555734;22399.888000559993;22530.549389012253;21819.345419637397;22050.44873878154;22483.775162248374;22676.206332778354;22894.88552557235;6738.056672065927;6869.416099631538;7773.261540153676;8053.6375863086305;8102.837943241161;7644.312011918926;7855.7250496232355;7958.681652733883;8099.1900809919125;8100.068492123422;7944.801379965518;8176;8176.1415051419635;8177.386695997824;8183.386246031541;8011.078725946503;8109.4323397362205;8118.309943654767;8125.431219814615;8130.55281959492;7751.457397982143;7971.123176450586;8045.074816396131;8048.557329346883;8052.2752952234;29689.40621187586;29911.252218694488;30061.849690751624;30738.38523229523;31009.37981240385;3278.5901762279714;3523.154442933703;3849.480320156799;3915.9412608810608;3927.410888366739;18403;18756.90621546897;18811.247550097978;19327.130279137473;19644.90177549103;2348.8825558721996;2394.652775347582;2404.8797560121925;2455.8772061396858;2473.3322003059075;118697;121723.3913830434;122074;122230;122647;21257.468563286042;21430.892845535673;21796.346109616738;21984.45038874009;22465.43836404103;42190.789046054575;42515.36226956598;42547;42648.36027459591;42674.78662606667;9990.650327238513;10500.31747936397;10516.57933682644;10624.946875265574;10657.520411581498;9097.454152750855;9135.406198597202;9144.014790920923;9158.129977652112;9378.718638440858;9311.767205819773;9476.810463790685;9591.232701383873;9704.854427183598;9774.853377199348;3911.7066220033043;3924.234774219057;4083.7958102094776;4123.60825721556;4123.9175216495505;968.8788901387327;988.8566157907063;998.6604554451376;998.7952469743685;1006.7835415385728;21653.78346216549;21785.346439606838;21837.45406364822;22091.66862497064;22109.889450552964;1014.8274793285159;1018.3940555369692;1024.8206563851297;1025.6410256410197;1038.2368958815264;11744.530218791324;12020.519179232733;12279.938600306941;12315.261084334968;12407;661718;674589;688982;716960;731862;108623;109057.45471272593;112582.43708781403;112909;113905;1274.1526884621624;1307.5358247821887;1321.5506727712432;1326.8540460549332;1328.130074801007;18460.26158953627;18496.260149593865;18776.624467510846;18815;19177.808221917603;52154;52715.73642131765;53877.73061134826;54270;54744.45255547394;69215.65392173007;69838.65080674565;70892.5187618431;71101.28898710947;71887;8361.498310101413;12112;12583.433745481658;12991.220526768424;13124.934375328063;13116.147450416074;13271.535496257584;14203.715925681221;14221.431142754174;14259.358328875433;11822.527098915947;12241.938790305992;12247.142700010914;12283.877161228273;13005.106847141878;11298.09615230796;11370.147238956946;11517.94241028823;11630.360330181922;12000.699982500508;12931.482740690642;13086.08397412172;13123.671908202372;13204.207747535178;13310.33448327599;12072.818907716566;12700;12833.294168820432;12885.677858053623;12887.226766394046;11944.402779861146;12266.75466490683;12985.545505907248;12991.48034078664;13394.799078014032 14:15:00 INFO - PROCESS | 2409 | |3;mootools.html;1461.3789139615674;1569.6703692224633;1619.8623117035058;1629.6088938654723;1633.599768056826;9614.567344469498;11315.264507806996;11687.5909343173;12109;12156.756864862697;11788.587399441021;11872.050235981122;11907.642770716871;11914.880851191485;11922.523099076048;1933.1300914588421;1998.19073275323;2389.545986262613;2440.9511809763794;2447.547203767309;2293.9655905161435;2345.542619189259;2354.3878591566145;2355.3522781235124;2362.917297894574;2395.1257790906366;2395.3412811476896;2403.158894386968;2417.782399584037;2425.163318655061;2265.9546809063727;2299.9770002300015;2302.6776251324827;2304.043821813945;2304.37781798915;2205.514786746913;2209.712737344135;2216.2907869481774;2249.3926639807323;2263.287064574654;11915.821262681067;11991.820122698167;11995.94002029993;12012.219205751686;12016.519339226421;5712.971435142797;5857.355690874;5868.559858010623;5871.735771890232;5879.970600146972;3282.6553211912883;3358.630550639452;3571.714262858965;3603.423452247654;3624.4019736743408;1336.4386957477882;1352.2900477249425;1402.754517959353;1442.8124343835234;1460.5472303585923;17949.371771987917;17953.371631992817;17956.820431795644;17959.910200448914;17972.640547189112;4647.233206520954;4651.744154071491;4687.789049492712;4789.592884604778;4852.636052296092;14032.36854341557;14039.929800350932;14046.719065618834;14139.50511732105;14271.001029927806;5826.300843898759;5849.181114643953;5957.225560677084;5959.523238140939;5959.910601340984;5254.710990895461;5393.730313484389;5428.4842939920645;5430.70131142791;5437.646552974044;4085.4261815056325;4140.37894315855;4165.881244109282;4213.410122582841;4222.239996800606;1083.9458027098613;1092.1317552545745;1105.8949399807007;1106.5684383090602;1107.9612213572648;922.2437601167113;931.7111695374456;939.33307351781;962.4610218277787;1095.5070218401752;7134.785956421316;7229.168645605718;7269.818254543573;7272.163701174432;7351;1130.6099395708468;1138.2089447833841;1141.0529260713588;1145.381493993251;1164.4352489042808;4720.669553131248;4753.976230118897;4760.45254795696;4862.708237505761;4896.730679812645;13869.791953120712;14536.709265814625;15063.924680376527;15130.016548924285;15144.091354518765;14972.401103955719;15010.62473438151;15021.248937553297;15041.924790376197;15049.924750376396;10319.535620897077;10436.634717784993;10483.47582620866;10508;10508.527116279785;7272;7541.585212813348;7643.694252230071;7664.501807382502;7674.15584285717;13238.073334866853;13296.202227866359;13307.933460333024;13366.532171373956;13372;13532.458701652218;13532.86467135316;13536.661583460493;13651.863481365059;13677.042607017413;10067.496625168566;10317.17462603005;10380.948095259777;10385.221108416748;10443.94778026105;11111.83332250033;11128.332300062022;11161.441927903736;11175.10599152082;11314.490847912028;9128.497932613504;9180.357374983687;9190.49452280131;9259.35184537103;9387.436753794795;9115.589798458952;9119.133682300171;9154.862677059717;9203.907960920305;9307.76730581741;8594.441361311494;8722.127787221227;8759.080296568769;8760.12398760119;8770.473771573726;6447.6131432114225;6451.48388128958;6484.092227088306;6592.90110648331;6651.767188148391;6885.311468853075;6908.447324214151;6998.405135563534;7026;7064 14:15:00 INFO - PROCESS | 2409 | |4;prototype.html;421.4415898933913;442.71444918027873;443.55201246740785;447.5412701980472;449.27666457004227;5190.2474141249595;5232.555232805212;5321.494458026491;5328.653637513564;5346.599005074616;5057.291979122926;5192.350956130484;5205.16717325228;5209.609279304058;5215.608829337796;4336.28451305535;4356.978215108936;4358.455193100863;4373.759443230621;4385.583369579885;4339.934900976488;4382.8904277393;4392.824287028515;4404.555471713198;4424.491183513905;4393.890152746175;4397.296432570789;4398.472183338004;4415.668824838134;4443.4892136673525;4163.812628431712;4321.546237645033;4362.716423432498;4369.601727447218;4377.518472967971;4122.134351786129;4138.6482149017365;4139.461869956886;4144.233316836395;4148.419221309019;5102.515261050195;5110.029094472038;5121.718305493197;5149.588032957355;5151.665141765773;4994.650374473788;5116.948830511685;5119.436861945183;5119.87200319995;5144.61415393847;5396.514313711785;5411.945880541183;5420.349558053059;5464.863378415532;5469.339233892902;4566.862994110182;4621.306803979396;4622.560856718606;4633.7683115844075;4705.223638099709;5468.72656367184;5485.396606373336;5569.359523654751;5605.439456054362;5779.971100144515;4701.200795864711;4707.7881495332795;4761.428628564595;4830.053667262967;4834.8791280218275;4906.552566992675;4907.411110666742;4907.552272079745;4911.361523001987;4921.753912304371;4958.256261560723;5024.869404383984;5069.721165335942;5138.3577052868395;5208.5312321891215;5452.291202143696;5458.372287187024;5458.8263523342675;5495.482890949796;5498.917516237259;1638.3528506239968;1654.973916171957;1655.4371513685405;1657.4281872753882;1676.7652528646;1242.9062425065883;1281.5130250504749;1285.832841730569;1293.379177994568;1326.7412854493475;5426.406915597568;5456.972715136399;5492.752826122834;5543.25166102571;5547.278853749068;5574.8815450129505;5721.197822685841;5763.337216220105;5780.976658169607;5819.5344372450945;5396.811111611075;5445;5446.863828404322;5516.806911758069;5532.917006244909;2903.462859371023;2968.302448924501;3004.2038859702197;3006.2033561106323;3020.894268700585;5579.832605021856;5723.5707321950185;5785.971070144707;5816.88366232673;5843.737031833578;5730.541556675456;5737.913931291034;5765.827025189251;5825;5830.650160990354;5788.913166302509;5814.680192589363;5841.269841269841;5841.977653910549;5843.8539036522725;5360.383555891122;5632.310836402163;5632.887342253214;5655.388214358888;5657.548895174023;5389.407165211902;5572.414896435978;5576.104124597657;5597.580181486486;5623.831285061455;5709.571782116275;5726.713664316684;5730.793644446834;5808.912866307093;5837.708114594338;4683.508231635766;4687.890449297762;4710.528947105262;4713.340132381538;4718.457377401643;5461.972690136524;5636.718164091861;5687.800926967537;5717.771289148387;5764.654120752768;5459.508644222039;5490.670559766427;5544.528715059104;5547.618809754532;5548.750306236148;5528.226048353154;5545.195946587803;5546.417626149197;5549.612596850788;5574.721263936868;5316.548093412103;5473.890522189614;5478.260434841405;5504.779808807602;5509.917351239655;5474.8905021898545;5475.370332411823;5519.2824932758485;5558.749856256559;5593.580481463825;5272.7100009499845;5411.211078195746;5417.945820541744;5437.564994800327;5464.480874316933 14:15:00 INFO - PROCESS | 2409 | |5;yui.html;866.1944391715709;866.6836604639309;930.5347326336831;952.476138124032;954.451190565425;16861.915690421545;18119.637607247863;18121.728174077398;18128.093595320246;18180.63638727226;13492;17374.131293435308;17573.824261757378;17609.11954402281;17659.646807063884;903.2232280239006;931.0875342164649;934.6261495401826;934.8177105464437;934.9439033657968;920.7191806499015;923.9353245272833;924.191332583989;924.2051835421565;924.3529529329496;968.4721826604517;974.1086905481511;974.1232890398627;974.4786539201529;974.7075877236836;975.2831668723483;976.0873583199696;976.5166242709869;977.49170431376;977.7555611097225;712.9180144283422;713.1905287498674;713.5718568858695;713.9964300178466;714.2999860137043;17403;17642.558936026573;17708.557286067822;17782.733259001125;17809.554761131076;17445.825541744547;17732;17738.91130544339;18368.63262734738;18641.53396165093;14278.286085695778;14429.855701442957;14448.711025779425;14633.41466341345;14793.852061479354;4287.528371879091;4303.698741088125;4333.198358303724;4393.802278897425;4496.775161241958;46076;61885.690571547304;64781.67609161971;68979;87012.56493717554;13419.194848308938;13744;13853.515126970507;13894.861051389558;14001.579952601438;23264.65103023456;23289.184878529508;23544;23808.16671416492;23833.880830595735;9780.119789219005;9791.853122203172;9808.803823923625;10146.644867429604;10178.541965611565;5047.545720885065;5126.179811230185;5162.406323272871;5185.248139019821;5196.830713089525;3809.942850857239;3873.65137137659;3894.571597124314;3910.2961466936226;3913.3151698452657;642.0754114075716;644.635780783865;656.6125985668479;663.4062514049898;664.1830548425464;663.0948754949555;675.6317806795308;683.2381894188044;688.7933619914106;694.1496666583496;11578.247413918069;11580.420978951019;11753.353565553976;11849;11869.169158158846;628.6416742456755;640.5708175522411;646.8447572582546;651.5699638238834;652.8139480248201;5405.297311349578;5666;5699.857503562444;5747.224124743107;5756.769729210869;67598;67845;80402;81773.59113204396;81980;44531.10937781226;46941;69441.3055869445;73132.26867731255;74280.62859685667;63652.31475232847;64115.679421602596;65467.345326547074;66062;66626.33373666299;11874.238702849305;12416.192947458387;12457.377131143225;12633.431495582905;12740.235585864877;41971.790141049096;42234.78882605567;43050;43170.13659726725;43617.345739813296;47806.5219347816;48070.27894581654;48212.276815847086;48305.5169448301;49919.75040124776;14818.555443336718;15068.321925513597;15108.773368399703;15132.167730774912;15398.230088495306;16465.506034818976;16473.093979831225;16655.666886661955;16763.1618419081;16795.41216057432;14415;14627.488037918622;14657.267136643339;15508.591900836966;16196.947198432536;15281.541553753405;15449.073055616698;15849.366025359317;17653;17675.734863977308;15860.52418427449;16537;16655.16724163811;17245.913770431067;17255.654886902445;11546.249493782878;11703.414829258674;12043.638690839289;12517.311547864954;12558.874411256136;12974.610761677164;13343.132696374705;13464.865351346753;13914.721705566035;14324 14:15:00 INFO - PROCESS | 2409 | __end_tp_report 14:15:00 INFO - PROCESS | 2409 | __start_cc_report 14:15:00 INFO - PROCESS | 2409 | _x_x_mozilla_cycle_collect,1059 14:15:00 INFO - PROCESS | 2409 | __end_cc_report 14:15:00 INFO - PROCESS | 2409 | __startTimestamp1494537300566__endTimestamp 14:15:00 INFO - PROCESS | 2409 | 14:15:00 INFO - PROCESS | 2409 | ------- Summary: start ------- 14:15:00 INFO - PROCESS | 2409 | Number of tests: 6 14:15:00 INFO - PROCESS | 2409 | 14:15:00 INFO - PROCESS | 2409 | [#0] dojo.html Cycles:165 Average:5750.57 Median:6012.13 stddev:647.85 (10.8%) stddev-sans-first:623.20 14:15:00 INFO - PROCESS | 2409 | Values: 3406.7 3450.3 3456.7 3462.9 3494.5 5748.7 5792.7 5796.2 5809.9 5816.4 5783.2 5836.0 5854.4 5856.5 5863.7 5205.1 5210.9 5218.8 5260.3 5292.6 5071.9 5104.6 5139.7 5297.9 5310.1 5252.1 5256.5 5262.0 5285.9 5287.1 5271.3 5275.7 5276.4 5278.2 5299.8 5007.6 5091.3 5182.7 5194.9 5247.0 5612.2 5621.7 5674.1 5695.2 5696.8 6104.8 6138.6 6149.5 6161.2 6163.0 6057.3 6170.6 6192.7 6199.7 6203.9 5699.6 5723.7 5732.1 5810.6 5846.9 6307.3 6316.0 6320.2 6326.1 6327.9 6078.8 6131.5 6132.6 6142.2 6143.0 6027.7 6072.9 6108.7 6139.0 6220.8 6191.5 6237.7 6239.9 6241.9 6244.6 5976.6 6001.2 6012.1 6100.4 6128.5 4656.3 4718.1 4737.7 4745.6 4746.3 4206.1 4225.3 4239.0 4243.0 4254.3 5864.3 5902.0 5973.4 5975.8 5988.6 6155.7 6159.0 6163.9 6164.5 6182.9 6057.9 6067.1 6078.3 6082.9 6089.5 5290.6 5334.2 5338.1 5340.7 5346.2 6160.9 6208.3 6432.7 6506.4 6515.5 6140.6 6173.8 6273.7 6281.2 6284.7 6429.3 6454.7 6475.2 6483.7 6484.1 5981.6 6163.4 6166.1 6169.6 6174.3 6134.2 6135.1 6139.1 6234.0 6248.3 6080.2 6080.9 6082.0 6086.3 6112.3 6021.5 6084.8 6088.0 6092.4 6092.9 5833.9 5847.6 5975.4 5990.4 6083.8 6136.1 6140.0 6142.1 6144.6 6145.8 5786.0 5811.8 5977.6 5986.9 6009.8 14:15:00 INFO - PROCESS | 2409 | 14:15:00 INFO - PROCESS | 2409 | [#1] ext.html Cycles:180 Average:45301.35 Median:172434.00 stddev:73434.67 (42.6%) stddev-sans-first:73564.61 14:15:00 INFO - PROCESS | 2409 | Values: 785.2 835.8 856.2 922.8 938.7 32247.0 32647.3 35200.0 35223.3 48728.3 47494.8 47696.0 47716.0 47759.0 47882.3 9214.9 9372.0 9374.0 9387.2 9390.0 7526.7 7773.6 10696.5 10862.8 11279.7 11029.7 11044.2 11069.6 11071.3 11078.1 11014.0 11057.7 11060.6 11064.2 11067.6 10736.4 10737.2 10767.8 10801.3 10810.9 43313.0 43619.8 43662.0 43730.1 44026.0 4327.3 4349.1 4383.8 4449.3 4529.4 31238.8 31469.2 31604.7 32066.8 32668.0 4419.2 4493.9 4542.4 4598.9 4611.8 240146.0 240252.0 242546.0 243012.0 243679.0 55658.7 55987.2 56040.7 56257.0 56267.4 56752.4 56882.7 57179.4 57267.4 57392.0 17172.9 17334.7 17361.7 17411.3 17439.2 15375.1 15506.8 15506.8 15516.1 15556.3 12199.2 12527.7 13774.3 14176.0 14211.0 5775.8 5800.3 5814.4 5901.6 5952.3 1034.6 1039.1 1045.8 1048.0 1055.2 29237.3 29244.0 29439.7 29494.7 29677.7 1111.7 1113.5 1119.5 1129.4 1145.1 14690.8 15575.1 15665.3 15728.0 15783.7 312696.0 312940.0 312991.0 313521.0 313617.0 59340.0 59454.0 59541.0 59666.0 59791.7 1512.1 1520.2 1541.4 1560.0 1564.2 28178.9 29008.0 29401.7 29582.9 30039.2 36657.6 36727.4 36819.3 36821.6 36836.8 283828.0 284929.0 285440.0 285765.0 287446.0 30068.8 30582.5 31334.0 32894.7 33365.5 39939.4 40052.2 40291.2 40667.4 43875.6 31775.0 32099.5 32102.5 32315.5 32349.0 31347.0 31986.4 32260.8 32577.2 32623.7 35700.5 35793.6 35924.0 36313.5 36781.3 30459.5 30500.8 30616.7 30718.1 30843.5 24568.4 25701.9 25855.3 25903.5 25980.7 14:15:00 INFO - PROCESS | 2409 | 14:15:00 INFO - PROCESS | 2409 | [#2] jquery.html Cycles:180 Average:39814.04 Median:22507.16 stddev:114420.39 (508.4%) stddev-sans-first:114703.20 14:15:00 INFO - PROCESS | 2409 | Values: 453.7 456.2 470.8 475.9 478.5 20251.3 21758.3 22292.9 22399.9 22530.5 21819.3 22050.4 22483.8 22676.2 22894.9 6738.1 6869.4 7773.3 8053.6 8102.8 7644.3 7855.7 7958.7 8099.2 8100.1 7944.8 8176.0 8176.1 8177.4 8183.4 8011.1 8109.4 8118.3 8125.4 8130.6 7751.5 7971.1 8045.1 8048.6 8052.3 29689.4 29911.3 30061.8 30738.4 31009.4 3278.6 3523.2 3849.5 3915.9 3927.4 18403.0 18756.9 18811.2 19327.1 19644.9 2348.9 2394.7 2404.9 2455.9 2473.3 118697.0 121723.4 122074.0 122230.0 122647.0 21257.5 21430.9 21796.3 21984.5 22465.4 42190.8 42515.4 42547.0 42648.4 42674.8 9990.7 10500.3 10516.6 10624.9 10657.5 9097.5 9135.4 9144.0 9158.1 9378.7 9311.8 9476.8 9591.2 9704.9 9774.9 3911.7 3924.2 4083.8 4123.6 4123.9 968.9 988.9 998.7 998.8 1006.8 21653.8 21785.3 21837.5 22091.7 22109.9 1014.8 1018.4 1024.8 1025.6 1038.2 11744.5 12020.5 12279.9 12315.3 12407.0 661718.0 674589.0 688982.0 716960.0 731862.0 108623.0 109057.5 112582.4 112909.0 113905.0 1274.2 1307.5 1321.6 1326.9 1328.1 18460.3 18496.3 18776.6 18815.0 19177.8 52154.0 52715.7 53877.7 54270.0 54744.5 69215.7 69838.7 70892.5 71101.3 71887.0 8361.5 12112.0 12583.4 12991.2 13124.9 13116.1 13271.5 14203.7 14221.4 14259.4 11822.5 12241.9 12247.1 12283.9 13005.1 11298.1 11370.1 11517.9 11630.4 12000.7 12931.5 13086.1 13123.7 13204.2 13310.3 12072.8 12700.0 12833.3 12885.7 12887.2 11944.4 12266.8 12985.5 12991.5 13394.8 14:15:00 INFO - PROCESS | 2409 | 14:15:00 INFO - PROCESS | 2409 | [#3] mootools.html Cycles:180 Average:7316.61 Median:2303.36 stddev:4755.08 (206.4%) stddev-sans-first:4748.07 14:15:00 INFO - PROCESS | 2409 | Values: 1461.4 1569.7 1619.9 1629.6 1633.6 9614.6 11315.3 11687.6 12109.0 12156.8 11788.6 11872.1 11907.6 11914.9 11922.5 1933.1 1998.2 2389.5 2441.0 2447.5 2294.0 2345.5 2354.4 2355.4 2362.9 2395.1 2395.3 2403.2 2417.8 2425.2 2266.0 2300.0 2302.7 2304.0 2304.4 2205.5 2209.7 2216.3 2249.4 2263.3 11915.8 11991.8 11995.9 12012.2 12016.5 5713.0 5857.4 5868.6 5871.7 5880.0 3282.7 3358.6 3571.7 3603.4 3624.4 1336.4 1352.3 1402.8 1442.8 1460.5 17949.4 17953.4 17956.8 17959.9 17972.6 4647.2 4651.7 4687.8 4789.6 4852.6 14032.4 14039.9 14046.7 14139.5 14271.0 5826.3 5849.2 5957.2 5959.5 5959.9 5254.7 5393.7 5428.5 5430.7 5437.6 4085.4 4140.4 4165.9 4213.4 4222.2 1083.9 1092.1 1105.9 1106.6 1108.0 922.2 931.7 939.3 962.5 1095.5 7134.8 7229.2 7269.8 7272.2 7351.0 1130.6 1138.2 1141.1 1145.4 1164.4 4720.7 4754.0 4760.5 4862.7 4896.7 13869.8 14536.7 15063.9 15130.0 15144.1 14972.4 15010.6 15021.2 15041.9 15049.9 10319.5 10436.6 10483.5 10508.0 10508.5 7272.0 7541.6 7643.7 7664.5 7674.2 13238.1 13296.2 13307.9 13366.5 13372.0 13532.5 13532.9 13536.7 13651.9 13677.0 10067.5 10317.2 10380.9 10385.2 10443.9 11111.8 11128.3 11161.4 11175.1 11314.5 9128.5 9180.4 9190.5 9259.4 9387.4 9115.6 9119.1 9154.9 9203.9 9307.8 8594.4 8722.1 8759.1 8760.1 8770.5 6447.6 6451.5 6484.1 6592.9 6651.8 6885.3 6908.4 6998.4 7026.0 7064.0 14:15:00 INFO - PROCESS | 2409 | 14:15:00 INFO - PROCESS | 2409 | [#4] prototype.html Cycles:180 Average:4813.85 Median:5337.63 stddev:1272.19 (23.8%) stddev-sans-first:1232.30 14:15:00 INFO - PROCESS | 2409 | Values: 421.4 442.7 443.6 447.5 449.3 5190.2 5232.6 5321.5 5328.7 5346.6 5057.3 5192.4 5205.2 5209.6 5215.6 4336.3 4357.0 4358.5 4373.8 4385.6 4339.9 4382.9 4392.8 4404.6 4424.5 4393.9 4397.3 4398.5 4415.7 4443.5 4163.8 4321.5 4362.7 4369.6 4377.5 4122.1 4138.6 4139.5 4144.2 4148.4 5102.5 5110.0 5121.7 5149.6 5151.7 4994.7 5116.9 5119.4 5119.9 5144.6 5396.5 5411.9 5420.3 5464.9 5469.3 4566.9 4621.3 4622.6 4633.8 4705.2 5468.7 5485.4 5569.4 5605.4 5780.0 4701.2 4707.8 4761.4 4830.1 4834.9 4906.6 4907.4 4907.6 4911.4 4921.8 4958.3 5024.9 5069.7 5138.4 5208.5 5452.3 5458.4 5458.8 5495.5 5498.9 1638.4 1655.0 1655.4 1657.4 1676.8 1242.9 1281.5 1285.8 1293.4 1326.7 5426.4 5457.0 5492.8 5543.3 5547.3 5574.9 5721.2 5763.3 5781.0 5819.5 5396.8 5445.0 5446.9 5516.8 5532.9 2903.5 2968.3 3004.2 3006.2 3020.9 5579.8 5723.6 5786.0 5816.9 5843.7 5730.5 5737.9 5765.8 5825.0 5830.7 5788.9 5814.7 5841.3 5842.0 5843.9 5360.4 5632.3 5632.9 5655.4 5657.5 5389.4 5572.4 5576.1 5597.6 5623.8 5709.6 5726.7 5730.8 5808.9 5837.7 4683.5 4687.9 4710.5 4713.3 4718.5 5462.0 5636.7 5687.8 5717.8 5764.7 5459.5 5490.7 5544.5 5547.6 5548.8 5528.2 5545.2 5546.4 5549.6 5574.7 5316.5 5473.9 5478.3 5504.8 5509.9 5474.9 5475.4 5519.3 5558.7 5593.6 5272.7 5411.2 5417.9 5437.6 5464.5 14:15:00 INFO - PROCESS | 2409 | 14:15:00 INFO - PROCESS | 2409 | [#5] yui.html Cycles:180 Average:18001.21 Median:23676.85 stddev:20702.28 (87.4%) stddev-sans-first:20720.36 14:15:00 INFO - PROCESS | 2409 | Values: 866.2 866.7 930.5 952.5 954.5 16861.9 18119.6 18121.7 18128.1 18180.6 13492.0 17374.1 17573.8 17609.1 17659.6 903.2 931.1 934.6 934.8 934.9 920.7 923.9 924.2 924.2 924.4 968.5 974.1 974.1 974.5 974.7 975.3 976.1 976.5 977.5 977.8 712.9 713.2 713.6 714.0 714.3 17403.0 17642.6 17708.6 17782.7 17809.6 17445.8 17732.0 17738.9 18368.6 18641.5 14278.3 14429.9 14448.7 14633.4 14793.9 4287.5 4303.7 4333.2 4393.8 4496.8 46076.0 61885.7 64781.7 68979.0 87012.6 13419.2 13744.0 13853.5 13894.9 14001.6 23264.7 23289.2 23544.0 23808.2 23833.9 9780.1 9791.9 9808.8 10146.6 10178.5 5047.5 5126.2 5162.4 5185.2 5196.8 3809.9 3873.7 3894.6 3910.3 3913.3 642.1 644.6 656.6 663.4 664.2 663.1 675.6 683.2 688.8 694.1 11578.2 11580.4 11753.4 11849.0 11869.2 628.6 640.6 646.8 651.6 652.8 5405.3 5666.0 5699.9 5747.2 5756.8 67598.0 67845.0 80402.0 81773.6 81980.0 44531.1 46941.0 69441.3 73132.3 74280.6 63652.3 64115.7 65467.3 66062.0 66626.3 11874.2 12416.2 12457.4 12633.4 12740.2 41971.8 42234.8 43050.0 43170.1 43617.3 47806.5 48070.3 48212.3 48305.5 49919.8 14818.6 15068.3 15108.8 15132.2 15398.2 16465.5 16473.1 16655.7 16763.2 16795.4 14415.0 14627.5 14657.3 15508.6 16196.9 15281.5 15449.1 15849.4 17653.0 17675.7 15860.5 16537.0 16655.2 17245.9 17255.7 11546.2 11703.4 12043.6 12517.3 12558.9 12974.6 13343.1 13464.9 13914.7 14324.0 14:15:00 INFO - PROCESS | 2409 | -------- Summary: end -------- 14:15:00 INFO - PROCESS | 2409 | 14:15:01 INFO - TEST-INFO | 2409: exit 0 14:15:01 INFO - TEST-OK | dromaeo_css | took 1115382ms 14:15:01 INFO - TEST-START | kraken 14:15:01 INFO - operating with platform_type : linux_ 14:15:01 INFO - Initialising browser for kraken test... 14:15:01 INFO - TEST-INFO | started process 2868 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP7cEQN/profile http://localhost:52471/getInfo.html) 14:15:05 INFO - PROCESS | 2868 | __metrics Screen width/height:1600/1200 14:15:05 INFO - PROCESS | 2868 | colorDepth:24 14:15:05 INFO - PROCESS | 2868 | Browser inner width/height: 1024/697 14:15:05 INFO - PROCESS | 2868 | __metrics 14:15:05 INFO - PROCESS | 2868 | 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" 14:15:05 INFO - PROCESS | 2868 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:15:06 INFO - TEST-INFO | 2868: exit 0 14:15:06 INFO - Browser initialized. 14:15:06 INFO - Running cycle 1/1 for kraken test... 14:15:06 INFO - TEST-INFO | started process 3007 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP7cEQN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:15:18 INFO - PROCESS | 3007 | RSS: Main: 141533184 14:15:18 INFO - PROCESS | 3007 | 14:15:21 INFO - PROCESS | 3007 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52471/tests/kraken/driver.html?testName=audio-beat-detection) 14:15:21 INFO - PROCESS | 3007 | RSS: Main: 183930880 14:15:21 INFO - PROCESS | 3007 | 14:15:24 INFO - PROCESS | 3007 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52471/tests/kraken/driver.html?testName=audio-dft) 14:15:25 INFO - PROCESS | 3007 | RSS: Main: 173584384 14:15:25 INFO - PROCESS | 3007 | 14:15:27 INFO - PROCESS | 3007 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52471/tests/kraken/driver.html?testName=audio-fft) 14:15:28 INFO - PROCESS | 3007 | RSS: Main: 265715712 14:15:28 INFO - PROCESS | 3007 | 14:15:30 INFO - PROCESS | 3007 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52471/tests/kraken/driver.html?testName=audio-oscillator) 14:15:31 INFO - PROCESS | 3007 | RSS: Main: 171847680 14:15:31 INFO - PROCESS | 3007 | 14:15:33 INFO - PROCESS | 3007 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52471/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:15:33 INFO - PROCESS | 3007 | RSS: Main: 174379008 14:15:33 INFO - PROCESS | 3007 | 14:15:36 INFO - PROCESS | 3007 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52471/tests/kraken/driver.html?testName=imaging-darkroom) 14:15:37 INFO - PROCESS | 3007 | RSS: Main: 210096128 14:15:37 INFO - PROCESS | 3007 | 14:15:40 INFO - PROCESS | 3007 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52471/tests/kraken/driver.html?testName=imaging-desaturate) 14:15:41 INFO - PROCESS | 3007 | RSS: Main: 211570688 14:15:41 INFO - PROCESS | 3007 | 14:15:43 INFO - PROCESS | 3007 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52471/tests/kraken/driver.html?testName=json-parse-financial) 14:15:44 INFO - PROCESS | 3007 | RSS: Main: 212148224 14:15:44 INFO - PROCESS | 3007 | 14:15:46 INFO - PROCESS | 3007 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52471/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:15:46 INFO - PROCESS | 3007 | RSS: Main: 174153728 14:15:46 INFO - PROCESS | 3007 | 14:15:48 INFO - PROCESS | 3007 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:15:48 INFO - PROCESS | 3007 | RSS: Main: 181207040 14:15:48 INFO - PROCESS | 3007 | 14:15:50 INFO - PROCESS | 3007 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:15:51 INFO - PROCESS | 3007 | RSS: Main: 191066112 14:15:51 INFO - PROCESS | 3007 | 14:15:53 INFO - PROCESS | 3007 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:15:54 INFO - PROCESS | 3007 | RSS: Main: 200155136 14:15:54 INFO - PROCESS | 3007 | 14:15:57 INFO - PROCESS | 3007 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:15:58 INFO - PROCESS | 3007 | RSS: Main: 196300800 14:15:58 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | Cycle 1(1): loaded http://localhost:52471/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52471/tests/kraken/driver.html?testName=ai-astar) 14:15:59 INFO - PROCESS | 3007 | RSS: Main: 246640640 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | __start_tp_report 14:15:59 INFO - PROCESS | 3007 | _x_x_mozilla_page_load 14:15:59 INFO - PROCESS | 3007 | _x_x_mozilla_page_load_details 14:15:59 INFO - PROCESS | 3007 | |i|pagename|runs| 14:15:59 INFO - PROCESS | 3007 | |0;ai-astar;103;113;113;112;112;115;111;112;114;112 14:15:59 INFO - PROCESS | 3007 | |1;audio-beat-detection;149;115;114;155;119;116;117;114;138;115 14:15:59 INFO - PROCESS | 3007 | |2;audio-dft;160;161;161;161;162;161;163;160;159;161 14:15:59 INFO - PROCESS | 3007 | |3;audio-fft;75;76;75;105;75;75;76;76;90;77 14:15:59 INFO - PROCESS | 3007 | |4;audio-oscillator;81;81;99;81;83;82;92;82;82;99 14:15:59 INFO - PROCESS | 3007 | |5;imaging-gaussian-blur;113;113;113;114;112;113;113;113;113;112 14:15:59 INFO - PROCESS | 3007 | |6;imaging-darkroom;146;146;147;147;147;146;147;147;148;146 14:15:59 INFO - PROCESS | 3007 | |7;imaging-desaturate;94;93;94;93;93;94;94;93;93;94 14:15:59 INFO - PROCESS | 3007 | |8;json-parse-financial;54;56;56;54;53;55;54;56;54;54 14:15:59 INFO - PROCESS | 3007 | |9;json-stringify-tinderbox;53;53;54;54;56;53;53;54;55;54 14:15:59 INFO - PROCESS | 3007 | |10;stanford-crypto-aes;75;84;75;78;83;77;83;77;74;77 14:15:59 INFO - PROCESS | 3007 | |11;stanford-crypto-ccm;129;120;120;121;119;126;126;127;164;120 14:15:59 INFO - PROCESS | 3007 | |12;stanford-crypto-pbkdf2;166;165;162;160;165;164;159;161;157;159 14:15:59 INFO - PROCESS | 3007 | |13;stanford-crypto-sha256-iterative;60;60;62;59;60;62;61;61;60;59 14:15:59 INFO - PROCESS | 3007 | __end_tp_report 14:15:59 INFO - PROCESS | 3007 | __start_cc_report 14:15:59 INFO - PROCESS | 3007 | _x_x_mozilla_cycle_collect,1656 14:15:59 INFO - PROCESS | 3007 | __end_cc_report 14:15:59 INFO - PROCESS | 3007 | __startTimestamp1494537359890__endTimestamp 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | ------- Summary: start ------- 14:15:59 INFO - PROCESS | 3007 | Number of tests: 14 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.50 stddev:3.27 (2.9%) stddev-sans-first:1.22 14:15:59 INFO - PROCESS | 3007 | Values: 103.0 113.0 113.0 112.0 112.0 115.0 111.0 112.0 114.0 112.0 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | [#1] audio-beat-detection Cycles:10 Average:125.20 Median:118.00 stddev:15.87 (13.5%) stddev-sans-first:14.31 14:15:59 INFO - PROCESS | 3007 | Values: 149.0 115.0 114.0 155.0 119.0 116.0 117.0 114.0 138.0 115.0 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.12 14:15:59 INFO - PROCESS | 3007 | Values: 160.0 161.0 161.0 161.0 162.0 161.0 163.0 160.0 159.0 161.0 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | [#3] audio-fft Cycles:10 Average:80.00 Median:76.00 stddev:9.90 (13.0%) stddev-sans-first:10.33 14:15:59 INFO - PROCESS | 3007 | Values: 75.0 76.0 75.0 105.0 75.0 75.0 76.0 76.0 90.0 77.0 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | [#4] audio-oscillator Cycles:10 Average:86.20 Median:82.50 stddev:7.50 (9.1%) stddev-sans-first:7.71 14:15:59 INFO - PROCESS | 3007 | Values: 81.0 81.0 99.0 81.0 83.0 82.0 92.0 82.0 82.0 99.0 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 14:15:59 INFO - PROCESS | 3007 | Values: 113.0 113.0 113.0 114.0 112.0 113.0 113.0 113.0 113.0 112.0 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 14:15:59 INFO - PROCESS | 3007 | Values: 146.0 146.0 147.0 147.0 147.0 146.0 147.0 147.0 148.0 146.0 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | [#7] imaging-desaturate Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 14:15:59 INFO - PROCESS | 3007 | Values: 94.0 93.0 94.0 93.0 93.0 94.0 94.0 93.0 93.0 94.0 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:54.50 stddev:1.07 (2.0%) stddev-sans-first:1.12 14:15:59 INFO - PROCESS | 3007 | Values: 54.0 56.0 56.0 54.0 53.0 55.0 54.0 56.0 54.0 54.0 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | [#9] json-stringify-tinderbox Cycles:10 Average:53.90 Median:54.00 stddev:0.99 (1.8%) stddev-sans-first:1.00 14:15:59 INFO - PROCESS | 3007 | Values: 53.0 53.0 54.0 54.0 56.0 53.0 53.0 54.0 55.0 54.0 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:77.50 stddev:3.68 (4.8%) stddev-sans-first:3.71 14:15:59 INFO - PROCESS | 3007 | Values: 75.0 84.0 75.0 78.0 83.0 77.0 83.0 77.0 74.0 77.0 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | [#11] stanford-crypto-ccm Cycles:10 Average:127.20 Median:126.00 stddev:13.42 (10.7%) stddev-sans-first:14.22 14:15:59 INFO - PROCESS | 3007 | Values: 129.0 120.0 120.0 121.0 119.0 126.0 126.0 127.0 164.0 120.0 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.80 Median:163.00 stddev:3.08 (1.9%) stddev-sans-first:2.87 14:15:59 INFO - PROCESS | 3007 | Values: 166.0 165.0 162.0 160.0 165.0 164.0 159.0 161.0 157.0 159.0 14:15:59 INFO - PROCESS | 3007 | 14:15:59 INFO - PROCESS | 3007 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.40 Median:60.50 stddev:1.07 (1.8%) stddev-sans-first:1.13 14:15:59 INFO - PROCESS | 3007 | Values: 60.0 60.0 62.0 59.0 60.0 62.0 61.0 61.0 60.0 59.0 14:15:59 INFO - PROCESS | 3007 | -------- Summary: end -------- 14:15:59 INFO - PROCESS | 3007 | 14:16:00 INFO - TEST-INFO | 3007: exit 0 14:16:00 INFO - TEST-OK | kraken | took 59638ms 14:16:00 INFO - SUITE-END | took 1175s 14:16:01 INFO - Completed test suite (00:19:35) 14:16:01 INFO - javascript benchmark 14:16:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7998.054928601373, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3406.659334066593, 3450.309938012398, 3456.706179974702, 3462.8614855405785, 3494.4758286257056, 5748.741306641201, 5792.739326730297, 5796.188533605298, 5809.941900580993, 5816.447437493442, 5783.21926539917, 5836.037053886103, 5854.443827836349, 5856.5021973132225, 5863.736131874065, 5205.115130427836, 5210.869728256805, 5218.791248350061, 5260.316158899357, 5292.550133238679, 5071.873203169932, 5104.591632669396, 5139.743012849362, 5297.947020529803, 5310.097283461801, 5252.080885844981, 5256.526912577867, 5262.0, 5285.920711189336, 5287.101192797234, 5271.288376069239, 5275.709835959021, 5276.419593844674, 5278.181881808307, 5299.788008479656, 5007.5993920486635, 5091.287219789232, 5182.740862956837, 5194.935038317136, 5247.02929957959, 5612.214289999403, 5621.7189140542805, 5674.120511320752, 5695.174199741055, 5696.8006119785605, 6104.816855494342, 6138.570300078996, 6149.538784591174, 6161.229846269216, 6162.952298109288, 6057.30341010785, 6170.568060235785, 6192.659403732793, 6199.690015499207, 6203.906941395882, 5699.629524080921, 5723.685197314146, 5732.082866741343, 5810.593258471951, 5846.883062338729, 6307.274663413675, 6316.021016742412, 6320.178376810985, 6326.114343991845, 6327.873442531123, 6078.848028799315, 6131.540134489842, 6132.601380910227, 6142.232220972379, 6143.0, 6027.6986150693165, 6072.908906366408, 6108.6759897029115, 6139.001538246403, 6220.844478888064, 6191.473724733448, 6237.688115594202, 6239.875202495925, 6241.937580624136, 6244.594101383395, 5976.551758618121, 6001.2498437695285, 6012.128241404972, 6100.359462256488, 6128.540359472968, 4656.278276867091, 4718.127146477913, 4737.6683632146105, 4745.596624286905, 4746.264329029006, 4206.095689426788, 4225.323948168279, 4239.003834098984, 4243.045314804144, 4254.31930891056, 5864.296284445893, 5901.970490147522, 5973.402659733992, 5975.820725378158, 5988.550858685616, 6155.692215389213, 6159.045347971071, 6163.907541386883, 6164.506839452833, 6182.876342473125, 6057.939420605738, 6067.1202675611785, 6078.331383547807, 6082.939170608237, 6089.54328425361, 5290.550303224269, 5334.199870019528, 5338.145896656516, 5340.679559226459, 5346.224797404355, 6160.8767824643255, 6208.348123447154, 6432.71052802625, 6506.41442270198, 6515.54391192612, 6140.565599444434, 6173.81478555644, 6273.654948977667, 6281.183446151971, 6284.654344011124, 6429.324920883427, 6454.741810327534, 6475.158229430144, 6483.675816209076, 6484.059811327425, 5981.551383646157, 6163.3836616338, 6166.075088736726, 6169.598976066721, 6174.25908890936, 6134.191681013941, 6135.07955838608, 6139.079138129317, 6234.0, 6248.312685604671, 6080.209572755513, 6080.908786368296, 6081.969590152198, 6086.272008928084, 6112.297085835008, 6021.518278537794, 6084.787032453843, 6087.969560152171, 6092.390760923872, 6092.908606370819, 5833.854153646193, 5847.619904706181, 5975.43233392827, 5990.430909063632, 6083.847903802263, 6136.103574230307, 6140.048292514667, 6142.08251080733, 6144.648177400964, 6145.784897528565, 5786.0, 5811.79658711943, 5977.581569290108, 5986.910196347146, 6009.819705408845], "value": 5707.857159666662, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [785.1598789295456, 835.8202986357933, 856.2293935458086, 922.7600823785818, 938.7371535969922, 32247.032589022307, 32647.347053058955, 35200.0, 35223.29553408933, 48728.2690759638, 47494.76252618732, 47696.0, 47716.0, 47759.0, 47882.28176577354, 9214.861777073349, 9372.0, 9374.0, 9387.155156035955, 9390.0, 7526.698932042739, 7773.611319434034, 10696.51865666047, 10862.782744345108, 11279.661610151708, 11029.724256893602, 11044.226904116715, 11069.557217711283, 11071.280366776175, 11078.058365038982, 11014.008739213468, 11057.668269951873, 11060.612878549293, 11064.17018723599, 11067.557297708041, 10736.355818650905, 10737.24839261252, 10767.78464430707, 10801.297915635458, 10810.945945270301, 43313.0, 43619.78190109061, 43662.0, 43730.12539749219, 44026.0, 4327.32926396409, 4349.13017396522, 4383.780810959439, 4449.26587113126, 4529.411176547028, 31238.843805781053, 31469.213269668213, 31604.683953160635, 32066.839665801755, 32668.0, 4419.204543182226, 4493.910121797546, 4542.38677778499, 4598.9080218395775, 4611.7694115294435, 240146.0, 240252.0, 242545.99999999822, 243012.0, 243679.0, 55658.72170639121, 55987.16019259714, 56040.71979640157, 56257.0, 56267.43732562704, 56752.432475674716, 56882.71558642263, 57179.42820571741, 57267.42732572704, 57392.0, 17172.91413542949, 17334.65330693379, 17361.7395739064, 17411.3035478582, 17439.21523531444, 15375.077495350315, 15506.844931550766, 15506.844931550766, 15516.146611936452, 15556.299966501534, 12199.207051541622, 12527.686807829878, 13774.311284435738, 14176.0, 14211.0, 5775.826725198251, 5800.303963524404, 5814.418558144152, 5901.645901245939, 5952.345242023374, 1034.6183911136332, 1039.1323245090282, 1045.8431235314763, 1047.9633212837514, 1055.208593554834, 29237.26906827304, 29244.0, 29439.705602944126, 29494.705052949626, 29677.703222967924, 1111.6720567432626, 1113.4711012269108, 1119.5018216893568, 1129.4352823588206, 1145.1068166829887, 14690.779638305434, 15575.143367114692, 15665.295061722021, 15728.0, 15783.684326313409, 312696.0, 312940.0, 312991.0, 313521.0, 313617.0, 59340.0, 59454.0, 59541.0, 59666.0, 59791.70104149451, 1512.138081293652, 1520.2246854104487, 1541.4142625802124, 1560.001598976656, 1564.1866229560792, 28178.85910570434, 29008.0, 29401.705982939897, 29582.852085739432, 30039.249018774706, 36657.63342366542, 36727.449088263165, 36819.263614728094, 36821.63178368182, 36836.81581592075, 283828.0, 284929.0, 285440.0, 285765.0, 287446.0, 30068.849655751583, 30582.541261881535, 31334.0, 32894.67105328916, 33365.499517506774, 39939.40090898697, 40052.1989560213, 40291.1941761169, 40667.3899891496, 43875.561244388424, 31775.0, 32099.518507221943, 32102.518462223554, 32315.51526727054, 32349.0, 31347.0, 31986.36027279488, 32260.83869580637, 32577.18557036093, 32623.673763262064, 35700.46449303314, 35793.642063580075, 35924.0, 36313.45529817002, 36781.26437471289, 30459.543106852972, 30500.84749576238, 30616.693833062276, 30718.078457646305, 30843.537346939363, 24568.385790355384, 25701.87149064243, 25855.324802807943, 25903.481930361664, 25980.740192597834], "value": 19159.009638343883, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.7345652793116, 456.24946962247094, 470.75285475125554, 475.8524857294239, 478.5334299058418, 20251.291204807836, 21758.34724958252, 22292.88853555734, 22399.888000559993, 22530.549389012253, 21819.345419637397, 22050.44873878154, 22483.775162248374, 22676.206332778354, 22894.88552557235, 6738.056672065927, 6869.416099631538, 7773.261540153676, 8053.6375863086305, 8102.837943241161, 7644.312011918926, 7855.7250496232355, 7958.681652733883, 8099.1900809919125, 8100.068492123422, 7944.801379965518, 8176.0, 8176.1415051419635, 8177.386695997824, 8183.386246031541, 8011.078725946503, 8109.4323397362205, 8118.309943654767, 8125.431219814615, 8130.55281959492, 7751.457397982143, 7971.123176450586, 8045.074816396131, 8048.557329346883, 8052.2752952234, 29689.40621187586, 29911.252218694488, 30061.849690751624, 30738.38523229523, 31009.37981240385, 3278.5901762279714, 3523.154442933703, 3849.480320156799, 3915.9412608810608, 3927.410888366739, 18403.0, 18756.90621546897, 18811.247550097978, 19327.130279137473, 19644.90177549103, 2348.8825558721996, 2394.652775347582, 2404.8797560121925, 2455.8772061396858, 2473.3322003059075, 118697.0, 121723.3913830434, 122074.0, 122230.0, 122647.0, 21257.468563286042, 21430.892845535673, 21796.346109616738, 21984.45038874009, 22465.43836404103, 42190.789046054575, 42515.36226956598, 42547.0, 42648.36027459591, 42674.78662606667, 9990.650327238513, 10500.31747936397, 10516.57933682644, 10624.946875265574, 10657.520411581498, 9097.454152750855, 9135.406198597202, 9144.014790920923, 9158.129977652112, 9378.718638440858, 9311.767205819773, 9476.810463790685, 9591.232701383873, 9704.854427183598, 9774.853377199348, 3911.7066220033043, 3924.234774219057, 4083.7958102094776, 4123.60825721556, 4123.9175216495505, 968.8788901387327, 988.8566157907063, 998.6604554451376, 998.7952469743685, 1006.7835415385728, 21653.78346216549, 21785.346439606838, 21837.45406364822, 22091.66862497064, 22109.889450552964, 1014.8274793285159, 1018.3940555369692, 1024.8206563851297, 1025.6410256410197, 1038.2368958815264, 11744.530218791324, 12020.519179232733, 12279.938600306941, 12315.261084334968, 12407.0, 661718.0, 674589.0, 688982.0, 716960.0, 731862.0, 108623.0, 109057.45471272593, 112582.43708781403, 112909.0, 113905.0, 1274.1526884621624, 1307.5358247821887, 1321.5506727712432, 1326.8540460549332, 1328.130074801007, 18460.26158953627, 18496.260149593865, 18776.624467510846, 18815.0, 19177.808221917603, 52154.0, 52715.73642131765, 53877.73061134826, 54270.0, 54744.45255547394, 69215.65392173007, 69838.65080674565, 70892.5187618431, 71101.28898710947, 71887.0, 8361.498310101413, 12112.0, 12583.433745481658, 12991.220526768424, 13124.934375328063, 13116.147450416074, 13271.535496257584, 14203.715925681221, 14221.431142754174, 14259.358328875433, 11822.527098915947, 12241.938790305992, 12247.142700010914, 12283.877161228273, 13005.106847141878, 11298.09615230796, 11370.147238956946, 11517.94241028823, 11630.360330181922, 12000.699982500508, 12931.482740690642, 13086.08397412172, 13123.671908202372, 13204.207747535178, 13310.33448327599, 12072.818907716566, 12700.0, 12833.294168820432, 12885.677858053623, 12887.226766394046, 11944.402779861146, 12266.75466490683, 12985.545505907248, 12991.48034078664, 13394.799078014032], "value": 12246.992357626816, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1461.3789139615674, 1569.6703692224633, 1619.8623117035058, 1629.6088938654723, 1633.599768056826, 9614.567344469498, 11315.264507806996, 11687.5909343173, 12109.0, 12156.756864862697, 11788.587399441021, 11872.050235981122, 11907.642770716871, 11914.880851191485, 11922.523099076048, 1933.1300914588421, 1998.19073275323, 2389.545986262613, 2440.9511809763794, 2447.547203767309, 2293.9655905161435, 2345.542619189259, 2354.3878591566145, 2355.3522781235124, 2362.917297894574, 2395.1257790906366, 2395.3412811476896, 2403.158894386968, 2417.782399584037, 2425.163318655061, 2265.9546809063727, 2299.9770002300015, 2302.6776251324827, 2304.043821813945, 2304.37781798915, 2205.514786746913, 2209.712737344135, 2216.2907869481774, 2249.3926639807323, 2263.287064574654, 11915.821262681067, 11991.820122698167, 11995.94002029993, 12012.219205751686, 12016.519339226421, 5712.971435142797, 5857.355690874, 5868.559858010623, 5871.735771890232, 5879.970600146972, 3282.6553211912883, 3358.630550639452, 3571.714262858965, 3603.423452247654, 3624.4019736743408, 1336.4386957477882, 1352.2900477249425, 1402.754517959353, 1442.8124343835234, 1460.5472303585923, 17949.371771987917, 17953.371631992817, 17956.820431795644, 17959.910200448914, 17972.640547189112, 4647.233206520954, 4651.744154071491, 4687.789049492712, 4789.592884604778, 4852.636052296092, 14032.36854341557, 14039.929800350932, 14046.719065618834, 14139.50511732105, 14271.001029927806, 5826.300843898759, 5849.181114643953, 5957.225560677084, 5959.523238140939, 5959.910601340984, 5254.710990895461, 5393.730313484389, 5428.4842939920645, 5430.70131142791, 5437.646552974044, 4085.4261815056325, 4140.37894315855, 4165.881244109282, 4213.410122582841, 4222.239996800606, 1083.9458027098613, 1092.1317552545745, 1105.8949399807007, 1106.5684383090602, 1107.9612213572648, 922.2437601167113, 931.7111695374456, 939.33307351781, 962.4610218277787, 1095.5070218401752, 7134.785956421316, 7229.168645605718, 7269.818254543573, 7272.163701174432, 7351.0, 1130.6099395708468, 1138.2089447833841, 1141.0529260713588, 1145.381493993251, 1164.4352489042808, 4720.669553131248, 4753.976230118897, 4760.45254795696, 4862.708237505761, 4896.730679812645, 13869.791953120712, 14536.709265814625, 15063.924680376527, 15130.016548924285, 15144.091354518765, 14972.401103955719, 15010.62473438151, 15021.248937553297, 15041.924790376197, 15049.924750376396, 10319.535620897077, 10436.634717784993, 10483.47582620866, 10508.0, 10508.527116279785, 7272.0, 7541.585212813348, 7643.694252230071, 7664.501807382502, 7674.15584285717, 13238.073334866853, 13296.202227866359, 13307.933460333024, 13366.532171373956, 13372.0, 13532.458701652218, 13532.86467135316, 13536.661583460493, 13651.863481365059, 13677.042607017413, 10067.496625168566, 10317.17462603005, 10380.948095259777, 10385.221108416748, 10443.94778026105, 11111.83332250033, 11128.332300062022, 11161.441927903736, 11175.10599152082, 11314.490847912028, 9128.497932613504, 9180.357374983687, 9190.49452280131, 9259.35184537103, 9387.436753794795, 9115.589798458952, 9119.133682300171, 9154.862677059717, 9203.907960920305, 9307.76730581741, 8594.441361311494, 8722.127787221227, 8759.080296568769, 8760.12398760119, 8770.473771573726, 6447.6131432114225, 6451.48388128958, 6484.092227088306, 6592.90110648331, 6651.767188148391, 6885.311468853075, 6908.447324214151, 6998.405135563534, 7026.0, 7064.0], "value": 5479.282427416788, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.4415898933913, 442.71444918027873, 443.55201246740785, 447.5412701980472, 449.27666457004227, 5190.2474141249595, 5232.555232805212, 5321.494458026491, 5328.653637513564, 5346.599005074616, 5057.291979122926, 5192.350956130484, 5205.16717325228, 5209.609279304058, 5215.608829337796, 4336.28451305535, 4356.978215108936, 4358.455193100863, 4373.759443230621, 4385.583369579885, 4339.934900976488, 4382.8904277393, 4392.824287028515, 4404.555471713198, 4424.491183513905, 4393.890152746175, 4397.296432570789, 4398.472183338004, 4415.668824838134, 4443.4892136673525, 4163.812628431712, 4321.546237645033, 4362.716423432498, 4369.601727447218, 4377.518472967971, 4122.134351786129, 4138.6482149017365, 4139.461869956886, 4144.233316836395, 4148.419221309019, 5102.515261050195, 5110.029094472038, 5121.718305493197, 5149.588032957355, 5151.665141765773, 4994.650374473788, 5116.948830511685, 5119.436861945183, 5119.87200319995, 5144.61415393847, 5396.514313711785, 5411.945880541183, 5420.349558053059, 5464.863378415532, 5469.339233892902, 4566.862994110182, 4621.306803979396, 4622.560856718606, 4633.7683115844075, 4705.223638099709, 5468.72656367184, 5485.396606373336, 5569.359523654751, 5605.439456054362, 5779.971100144515, 4701.200795864711, 4707.7881495332795, 4761.428628564595, 4830.053667262967, 4834.8791280218275, 4906.552566992675, 4907.411110666742, 4907.552272079745, 4911.361523001987, 4921.753912304371, 4958.256261560723, 5024.869404383984, 5069.721165335942, 5138.3577052868395, 5208.5312321891215, 5452.291202143696, 5458.372287187024, 5458.8263523342675, 5495.482890949796, 5498.917516237259, 1638.3528506239968, 1654.973916171957, 1655.4371513685405, 1657.4281872753882, 1676.7652528646, 1242.9062425065883, 1281.5130250504749, 1285.832841730569, 1293.379177994568, 1326.7412854493475, 5426.406915597568, 5456.972715136399, 5492.752826122834, 5543.25166102571, 5547.278853749068, 5574.8815450129505, 5721.197822685841, 5763.337216220105, 5780.976658169607, 5819.5344372450945, 5396.811111611075, 5445.0, 5446.863828404322, 5516.806911758069, 5532.917006244909, 2903.462859371023, 2968.302448924501, 3004.2038859702197, 3006.2033561106323, 3020.894268700585, 5579.832605021856, 5723.5707321950185, 5785.971070144707, 5816.88366232673, 5843.737031833578, 5730.541556675456, 5737.913931291034, 5765.827025189251, 5825.0, 5830.650160990354, 5788.913166302509, 5814.680192589363, 5841.269841269841, 5841.977653910549, 5843.8539036522725, 5360.383555891122, 5632.310836402163, 5632.887342253214, 5655.388214358888, 5657.548895174023, 5389.407165211902, 5572.414896435978, 5576.104124597657, 5597.580181486486, 5623.831285061455, 5709.571782116275, 5726.713664316684, 5730.793644446834, 5808.912866307093, 5837.708114594338, 4683.508231635766, 4687.890449297762, 4710.528947105262, 4713.340132381538, 4718.457377401643, 5461.972690136524, 5636.718164091861, 5687.800926967537, 5717.771289148387, 5764.654120752768, 5459.508644222039, 5490.670559766427, 5544.528715059104, 5547.618809754532, 5548.750306236148, 5528.226048353154, 5545.195946587803, 5546.417626149197, 5549.612596850788, 5574.721263936868, 5316.548093412103, 5473.890522189614, 5478.260434841405, 5504.779808807602, 5509.917351239655, 5474.8905021898545, 5475.370332411823, 5519.2824932758485, 5558.749856256559, 5593.580481463825, 5272.7100009499845, 5411.211078195746, 5417.945820541744, 5437.564994800327, 5464.480874316933], "value": 4449.539501791784, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [866.1944391715709, 866.6836604639309, 930.5347326336831, 952.476138124032, 954.451190565425, 16861.915690421545, 18119.637607247863, 18121.728174077398, 18128.093595320246, 18180.63638727226, 13492.0, 17374.131293435308, 17573.824261757378, 17609.11954402281, 17659.646807063884, 903.2232280239006, 931.0875342164649, 934.6261495401826, 934.8177105464437, 934.9439033657968, 920.7191806499015, 923.9353245272833, 924.191332583989, 924.2051835421565, 924.3529529329496, 968.4721826604517, 974.1086905481511, 974.1232890398627, 974.4786539201529, 974.7075877236836, 975.2831668723483, 976.0873583199696, 976.5166242709869, 977.49170431376, 977.7555611097225, 712.9180144283422, 713.1905287498674, 713.5718568858695, 713.9964300178466, 714.2999860137043, 17403.0, 17642.558936026573, 17708.557286067822, 17782.733259001125, 17809.554761131076, 17445.825541744547, 17732.0, 17738.91130544339, 18368.63262734738, 18641.53396165093, 14278.286085695778, 14429.855701442957, 14448.711025779425, 14633.41466341345, 14793.852061479354, 4287.528371879091, 4303.698741088125, 4333.198358303724, 4393.802278897425, 4496.775161241958, 46076.0, 61885.690571547304, 64781.67609161971, 68979.0, 87012.56493717554, 13419.194848308938, 13744.0, 13853.515126970507, 13894.861051389558, 14001.579952601438, 23264.65103023456, 23289.184878529508, 23544.0, 23808.16671416492, 23833.880830595735, 9780.119789219005, 9791.853122203172, 9808.803823923625, 10146.644867429604, 10178.541965611565, 5047.545720885065, 5126.179811230185, 5162.406323272871, 5185.248139019821, 5196.830713089525, 3809.942850857239, 3873.65137137659, 3894.571597124314, 3910.2961466936226, 3913.3151698452657, 642.0754114075716, 644.635780783865, 656.6125985668479, 663.4062514049898, 664.1830548425464, 663.0948754949555, 675.6317806795308, 683.2381894188044, 688.7933619914106, 694.1496666583496, 11578.247413918069, 11580.420978951019, 11753.353565553976, 11849.0, 11869.169158158846, 628.6416742456755, 640.5708175522411, 646.8447572582546, 651.5699638238834, 652.8139480248201, 5405.297311349578, 5666.0, 5699.857503562444, 5747.224124743107, 5756.769729210869, 67598.0, 67845.0, 80402.0, 81773.59113204396, 81980.0, 44531.10937781226, 46941.0, 69441.3055869445, 73132.26867731255, 74280.62859685667, 63652.31475232847, 64115.679421602596, 65467.345326547074, 66062.0, 66626.33373666299, 11874.238702849305, 12416.192947458387, 12457.377131143225, 12633.431495582905, 12740.235585864877, 41971.790141049096, 42234.78882605567, 43050.0, 43170.13659726725, 43617.345739813296, 47806.5219347816, 48070.27894581654, 48212.276815847086, 48305.5169448301, 49919.75040124776, 14818.555443336718, 15068.321925513597, 15108.773368399703, 15132.167730774912, 15398.230088495306, 16465.506034818976, 16473.093979831225, 16655.666886661955, 16763.1618419081, 16795.41216057432, 14415.0, 14627.488037918622, 14657.267136643339, 15508.591900836966, 16196.947198432536, 15281.541553753405, 15449.073055616698, 15849.366025359317, 17653.0, 17675.734863977308, 15860.52418427449, 16537.0, 16655.16724163811, 17245.913770431067, 17255.654886902445, 11546.249493782878, 11703.414829258674, 12043.638690839289, 12517.311547864954, 12558.874411256136, 12974.610761677164, 13343.132696374705, 13464.865351346753, 13914.721705566035, 14324.0], "value": 8015.903704296149, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1453.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 112.0, 112.0, 115.0, 111.0, 112.0, 114.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 114.0, 155.0, 119.0, 116.0, 117.0, 114.0, 138.0, 115.0], "value": 125.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 161.0, 162.0, 161.0, 163.0, 160.0, 159.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 105.0, 75.0, 75.0, 76.0, 76.0, 90.0, 77.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 99.0, 81.0, 83.0, 82.0, 92.0, 82.0, 82.0, 99.0], "value": 86.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 114.0, 112.0, 113.0, 113.0, 113.0, 113.0, 112.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0, 147.0, 148.0, 146.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 93.0, 94.0, 94.0, 93.0, 93.0, 94.0], "value": 93.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 56.0, 54.0, 53.0, 55.0, 54.0, 56.0, 54.0, 54.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 54.0, 56.0, 53.0, 53.0, 54.0, 55.0, 54.0], "value": 53.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 84.0, 75.0, 78.0, 83.0, 77.0, 83.0, 77.0, 74.0, 77.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 120.0, 120.0, 121.0, 119.0, 126.0, 126.0, 127.0, 164.0, 120.0], "value": 127.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 162.0, 160.0, 165.0, 164.0, 159.0, 161.0, 157.0, 159.0], "value": 161.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 62.0, 59.0, 60.0, 62.0, 61.0, 61.0, 60.0, 59.0], "value": 60.4, "unit": "score"}]}]} 14:16:01 INFO - Return code: 0 14:16:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:16:01 INFO - # TBPL SUCCESS # 14:16:01 INFO - Running post-action listener: _resource_record_post_action 14:16:01 INFO - [mozharness: 2017-05-11 21:16:01.198183Z] Finished run-tests step (success) 14:16:01 INFO - Running post-run listener: _resource_record_post_run 14:16:01 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 12288; Write bytes: 323403776; Read time: 296; Write time: 229512 14:16:01 INFO - TinderboxPrint: CPU usage
13.1% 14:16:01 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 296 14:16:01 INFO - TinderboxPrint: I/O write bytes / time
323,403,776 / 229,512 14:16:01 INFO - TinderboxPrint: CPU idle
8,246.5 (86.9%) 14:16:01 INFO - TinderboxPrint: CPU user
1,200.0 (12.6%) 14:16:01 INFO - TinderboxPrint: Swap in / out
0 / 0 14:16:01 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 471040; Read time: 0; Write time: 44 14:16:01 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 256876544; Read time: 68; Write time: 183872 14:16:01 INFO - Running post-run listener: _upload_blobber_files 14:16:01 INFO - Blob upload gear active. 14:16:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:16:01 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 14:16:01 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'] 14:16:01 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 14:16:02 INFO - (blobuploader) - INFO - Open directory for files ... 14:16:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:16:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:16:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:16:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:16:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:16:03 INFO - (blobuploader) - INFO - Done attempting. 14:16:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:16:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:16:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:16:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:16:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:16:04 INFO - (blobuploader) - INFO - Done attempting. 14:16:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:16:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:16:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:16:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:16:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:16:08 INFO - (blobuploader) - INFO - Done attempting. 14:16:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:16:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:16:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:16:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:16:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:16:09 INFO - (blobuploader) - INFO - Done attempting. 14:16:09 INFO - (blobuploader) - INFO - Iteration through files over. 14:16:09 INFO - Return code: 0 14:16:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:16:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:16:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7a5cfcb9b1d5bc8107f4a64c0e732edffc138ef091673f00160bf6d702c11ec79c44a2b9126b38eb4f0c461f2b6f0ca0dd59df5a9a41b7c6e0c69364d16b0769", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e9f9d20280d70d667ed1bdf0ed6ba3ce18c882901904e7633268d1f8ea67b94db5149bb90e04a2ae0a18c77dcb10a1410813d61638e82e0c9608bf6eee27ae18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/95e74f68cfb90c5d34be17f12fc3733a119f8c8cecfcabf312087b71827708fb3051d45800514ed82273013b95383c7a883197645b20581b606427d71e0404bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9d09e8b3a35be54b8883ecdd1064403483a270a827b6106adb93daa1fa99961e1475e3f31c2c25db5da9fe364b0fc53ecfaf2362c157680238af1155fea36193"} 14:16:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:16:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:16:09 INFO - Contents: 14:16:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7a5cfcb9b1d5bc8107f4a64c0e732edffc138ef091673f00160bf6d702c11ec79c44a2b9126b38eb4f0c461f2b6f0ca0dd59df5a9a41b7c6e0c69364d16b0769", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e9f9d20280d70d667ed1bdf0ed6ba3ce18c882901904e7633268d1f8ea67b94db5149bb90e04a2ae0a18c77dcb10a1410813d61638e82e0c9608bf6eee27ae18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/95e74f68cfb90c5d34be17f12fc3733a119f8c8cecfcabf312087b71827708fb3051d45800514ed82273013b95383c7a883197645b20581b606427d71e0404bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9d09e8b3a35be54b8883ecdd1064403483a270a827b6106adb93daa1fa99961e1475e3f31c2c25db5da9fe364b0fc53ecfaf2362c157680238af1155fea36193"} 14:16:09 INFO - Running post-run listener: copy_logs_to_upload_dir 14:16:09 INFO - Copying logs to upload dir... 14:16:09 INFO - mkdir: /builds/slave/test/build/upload/logs 14:16:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.331725 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-05-11 14:16:09.320369) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 14:16:09.324102) ========= 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=62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733 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/7a5cfcb9b1d5bc8107f4a64c0e732edffc138ef091673f00160bf6d702c11ec79c44a2b9126b38eb4f0c461f2b6f0ca0dd59df5a9a41b7c6e0c69364d16b0769", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e9f9d20280d70d667ed1bdf0ed6ba3ce18c882901904e7633268d1f8ea67b94db5149bb90e04a2ae0a18c77dcb10a1410813d61638e82e0c9608bf6eee27ae18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/95e74f68cfb90c5d34be17f12fc3733a119f8c8cecfcabf312087b71827708fb3051d45800514ed82273013b95383c7a883197645b20581b606427d71e0404bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9d09e8b3a35be54b8883ecdd1064403483a270a827b6106adb93daa1fa99961e1475e3f31c2c25db5da9fe364b0fc53ecfaf2362c157680238af1155fea36193"} build_url:https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011191 build_url: 'https://queue.taskcluster.net/v1/task/N6Lx78ZrTpyMHP89jrXK4w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7a5cfcb9b1d5bc8107f4a64c0e732edffc138ef091673f00160bf6d702c11ec79c44a2b9126b38eb4f0c461f2b6f0ca0dd59df5a9a41b7c6e0c69364d16b0769", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e9f9d20280d70d667ed1bdf0ed6ba3ce18c882901904e7633268d1f8ea67b94db5149bb90e04a2ae0a18c77dcb10a1410813d61638e82e0c9608bf6eee27ae18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/95e74f68cfb90c5d34be17f12fc3733a119f8c8cecfcabf312087b71827708fb3051d45800514ed82273013b95383c7a883197645b20581b606427d71e0404bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9d09e8b3a35be54b8883ecdd1064403483a270a827b6106adb93daa1fa99961e1475e3f31c2c25db5da9fe364b0fc53ecfaf2362c157680238af1155fea36193"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 14:16:09.349831) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 14:16:09.350641) ========= 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=62c568580acbfdaac3eec7be000001dc-1494536123.935806-1732535733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004435 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 14:16:09.435864) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 14:16:09.436220) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 14:16:09.436560) ========= ========= Total master_lag: 0.17 =========