builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-014 starttime: 1494949054.71 results: success (0) revision: 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 08:37:34.713364) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 08:37:34.713783) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 08:37:34.714058) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 08:37:34.884996) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 08:37:34.885254) ========= 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=31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-16 08:37:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2017-05-16 08:37:35 (222 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117929 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 08:37:35.028565) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 08:37:35.028853) ========= 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=31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020327 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 08:37:35.082912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 08:37:35.083227) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 --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 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 --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=31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-16 08:37:35,650 truncating revision to first 12 chars 2017-05-16 08:37:35,650 Setting DEBUG logging. 2017-05-16 08:37:35,650 attempt 1/10 2017-05-16 08:37:35,650 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/13ff34d82062?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-16 08:37:36,137 unpacking tar archive at: mozilla-beta-13ff34d82062/testing/mozharness/ program finished with exit code 0 elapsedTime=0.896726 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 08:37:35.993052) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 08:37:35.993329) ========= script_repo_revision: 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 08:37:35.996692) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 08:37:35.996966) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 08:37:36.008250) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-05-16 08:37:36.008547) ========= /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=31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:37:36 INFO - MultiFileLogger online at 20170516 08:37:36 in /builds/slave/test 08:37:36 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 08:37:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:37:36 INFO - {'append_to_log': False, 08:37:36 INFO - 'base_work_dir': '/builds/slave/test', 08:37:36 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 08:37:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:37:36 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 08:37:36 INFO - 'buildbot_json_path': 'buildprops.json', 08:37:36 INFO - 'config_files': ('talos/linux_config.py',), 08:37:36 INFO - 'default_actions': ('clobber', 08:37:36 INFO - 'read-buildbot-config', 08:37:36 INFO - 'download-and-extract', 08:37:36 INFO - 'populate-webroot', 08:37:36 INFO - 'create-virtualenv', 08:37:36 INFO - 'install', 08:37:36 INFO - 'run-tests'), 08:37:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:37:36 INFO - 'download_minidump_stackwalk': True, 08:37:36 INFO - 'download_symbols': 'ondemand', 08:37:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:37:36 INFO - 'tooltool.py': '/tools/tooltool.py', 08:37:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:37:36 INFO - '/tools/misc-python/virtualenv.py')}, 08:37:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:37:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:37:36 INFO - 'gecko_profile': False, 08:37:36 INFO - 'gecko_profile_interval': 0, 08:37:36 INFO - 'installer_path': 'installer.exe', 08:37:36 INFO - 'log_level': 'info', 08:37:36 INFO - 'log_name': 'talos', 08:37:36 INFO - 'log_to_console': True, 08:37:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:37:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:37:36 INFO - 'opt_config_files': (), 08:37:36 INFO - 'pip_index': False, 08:37:36 INFO - 'suite': 'dromaeojs', 08:37:36 INFO - 'system_bits': '32', 08:37:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:37:36 INFO - 'title': 'talos-linux64-ix-014', 08:37:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:37:36 INFO - 'use_talos_json': True, 08:37:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:37:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:37:36 INFO - 'work_dir': 'build'} 08:37:36 INFO - [mozharness: 2017-05-16 15:37:36.673773Z] Running clobber step. 08:37:36 INFO - Running pre-action listener: _resource_record_pre_action 08:37:36 INFO - Running main action method: clobber 08:37:36 INFO - rmtree: /builds/slave/test/build 08:37:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:37:37 INFO - Running post-action listener: _resource_record_post_action 08:37:37 INFO - [mozharness: 2017-05-16 15:37:37.677369Z] Finished clobber step (success) 08:37:37 INFO - [mozharness: 2017-05-16 15:37:37.677469Z] Running read-buildbot-config step. 08:37:37 INFO - Running pre-action listener: _resource_record_pre_action 08:37:37 INFO - Running main action method: read_buildbot_config 08:37:37 INFO - Using buildbot properties: 08:37:37 INFO - { 08:37:37 INFO - "project": "", 08:37:37 INFO - "product": "firefox", 08:37:37 INFO - "who": "ffxbld@noreply.mozilla.org", 08:37:37 INFO - "installer_path": "public/build/target.tar.bz2", 08:37:37 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 08:37:37 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 08:37:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 08:37:37 INFO - "stage_platform": "linux64", 08:37:37 INFO - "basedir": "/builds/slave/test", 08:37:37 INFO - "buildnumber": 46, 08:37:37 INFO - "platform": "ubuntu64_hw", 08:37:37 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:37:37 INFO - "slavebuilddir": "test", 08:37:37 INFO - "taskId": "RUqws0kAR66zFUVTSGzDFQ", 08:37:37 INFO - "branch": "mozilla-beta", 08:37:37 INFO - "script_repo_revision": "production", 08:37:37 INFO - "revision": "13ff34d820621cdaa52a9cf2dfd2a66998438bf8", 08:37:37 INFO - "slavename": "talos-linux64-ix-014", 08:37:37 INFO - "repo_path": "releases/mozilla-beta" 08:37:37 INFO - } 08:37:37 INFO - Finding installer, test and symbols from parent task. 08:37:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RUqws0kAR66zFUVTSGzDFQ'}, attempt #1 08:37:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RUqws0kAR66zFUVTSGzDFQ'}, attempt #1 08:37:38 INFO - Task dependencies: WulGc60wQ2esZXSXyvJz-g 08:37:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g'}, attempt #1 08:37:39 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.tar.bz2 08:37:39 INFO - Set test_url: https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/test_packages.json 08:37:39 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.crashreporter-symbols.zip 08:37:39 INFO - Running post-action listener: _resource_record_post_action 08:37:39 INFO - [mozharness: 2017-05-16 15:37:39.033669Z] Finished read-buildbot-config step (success) 08:37:39 INFO - [mozharness: 2017-05-16 15:37:39.033887Z] Running download-and-extract step. 08:37:39 INFO - Running pre-action listener: _resource_record_pre_action 08:37:39 INFO - Running main action method: download_and_extract 08:37:39 INFO - mkdir: /builds/slave/test/build/tests 08:37:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:37:39 INFO - trying https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.test_packages.json 08:37:39 INFO - Downloading https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:37:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:37:39 INFO - Downloaded 986 bytes. 08:37:39 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:37:39 INFO - Using the following test package requirements: 08:37:39 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:37:39 INFO - u'common': [u'target.common.tests.zip'], 08:37:39 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:37:39 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:37:39 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:37:39 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:37:39 INFO - u'mozbase': [u'target.common.tests.zip'], 08:37:39 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:37:39 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:37:39 INFO - u'web-platform': [u'target.common.tests.zip', 08:37:39 INFO - u'target.web-platform.tests.zip'], 08:37:39 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:37:39 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:37:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.common.tests.zip 08:37:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:37:39 INFO - Fetch https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.common.tests.zip into memory 08:37:42 INFO - Expected file size: 59542561 08:37:42 INFO - Obtained file size: 59542561 08:37:52 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:37:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.common.tests.zip 08:37:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:37:52 INFO - Fetch https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.common.tests.zip into memory 08:37:54 INFO - Expected file size: 59542561 08:37:54 INFO - Obtained file size: 59542561 08:38:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.talos.tests.zip 08:38:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:38:04 INFO - Fetch https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.talos.tests.zip into memory 08:38:05 INFO - Expected file size: 14404449 08:38:05 INFO - Obtained file size: 14404449 08:38:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:38:06 INFO - trying https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.tar.bz2 08:38:06 INFO - Downloading https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:38:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:38:09 INFO - Downloaded 59761707 bytes. 08:38:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.tar.bz2 08:38:09 INFO - mkdir: /builds/slave/test/properties 08:38:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:38:09 INFO - Writing to file /builds/slave/test/properties/build_url 08:38:09 INFO - Contents: 08:38:09 INFO - build_url:https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.tar.bz2 08:38:09 INFO - Running post-action listener: _resource_record_post_action 08:38:09 INFO - Running post-action listener: set_extra_try_arguments 08:38:09 INFO - [mozharness: 2017-05-16 15:38:09.423057Z] Finished download-and-extract step (success) 08:38:09 INFO - [mozharness: 2017-05-16 15:38:09.423281Z] Running populate-webroot step. 08:38:09 INFO - Running pre-action listener: _resource_record_pre_action 08:38:09 INFO - Running main action method: populate_webroot 08:38:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:38:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:38:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:38:09 INFO - '--tppagecycles', 08:38:09 INFO - '7'], 08:38:09 INFO - 'tests': ['tsvgm']}}, 08:38:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:38:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:38:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:38:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:38:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:38:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:38:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:38:09 INFO - 'talos_options': ['--disable-e10s'], 08:38:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:38:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:38:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:38:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:38:09 INFO - 'talos_options': ['--disable-e10s'], 08:38:09 INFO - 'tests': ['damp', 'tps']}, 08:38:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:38:09 INFO - 'tests': ['damp', 'tps']}, 08:38:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:38:09 INFO - 'tests': ['dromaeo_dom']}, 08:38:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:38:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:38:09 INFO - 'tests': ['basic_compositor_video']}, 08:38:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:38:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:38:09 INFO - 'tests': ['a11yr', 08:38:09 INFO - 'ts_paint', 08:38:09 INFO - 'tpaint', 08:38:09 INFO - 'sessionrestore', 08:38:09 INFO - 'sessionrestore_no_auto_restore', 08:38:09 INFO - 'tabpaint']}, 08:38:09 INFO - 'other-e10s': {'tests': ['a11yr', 08:38:09 INFO - 'ts_paint', 08:38:09 INFO - 'tpaint', 08:38:09 INFO - 'sessionrestore', 08:38:09 INFO - 'sessionrestore_no_auto_restore', 08:38:09 INFO - 'tabpaint']}, 08:38:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:38:09 INFO - 'ts_paint', 08:38:09 INFO - 'tpaint', 08:38:09 INFO - 'sessionrestore', 08:38:09 INFO - 'sessionrestore_no_auto_restore', 08:38:09 INFO - 'tabpaint']}, 08:38:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:38:09 INFO - 'ts_paint', 08:38:09 INFO - 'tpaint', 08:38:09 INFO - 'sessionrestore', 08:38:09 INFO - 'sessionrestore_no_auto_restore', 08:38:09 INFO - 'tabpaint']}, 08:38:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:38:09 INFO - 'tests': ['a11yr', 08:38:09 INFO - 'ts_paint', 08:38:09 INFO - 'tpaint', 08:38:09 INFO - 'sessionrestore', 08:38:09 INFO - 'sessionrestore_no_auto_restore', 08:38:09 INFO - 'tabpaint']}, 08:38:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:38:09 INFO - 'tests': ['a11yr', 08:38:09 INFO - 'ts_paint', 08:38:09 INFO - 'tpaint', 08:38:09 INFO - 'sessionrestore', 08:38:09 INFO - 'sessionrestore_no_auto_restore', 08:38:09 INFO - 'tabpaint']}, 08:38:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:38:09 INFO - 'tests': ['tsvgx', 08:38:09 INFO - 'tsvgr_opacity', 08:38:09 INFO - 'tart', 08:38:09 INFO - 'tscrollx', 08:38:09 INFO - 'cart', 08:38:09 INFO - 'tsvg_static']}, 08:38:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:38:09 INFO - 'tsvgr_opacity', 08:38:09 INFO - 'tart', 08:38:09 INFO - 'tscrollx', 08:38:09 INFO - 'cart', 08:38:09 INFO - 'tsvg_static']}, 08:38:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:38:09 INFO - 'talos_options': ['--disable-e10s'], 08:38:09 INFO - 'tests': ['tp5o']}, 08:38:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:38:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:38:09 INFO - 'talos_options': ['--disable-e10s', 08:38:09 INFO - '--xperf_path', 08:38:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:38:09 INFO - 'tests': ['tp5n']}, 08:38:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:38:09 INFO - 'talos_options': ['--xperf_path', 08:38:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:38:09 INFO - 'tests': ['tp5n']}}, 08:38:09 INFO - 'talos.zip': {'path': '', 08:38:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:38:09 INFO - Running post-action listener: _resource_record_post_action 08:38:09 INFO - [mozharness: 2017-05-16 15:38:09.439932Z] Finished populate-webroot step (success) 08:38:09 INFO - [mozharness: 2017-05-16 15:38:09.440021Z] Running create-virtualenv step. 08:38:09 INFO - Running pre-action listener: _resource_record_pre_action 08:38:09 INFO - Running main action method: create_virtualenv 08:38:09 INFO - Creating virtualenv /builds/slave/test/build/venv 08:38:09 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 08:38:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:38:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:38:09 INFO - Using real prefix '/usr' 08:38:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:38:10 INFO - Installing distribute.............................................................................................................................................................................................done. 08:38:12 INFO - Installing pip.................done. 08:38:12 INFO - Return code: 0 08:38:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:38:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:38:12 INFO - Reading from file tmpfile_stdout 08:38:12 INFO - Output received: 08:38:12 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) 08:38:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:38:12 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')]} 08:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:12 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')]} 08:38:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:12 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2053a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0400>, '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 0x22b13b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b1840>, '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': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', '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 08:38:12 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 08:38:12 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 08:38:12 INFO - Using env: {'DISPLAY': ':0', 08:38:12 INFO - 'HOME': '/home/cltbld', 08:38:12 INFO - 'LANG': 'en_US.UTF-8', 08:38:12 INFO - 'LANGUAGE': 'en_US:en', 08:38:12 INFO - 'LOGNAME': 'cltbld', 08:38:12 INFO - 'MAIL': '/var/mail/cltbld', 08:38:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:12 INFO - 'MOZ_NO_REMOTE': '1', 08:38:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:12 INFO - 'NO_EM_RESTART': '1', 08:38:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:12 INFO - 'PWD': '/builds/slave/test', 08:38:12 INFO - 'SHELL': '/bin/bash', 08:38:12 INFO - 'SHLVL': '1', 08:38:12 INFO - 'TERM': 'linux', 08:38:12 INFO - 'TMOUT': '86400', 08:38:12 INFO - 'USER': 'cltbld', 08:38:12 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', 08:38:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:12 INFO - '_': '/tools/buildbot/bin/python'} 08:38:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:12 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 08:38:12 INFO - Cleaning up... 08:38:12 INFO - Return code: 0 08:38:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:38:12 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')]} 08:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:12 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')]} 08:38:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:12 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2053a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0400>, '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 0x22b13b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b1840>, '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': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', '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 08:38:12 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 08:38:12 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 08:38:12 INFO - Using env: {'DISPLAY': ':0', 08:38:12 INFO - 'HOME': '/home/cltbld', 08:38:12 INFO - 'LANG': 'en_US.UTF-8', 08:38:12 INFO - 'LANGUAGE': 'en_US:en', 08:38:12 INFO - 'LOGNAME': 'cltbld', 08:38:12 INFO - 'MAIL': '/var/mail/cltbld', 08:38:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:12 INFO - 'MOZ_NO_REMOTE': '1', 08:38:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:12 INFO - 'NO_EM_RESTART': '1', 08:38:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:12 INFO - 'PWD': '/builds/slave/test', 08:38:12 INFO - 'SHELL': '/bin/bash', 08:38:12 INFO - 'SHLVL': '1', 08:38:12 INFO - 'TERM': 'linux', 08:38:12 INFO - 'TMOUT': '86400', 08:38:12 INFO - 'USER': 'cltbld', 08:38:12 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', 08:38:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:12 INFO - '_': '/tools/buildbot/bin/python'} 08:38:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:12 INFO - Downloading/unpacking psutil>=3.1.1 08:38:12 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 08:38:12 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 08:38:12 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 08:38:12 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 08:38:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:38:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:38:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:38:13 INFO - Installing collected packages: psutil 08:38:13 INFO - Running setup.py install for psutil 08:38:13 INFO - building 'psutil._psutil_linux' extension 08:38:13 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 08:38:14 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 08:38:14 INFO - building 'psutil._psutil_posix' extension 08:38:14 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 08:38:14 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 08:38:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:38:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:38:14 INFO - Successfully installed psutil 08:38:14 INFO - Cleaning up... 08:38:14 INFO - Return code: 0 08:38:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:38:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:38:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2053a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0400>, '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 0x22b13b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b1840>, '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': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', '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 08:38:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:38:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:38:14 INFO - Using env: {'DISPLAY': ':0', 08:38:14 INFO - 'HOME': '/home/cltbld', 08:38:14 INFO - 'LANG': 'en_US.UTF-8', 08:38:14 INFO - 'LANGUAGE': 'en_US:en', 08:38:14 INFO - 'LOGNAME': 'cltbld', 08:38:14 INFO - 'MAIL': '/var/mail/cltbld', 08:38:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:14 INFO - 'MOZ_NO_REMOTE': '1', 08:38:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:14 INFO - 'NO_EM_RESTART': '1', 08:38:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:14 INFO - 'PWD': '/builds/slave/test', 08:38:14 INFO - 'SHELL': '/bin/bash', 08:38:14 INFO - 'SHLVL': '1', 08:38:14 INFO - 'TERM': 'linux', 08:38:14 INFO - 'TMOUT': '86400', 08:38:14 INFO - 'USER': 'cltbld', 08:38:14 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', 08:38:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:14 INFO - '_': '/tools/buildbot/bin/python'} 08:38:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:38:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:38:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:38:15 INFO - Installing collected packages: mozsystemmonitor 08:38:15 INFO - Running setup.py install for mozsystemmonitor 08:38:15 INFO - Successfully installed mozsystemmonitor 08:38:15 INFO - Cleaning up... 08:38:15 INFO - Return code: 0 08:38:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:38:15 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')]} 08:38:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:15 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')]} 08:38:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:15 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2053a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0400>, '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 0x22b13b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b1840>, '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': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', '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 08:38:15 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 08:38:15 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 08:38:15 INFO - Using env: {'DISPLAY': ':0', 08:38:15 INFO - 'HOME': '/home/cltbld', 08:38:15 INFO - 'LANG': 'en_US.UTF-8', 08:38:15 INFO - 'LANGUAGE': 'en_US:en', 08:38:15 INFO - 'LOGNAME': 'cltbld', 08:38:15 INFO - 'MAIL': '/var/mail/cltbld', 08:38:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:15 INFO - 'MOZ_NO_REMOTE': '1', 08:38:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:15 INFO - 'NO_EM_RESTART': '1', 08:38:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:15 INFO - 'PWD': '/builds/slave/test', 08:38:15 INFO - 'SHELL': '/bin/bash', 08:38:15 INFO - 'SHLVL': '1', 08:38:15 INFO - 'TERM': 'linux', 08:38:15 INFO - 'TMOUT': '86400', 08:38:15 INFO - 'USER': 'cltbld', 08:38:15 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', 08:38:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:15 INFO - '_': '/tools/buildbot/bin/python'} 08:38:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:16 INFO - Downloading/unpacking jsonschema==2.5.1 08:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:38:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:17 INFO - Downloading functools32-3.2.3-2.tar.gz 08:38:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:38:17 INFO - warning: no files found matching '*.txt' 08:38:17 INFO - no previously-included directories found matching 'build' 08:38:17 INFO - no previously-included directories found matching 'dist' 08:38:17 INFO - no previously-included directories found matching '.git*' 08:38:17 INFO - Installing collected packages: jsonschema, functools32 08:38:17 INFO - Running setup.py install for functools32 08:38:17 INFO - warning: no files found matching '*.txt' 08:38:17 INFO - no previously-included directories found matching 'build' 08:38:17 INFO - no previously-included directories found matching 'dist' 08:38:17 INFO - no previously-included directories found matching '.git*' 08:38:17 INFO - Successfully installed jsonschema functools32 08:38:17 INFO - Cleaning up... 08:38:17 INFO - Return code: 0 08:38:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:38:17 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')]} 08:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:17 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')]} 08:38:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:17 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2053a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0400>, '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 0x22b13b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b1840>, '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': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', '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 08:38:17 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 08:38:17 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 08:38:17 INFO - Using env: {'DISPLAY': ':0', 08:38:17 INFO - 'HOME': '/home/cltbld', 08:38:17 INFO - 'LANG': 'en_US.UTF-8', 08:38:17 INFO - 'LANGUAGE': 'en_US:en', 08:38:17 INFO - 'LOGNAME': 'cltbld', 08:38:17 INFO - 'MAIL': '/var/mail/cltbld', 08:38:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:17 INFO - 'MOZ_NO_REMOTE': '1', 08:38:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:17 INFO - 'NO_EM_RESTART': '1', 08:38:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:17 INFO - 'PWD': '/builds/slave/test', 08:38:17 INFO - 'SHELL': '/bin/bash', 08:38:17 INFO - 'SHLVL': '1', 08:38:17 INFO - 'TERM': 'linux', 08:38:17 INFO - 'TMOUT': '86400', 08:38:17 INFO - 'USER': 'cltbld', 08:38:17 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', 08:38:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:17 INFO - '_': '/tools/buildbot/bin/python'} 08:38:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:38:17 INFO - Cleaning up... 08:38:17 INFO - Return code: 0 08:38:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:38:17 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')]} 08:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:17 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')]} 08:38:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:17 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2053a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0400>, '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 0x22b13b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b1840>, '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': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', '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 08:38:17 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 08:38:17 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 08:38:17 INFO - Using env: {'DISPLAY': ':0', 08:38:17 INFO - 'HOME': '/home/cltbld', 08:38:17 INFO - 'LANG': 'en_US.UTF-8', 08:38:17 INFO - 'LANGUAGE': 'en_US:en', 08:38:17 INFO - 'LOGNAME': 'cltbld', 08:38:17 INFO - 'MAIL': '/var/mail/cltbld', 08:38:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:17 INFO - 'MOZ_NO_REMOTE': '1', 08:38:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:17 INFO - 'NO_EM_RESTART': '1', 08:38:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:17 INFO - 'PWD': '/builds/slave/test', 08:38:17 INFO - 'SHELL': '/bin/bash', 08:38:17 INFO - 'SHLVL': '1', 08:38:17 INFO - 'TERM': 'linux', 08:38:17 INFO - 'TMOUT': '86400', 08:38:17 INFO - 'USER': 'cltbld', 08:38:17 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', 08:38:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:17 INFO - '_': '/tools/buildbot/bin/python'} 08:38:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:17 INFO - Downloading/unpacking blobuploader==1.2.4 08:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:19 INFO - Downloading blobuploader-1.2.4.tar.gz 08:38:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:38:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:38:19 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 08:38:19 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 08:38:19 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 08:38:19 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 08:38:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:38:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:38:19 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 08:38:19 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 08:38:19 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 08:38:19 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 08:38:19 INFO - Downloading docopt-0.6.1.tar.gz 08:38:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:38:19 INFO - Installing collected packages: blobuploader, requests, docopt 08:38:19 INFO - Running setup.py install for blobuploader 08:38:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:38:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:38:19 INFO - Running setup.py install for requests 08:38:20 INFO - Running setup.py install for docopt 08:38:20 INFO - Successfully installed blobuploader requests docopt 08:38:20 INFO - Cleaning up... 08:38:20 INFO - Return code: 0 08:38:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:38:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:38:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:38:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:20 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2053a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0400>, '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 0x22b13b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b1840>, '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': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', '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 08:38:20 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 08:38:20 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 08:38:20 INFO - Using env: {'DISPLAY': ':0', 08:38:20 INFO - 'HOME': '/home/cltbld', 08:38:20 INFO - 'LANG': 'en_US.UTF-8', 08:38:20 INFO - 'LANGUAGE': 'en_US:en', 08:38:20 INFO - 'LOGNAME': 'cltbld', 08:38:20 INFO - 'MAIL': '/var/mail/cltbld', 08:38:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:20 INFO - 'MOZ_NO_REMOTE': '1', 08:38:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:20 INFO - 'NO_EM_RESTART': '1', 08:38:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:20 INFO - 'PWD': '/builds/slave/test', 08:38:20 INFO - 'SHELL': '/bin/bash', 08:38:20 INFO - 'SHLVL': '1', 08:38:20 INFO - 'TERM': 'linux', 08:38:20 INFO - 'TMOUT': '86400', 08:38:20 INFO - 'USER': 'cltbld', 08:38:20 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', 08:38:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:20 INFO - '_': '/tools/buildbot/bin/python'} 08:38:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:38:20 INFO - Running setup.py (path:/tmp/pip-Lkb7xe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:38:20 INFO - Running setup.py (path:/tmp/pip-ujIUN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:38:20 INFO - Running setup.py (path:/tmp/pip-HFF6DH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:38:20 INFO - Running setup.py (path:/tmp/pip-Gliehu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:38:20 INFO - Running setup.py (path:/tmp/pip-06kC7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:38:20 INFO - Running setup.py (path:/tmp/pip-oLJbYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:38:20 INFO - Running setup.py (path:/tmp/pip-pdyilV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:38:20 INFO - Running setup.py (path:/tmp/pip-n0MISo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:38:21 INFO - Running setup.py (path:/tmp/pip-XQh3iW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:38:21 INFO - Running setup.py (path:/tmp/pip-PvTKgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:38:21 INFO - Running setup.py (path:/tmp/pip-ufGCEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:38:21 INFO - Running setup.py (path:/tmp/pip-eOiK9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:38:21 INFO - Running setup.py (path:/tmp/pip-pCuGFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:38:21 INFO - Running setup.py (path:/tmp/pip-ot3YBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:38:21 INFO - Running setup.py (path:/tmp/pip-zZgiYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:38:21 INFO - Running setup.py (path:/tmp/pip-_dRtJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:38:21 INFO - Running setup.py (path:/tmp/pip-IXfMNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:38:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:38:21 INFO - Running setup.py install for manifestparser 08:38:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:38:21 INFO - Running setup.py install for mozcrash 08:38:21 INFO - Running setup.py install for mozdebug 08:38:21 INFO - Running setup.py install for mozdevice 08:38:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:38:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:38:22 INFO - Running setup.py install for mozfile 08:38:22 INFO - Running setup.py install for mozhttpd 08:38:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:38:22 INFO - Running setup.py install for mozinfo 08:38:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:38:22 INFO - Running setup.py install for mozInstall 08:38:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:38:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:38:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:38:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:38:22 INFO - Running setup.py install for mozleak 08:38:22 INFO - Running setup.py install for mozlog 08:38:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:38:22 INFO - Running setup.py install for moznetwork 08:38:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:38:22 INFO - Running setup.py install for mozprocess 08:38:22 INFO - Running setup.py install for mozprofile 08:38:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:38:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:38:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:38:23 INFO - Running setup.py install for mozrunner 08:38:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:38:23 INFO - Running setup.py install for mozscreenshot 08:38:23 INFO - Running setup.py install for moztest 08:38:23 INFO - Running setup.py install for mozversion 08:38:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:38:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:38:23 INFO - Cleaning up... 08:38:23 INFO - Return code: 0 08:38:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:38:23 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')]} 08:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:23 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')]} 08:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:23 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2053a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0400>, '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 0x22b13b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b1840>, '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': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', '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 08:38:23 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 08:38:23 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 08:38:23 INFO - Using env: {'DISPLAY': ':0', 08:38:23 INFO - 'HOME': '/home/cltbld', 08:38:23 INFO - 'LANG': 'en_US.UTF-8', 08:38:23 INFO - 'LANGUAGE': 'en_US:en', 08:38:23 INFO - 'LOGNAME': 'cltbld', 08:38:23 INFO - 'MAIL': '/var/mail/cltbld', 08:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:23 INFO - 'MOZ_NO_REMOTE': '1', 08:38:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:23 INFO - 'NO_EM_RESTART': '1', 08:38:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:23 INFO - 'PWD': '/builds/slave/test', 08:38:23 INFO - 'SHELL': '/bin/bash', 08:38:23 INFO - 'SHLVL': '1', 08:38:23 INFO - 'TERM': 'linux', 08:38:23 INFO - 'TMOUT': '86400', 08:38:23 INFO - 'USER': 'cltbld', 08:38:23 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', 08:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:23 INFO - '_': '/tools/buildbot/bin/python'} 08:38:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:38:23 INFO - Running setup.py (path:/tmp/pip-wwMESJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:38:23 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)) 08:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:38:23 INFO - Running setup.py (path:/tmp/pip-cn6R3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:38:23 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)) 08:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:38:23 INFO - Running setup.py (path:/tmp/pip-kvmYZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:38:23 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)) 08:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:38:23 INFO - Running setup.py (path:/tmp/pip-fwXwgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:38:24 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)) 08:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:38:24 INFO - Running setup.py (path:/tmp/pip-MzwnDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:38:24 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)) 08:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:38:24 INFO - Running setup.py (path:/tmp/pip-MlQNce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:38:24 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)) 08:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:38:24 INFO - Running setup.py (path:/tmp/pip-fNkTIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:38:24 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)) 08:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:38:24 INFO - Running setup.py (path:/tmp/pip-PdceYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:38:24 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)) 08:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:38:24 INFO - Running setup.py (path:/tmp/pip-Nvlwqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:38:24 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)) 08:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:38:24 INFO - Running setup.py (path:/tmp/pip-yk2W8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:38:24 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)) 08:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:38:24 INFO - Running setup.py (path:/tmp/pip-5mkTeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:38:24 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)) 08:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:38:24 INFO - Running setup.py (path:/tmp/pip-UWF97l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:38:24 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)) 08:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:38:24 INFO - Running setup.py (path:/tmp/pip-hoKqCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:38:24 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)) 08:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:38:24 INFO - Running setup.py (path:/tmp/pip-7daArd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:38:24 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)) 08:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:38:24 INFO - Running setup.py (path:/tmp/pip-w0Gtsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:38:24 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)) 08:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:38:24 INFO - Running setup.py (path:/tmp/pip-NaZrzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:38:24 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)) 08:38:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:38:24 INFO - Running setup.py (path:/tmp/pip-Bgc_EP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:38:24 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)) 08:38:24 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)) 08:38:24 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)) 08:38:24 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)) 08:38:24 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)) 08:38:24 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)) 08:38:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:38:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:38:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:38:26 INFO - Downloading blessings-1.6.tar.gz 08:38:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:38:26 INFO - Installing collected packages: blessings 08:38:26 INFO - Running setup.py install for blessings 08:38:26 INFO - Successfully installed blessings 08:38:26 INFO - Cleaning up... 08:38:26 INFO - Return code: 0 08:38:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:38:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:38:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:38:26 INFO - Reading from file tmpfile_stdout 08:38:26 INFO - Current package versions: 08:38:26 INFO - argparse == 1.2.1 08:38:26 INFO - blessings == 1.6 08:38:26 INFO - blobuploader == 1.2.4 08:38:26 INFO - docopt == 0.6.1 08:38:26 INFO - functools32 == 3.2.3-2 08:38:26 INFO - jsonschema == 2.5.1 08:38:26 INFO - manifestparser == 1.1 08:38:26 INFO - mozInstall == 1.12 08:38:26 INFO - mozcrash == 1.0 08:38:26 INFO - mozdebug == 0.1 08:38:26 INFO - mozdevice == 0.49 08:38:26 INFO - mozfile == 1.2 08:38:26 INFO - mozhttpd == 0.7 08:38:26 INFO - mozinfo == 0.9 08:38:26 INFO - mozleak == 0.1 08:38:26 INFO - mozlog == 3.4 08:38:26 INFO - moznetwork == 0.27 08:38:26 INFO - mozprocess == 0.25 08:38:26 INFO - mozprofile == 0.28 08:38:26 INFO - mozrunner == 6.13 08:38:26 INFO - mozscreenshot == 0.1 08:38:26 INFO - mozsystemmonitor == 0.3 08:38:26 INFO - moztest == 0.8 08:38:26 INFO - mozversion == 1.4 08:38:26 INFO - psutil == 3.1.1 08:38:26 INFO - requests == 1.2.3 08:38:26 INFO - wsgiref == 0.1.2 08:38:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:38:26 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')]} 08:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:26 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')]} 08:38:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:26 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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2053a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0400>, '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 0x22b13b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b1840>, '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': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', '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 08:38:26 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 08:38:26 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 08:38:26 INFO - Using env: {'DISPLAY': ':0', 08:38:26 INFO - 'HOME': '/home/cltbld', 08:38:26 INFO - 'LANG': 'en_US.UTF-8', 08:38:26 INFO - 'LANGUAGE': 'en_US:en', 08:38:26 INFO - 'LOGNAME': 'cltbld', 08:38:26 INFO - 'MAIL': '/var/mail/cltbld', 08:38:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:26 INFO - 'MOZ_NO_REMOTE': '1', 08:38:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:26 INFO - 'NO_EM_RESTART': '1', 08:38:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:26 INFO - 'PWD': '/builds/slave/test', 08:38:26 INFO - 'SHELL': '/bin/bash', 08:38:26 INFO - 'SHLVL': '1', 08:38:26 INFO - 'TERM': 'linux', 08:38:26 INFO - 'TMOUT': '86400', 08:38:26 INFO - 'USER': 'cltbld', 08:38:26 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', 08:38:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:26 INFO - '_': '/tools/buildbot/bin/python'} 08:38:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:26 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)) 08:38:26 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)) 08:38:26 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)) 08:38:26 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)) 08:38:26 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)) 08:38:26 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)) 08:38:26 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)) 08:38:26 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)) 08:38:26 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)) 08:38:26 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)) 08:38:26 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)) 08:38:26 INFO - Cleaning up... 08:38:26 INFO - Return code: 0 08:38:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:38:26 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')]} 08:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:38:26 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')]} 08:38:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:38:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x20151f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2053a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22b3310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0400>, '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 0x22b13b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b1840>, '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': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', '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 08:38:26 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 08:38:26 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 08:38:26 INFO - Using env: {'DISPLAY': ':0', 08:38:26 INFO - 'HOME': '/home/cltbld', 08:38:26 INFO - 'LANG': 'en_US.UTF-8', 08:38:26 INFO - 'LANGUAGE': 'en_US:en', 08:38:26 INFO - 'LOGNAME': 'cltbld', 08:38:26 INFO - 'MAIL': '/var/mail/cltbld', 08:38:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:26 INFO - 'MOZ_NO_REMOTE': '1', 08:38:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:26 INFO - 'NO_EM_RESTART': '1', 08:38:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:26 INFO - 'PWD': '/builds/slave/test', 08:38:26 INFO - 'SHELL': '/bin/bash', 08:38:26 INFO - 'SHLVL': '1', 08:38:26 INFO - 'TERM': 'linux', 08:38:26 INFO - 'TMOUT': '86400', 08:38:26 INFO - 'USER': 'cltbld', 08:38:26 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', 08:38:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:26 INFO - '_': '/tools/buildbot/bin/python'} 08:38:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:38:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:38:26 INFO - Cleaning up... 08:38:26 INFO - Return code: 0 08:38:26 INFO - Running post-action listener: _resource_record_post_action 08:38:26 INFO - Running post-action listener: _start_resource_monitoring 08:38:26 INFO - Starting resource monitoring. 08:38:27 INFO - [mozharness: 2017-05-16 15:38:27.000944Z] Finished create-virtualenv step (success) 08:38:27 INFO - [mozharness: 2017-05-16 15:38:27.001559Z] Running install step. 08:38:27 INFO - Running pre-action listener: _resource_record_pre_action 08:38:27 INFO - Running main action method: install 08:38:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:38:27 INFO - Reading from file tmpfile_stdout 08:38:27 INFO - Detecting whether we're running mozinstall >=1.0... 08:38:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:38:27 INFO - Reading from file tmpfile_stdout 08:38:27 INFO - Output received: 08:38:27 INFO - Usage: mozinstall [options] installer 08:38:27 INFO - Options: 08:38:27 INFO - -h, --help show this help message and exit 08:38:27 INFO - -d DEST, --destination=DEST 08:38:27 INFO - Directory to install application into. [default: 08:38:27 INFO - "/builds/slave/test"] 08:38:27 INFO - --app=APP Application being installed. [default: firefox] 08:38:27 INFO - mkdir: /builds/slave/test/build/application 08:38:27 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'] 08:38:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:38:41 INFO - Reading from file tmpfile_stdout 08:38:41 INFO - Output received: 08:38:41 INFO - /builds/slave/test/build/application/firefox/firefox 08:38:41 INFO - Running post-action listener: _resource_record_post_action 08:38:41 INFO - [mozharness: 2017-05-16 15:38:41.392934Z] Finished install step (success) 08:38:41 INFO - [mozharness: 2017-05-16 15:38:41.393075Z] Running run-tests step. 08:38:41 INFO - Running pre-action listener: _resource_record_pre_action 08:38:41 INFO - Running main action method: run_tests 08:38:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:38:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:38:41 INFO - Python 2.7.3 08:38:41 INFO - Return code: 0 08:38:41 INFO - grabbing minidump binary from tooltool 08:38:41 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')]} 08:38:41 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 0x22b0400>, '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 0x22b13b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22b1840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:38:41 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 08:38:41 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 08:38:41 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 08:38:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:38:41 INFO - Return code: 0 08:38:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:38:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:38:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:38:41 INFO - ENV: RUST_BACKTRACE is now 1 08:38:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:38:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:38:41 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/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 08:38:41 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/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-014 --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 08:38:41 INFO - Using env: {'DISPLAY': ':0', 08:38:41 INFO - 'HOME': '/home/cltbld', 08:38:41 INFO - 'LANG': 'en_US.UTF-8', 08:38:41 INFO - 'LANGUAGE': 'en_US:en', 08:38:41 INFO - 'LOGNAME': 'cltbld', 08:38:41 INFO - 'MAIL': '/var/mail/cltbld', 08:38:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:38:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:38:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:38:41 INFO - 'MOZ_NO_REMOTE': '1', 08:38:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:38:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:38:41 INFO - 'NO_EM_RESTART': '1', 08:38:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:38:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:38:41 INFO - 'PWD': '/builds/slave/test', 08:38:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:38:41 INFO - 'RUST_BACKTRACE': '1', 08:38:41 INFO - 'SHELL': '/bin/bash', 08:38:41 INFO - 'SHLVL': '1', 08:38:41 INFO - 'TERM': 'linux', 08:38:41 INFO - 'TMOUT': '86400', 08:38:41 INFO - 'USER': 'cltbld', 08:38:41 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668', 08:38:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:38:41 INFO - '_': '/tools/buildbot/bin/python'} 08:38:41 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/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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 08:38:41 INFO - mozversion application_buildid: 20170516143940 08:38:41 INFO - mozversion application_changeset: 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 08:38:41 INFO - mozversion application_display_name: Firefox 08:38:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:38:41 INFO - mozversion application_name: Firefox 08:38:41 INFO - mozversion application_remotingname: firefox 08:38:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:38:41 INFO - mozversion application_vendor: Mozilla 08:38:41 INFO - mozversion application_version: 54.0 08:38:41 INFO - mozversion platform_buildid: 20170516143940 08:38:41 INFO - mozversion platform_changeset: 13ff34d820621cdaa52a9cf2dfd2a66998438bf8 08:38:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:38:41 INFO - mozversion platform_version: 54.0 08:38:41 INFO - using testdate: 1494949121 08:38:41 INFO - actual date: 1494949121 08:38:41 INFO - starting webserver on 'localhost:55038' 08:38:41 INFO - SUITE-START | Running 2 tests 08:38:41 INFO - TEST-START | dromaeo_css 08:38:41 INFO - operating with platform_type : linux_ 08:38:41 INFO - Initialising browser for dromaeo_css test... 08:38:41 INFO - TEST-INFO | started process 21293 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyDcOp3/profile http://localhost:55038/getInfo.html) 08:38:45 INFO - PROCESS | 21293 | __metrics Screen width/height:1600/1200 08:38:45 INFO - PROCESS | 21293 | colorDepth:24 08:38:45 INFO - PROCESS | 21293 | Browser inner width/height: 1024/697 08:38:45 INFO - PROCESS | 21293 | __metrics 08:38:46 INFO - PROCESS | 21293 | 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" 08:38:46 INFO - PROCESS | 21293 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:38:47 INFO - TEST-INFO | 21293: exit 0 08:38:47 INFO - Browser initialized. 08:38:47 INFO - Running cycle 1/1 for dromaeo_css test... 08:38:47 INFO - TEST-INFO | started process 21384 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyDcOp3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:38:58 INFO - PROCESS | 21384 | RSS: Main: 141455360 08:38:58 INFO - PROCESS | 21384 | 08:42:00 INFO - PROCESS | 21384 | Cycle 1(1): loaded http://localhost:55038/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55038/tests/dromaeo/cssquery-ext.html) 08:42:01 INFO - PROCESS | 21384 | RSS: Main: 273760256 08:42:01 INFO - PROCESS | 21384 | 08:45:04 INFO - PROCESS | 21384 | Cycle 1(1): loaded http://localhost:55038/tests/dromaeo/cssquery-ext.html (next: http://localhost:55038/tests/dromaeo/cssquery-jquery.html) 08:45:04 INFO - PROCESS | 21384 | RSS: Main: 221143040 08:45:04 INFO - PROCESS | 21384 | 08:48:06 INFO - PROCESS | 21384 | Cycle 1(1): loaded http://localhost:55038/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55038/tests/dromaeo/cssquery-mootools.html) 08:48:07 INFO - PROCESS | 21384 | RSS: Main: 200998912 08:48:07 INFO - PROCESS | 21384 | 08:51:09 INFO - PROCESS | 21384 | Cycle 1(1): loaded http://localhost:55038/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55038/tests/dromaeo/cssquery-prototype.html) 08:51:09 INFO - PROCESS | 21384 | RSS: Main: 172171264 08:51:09 INFO - PROCESS | 21384 | 08:54:12 INFO - PROCESS | 21384 | Cycle 1(1): loaded http://localhost:55038/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55038/tests/dromaeo/cssquery-yui.html) 08:54:13 INFO - PROCESS | 21384 | RSS: Main: 176001024 08:54:13 INFO - PROCESS | 21384 | 08:57:15 INFO - PROCESS | 21384 | Cycle 1(1): loaded http://localhost:55038/tests/dromaeo/cssquery-yui.html (next: http://localhost:55038/tests/dromaeo/cssquery-dojo.html) 08:57:16 INFO - PROCESS | 21384 | RSS: Main: 262717440 08:57:16 INFO - PROCESS | 21384 | 08:57:16 INFO - PROCESS | 21384 | __start_tp_report 08:57:16 INFO - PROCESS | 21384 | _x_x_mozilla_page_load 08:57:16 INFO - PROCESS | 21384 | _x_x_mozilla_page_load_details 08:57:16 INFO - PROCESS | 21384 | |i|pagename|runs| 08:57:16 INFO - PROCESS | 21384 | |0;dojo.html;3287.1453422110253;3367.1582104473882;3368.578927634045;3391.9491207631904;3414.7097496712777;5682.318121825381;5769.82690519284;5770.307563092435;5782.595218334713;5792.015357389243;5765.423457654243;5817.243758311413;5821.592488525805;5825.970870145643;5841.3282472515675;5128.717920514379;5168.870778230536;5178.922316165261;5191.402988656297;5209.010288045278;4999.650024498286;5045.369328833895;5215.765290561933;5216.713080780555;5226.27212555034;5152.252923326134;5164.070467315882;5164.225366195082;5183.352080989876;5197.1424714922;5234.947650523484;5235.11003129467;5237.450067742909;5251.973740131294;5271.657342272779;4987.351644286256;5071.087204303224;5144.0226356992425;5205.713685747282;5214.504622060898;5624.437556244384;5648.548116150698;5713.6000479966415;5796.8840623187725;5800.506956908625;6168.599041062316;6213.782517611907;6216.2540495140875;6221.253449586078;6226.221722284715;6210.875782484371;6268.498520118425;6269.7178626961895;6287.9056814147825;6288.993760998218;5629.437056294379;5641.68970706611;5753.942460575383;5785.566082543826;5790.189373487715;6328.95572230586;6335.429811317003;6335.524835637253;6337.144485494399;6350.4602108820345;6037.577369584174;6089.177960975212;6099.085137229451;6102.481289090477;6103.6032657877095;5988.850278743066;6014.308354539284;6118.984682626245;6176.104464852571;6201.224846894138;6193.969030154821;6215.409536094064;6220.813375598739;6227.750889964351;6233.96883015582;5972.462478376967;5989.221401217901;6005.129256257906;6110.694465276719;6234.407731265523;4698.36572062774;4767.475577686451;4768;4773.785179666854;4799.136155492045;4120.464339635828;4206.915861682749;4210.85262015828;4233.111046680232;4270.145970805851;5827.88344233113;5880.911786323209;5913.70431478433;5991.460768530853;6025.758969641253;6242.157308763259;6250.718717657716;6254.249490061131;6257.530685198628;6263.812085637438;5965.7613695452565;5977.312609049928;5978.432048955342;5980.252468441445;6014.699265036731;5117;5144.202648589475;5242.580593552507;5270.341207349082;5287.814926477555;6525.1517302750335;6525.477961762953;6543.640099794557;6544.247411547638;6548.083268342436;6224.439800417984;6232.657203853832;6271.122042914087;6276.215473065867;6292.056191571301;6209.4101060401;6234.37656234391;6283.465905398092;6534.8366290843105;6539.280679125388;6043.516518678407;6051.213342265477;6063.302720187058;6080.7567697291615;6093.177421048102;6151.753929842756;6176.598521096114;6187.81436556904;6190.659513726789;6192.504599632109;5825.805709829559;5932.940670593239;6002.549808764273;6004.1294012368835;6013.098035294741;5958;6003.7898673546215;6007.068904319837;6011.789587364421;6030.6080104793045;6060.2424696912885;6065.181200537871;6071;6071.058985857199;6077.027675571887;5883.411658834082;5919.911201331897;5944.375840536854;5945.91081133792;6109.602875813058;5848.210491583582;5914.172015917689;5951.494122999594;6039.456448919618;6041.909371359521 08:57:16 INFO - PROCESS | 21384 | |1;ext.html;762.5272331154684;834.1241696218971;851.1148405658116;918.6784625381114;932.1377725603814;32533;43860.3420948686;44734.552654473446;44795.32807007898;44841.32738008932;46009;46053;46089.76955115228;46208.53791462084;46286.7685661572;7802.141764405911;7964;9192.494412807293;9342.486163261017;9346.719598412059;10616.150707943347;10844.186685998542;10859.511321990529;10864.402457864815;10867.456627168649;11026.614068507604;11029.61396351128;11036.779264414707;11073.833892491619;11088.501017454193;10844.13246940243;10847.891521084766;10888.564457421733;10892.509837057352;10897.782044359068;10631.627893023784;10684.839727404094;10690.839637405445;10714.142868570496;10735.40955247461;44549.55450445486;44636.776816116035;44894.77552612216;44959.77520112378;44978.325325120146;4374.19077470669;4374.300111982099;4412.977935110336;4515.480719717241;4542.818287268506;30696.693033069605;31462.056138315886;31675.366492670248;32280.838595807105;32604.836975815208;4329.805158767863;4456.108778244364;4471.754053527055;4576.8626941191815;4578.175928332898;211386;212252;214560;215112;215708;54305;54493;54667.179992300145;54713.1793023105;54742.17886731702;55901.7204913981;56474.717626411606;56574.43425565692;56689.149662754266;56759.14861277084;16248.268827902773;16342.591435213977;16346.591335216715;16349.50951471434;16360;12584.30786306762;12794.616161515169;14172.787408188886;14181.78727319091;14188.148711077369;13383.732325353438;13438.395272212774;14055.367508462145;14062.648433789238;14141.929290353482;5368.773803486347;5393.352797664305;5454.372747134131;5520.751566179531;5559.527440167551;1038.6520515627349;1039.2672620905169;1046.131710680149;1050.4590136079926;1066.8826429092794;29405.705942940724;30089.548656770166;32126.196845078593;32558.511622325685;33082;1114.4817659788237;1185.2888267039707;1204.5242129358853;1217.7503611759569;1234.6234398508361;12563;15432;15510.146941918078;16026.67946641084;16296.26666799997;303935;304207;304657;305209;305497;50612.74693626582;50684.239736403986;50806.74596626993;50810.237846432334;50832;1502.3239542206052;1531.3721374236509;1531.6017835362886;1539.5535294764618;1566.7884835547056;28558.857205713837;29450;29697.25756856096;29811.70188298089;29996.85001574978;25764.742352576985;25814.354641134123;25889.352766180244;25895.611565826905;25905.870470647526;287935;289070;290478;290789;290855;29664.110076697732;30425.239369015067;31472.370552589276;31618;32393.514097288087;38892.80553597327;38901.22197555976;38903.41644875386;39037.21925561416;39986.80006599949;30629.23426914345;30659.693403065685;30952.69047309498;30960.225994350323;31052.534211986385;30380.544291836082;30434.239144021576;30488.237794055327;30838;31267.06198814039;34193.82903085568;34439.65560344465;34513.82743086369;34920.30159396747;34946.65053349434;28931.566026509197;28983.56524652174;29387.559186612645;29571.40857182801;30029.54955675623;23871.522569548164;23972.40068998289;24061;24157.97904990995;24283.150089746774 08:57:16 INFO - PROCESS | 21384 | |2;jquery.html;458.3102430841583;467.2174108368482;476.67347866711293;488.3700026965215;494.15499495862076;20298.797012029892;22073.558528829435;22561.323160305215;22705.659415108745;22710;23134.768652313473;23524.411889702766;23565.646515302284;23679.526409471844;23732.762672373316;6713.328667133296;6782.96608516958;6936.514443988923;6966.198887127969;6967.338102880243;6748.696308666097;6869.759558415456;7291.30732580404;7564.281393267631;7601.7719468416035;7562.697492100337;7584.241575842427;7586.886196707053;7608.581528015957;7614.695412183506;7428.962855185717;7623.504472209316;7624.19945905683;7628;7632.923670763277;7334;7565.886511702329;7635.923640763577;7645.923540764632;7646.502977306457;24854;25224;25413;26474.73525264742;26592.60111098335;3226.8063916165106;3314.950275745866;3321.5017747338093;3323.7341012718925;3368.8820891268933;16335.91832040844;16628.33486660532;16656.167191640492;16761.245743941552;17101.828981710274;2159.4709296222527;2288.6338185890177;2314.6875171851752;2330.953380932389;2383.797377222938;110246;111652;114045.42977285144;114592.70814583745;118385;18083.909580452015;18370;18547.629047418977;18700.568926039527;18890;32375.676243237736;32441.837790810896;32510.187245318582;32768;32821.507677384856;10184.949075254577;10453.84319235212;10489.947550262199;10696.94651526753;10711.357318560755;8713.869291960626;8721.433106848035;8726.86909696355;8931.598078086501;8983.281337492985;9053.68312109073;9585.856212156823;9600.519974001272;9655.420674759536;9747.220222382193;3401.727861771052;3750.6249375062275;3761.32296186689;3907.5701672815967;4032.9395059074136;978.7650963768787;987.4223579206101;992.1864071461332;995.9053889880449;1005.9849102263472;21045.263415780373;21180;21381.358559243246;22015.899205039685;22085.889570552044;1049.1344640671477;1078.0405439159154;1078.099786678117;1085.4898197846999;1109.7392112853472;8129.634166462523;8137.5931203439595;8316.501009939302;8417.28453081495;11743.765124697458;586595;589763;595695;596263;598539;80420;99897;111441;125501;129719.35140324426;1443.3793468808512;1451.8693317601467;1494.7907292978991;1498.992505037468;1501.5795577238391;18770.6245875079;19157.425277241706;19233.61532769365;19265.711014334516;19313.227470901005;51416.74291628518;53619.19571206357;54212;54270.45729542654;55121.724391377786;73575;73735;74991;75015.62492187505;75289.62355188189;12490.750184996068;12904.8064279034;12929.676758081123;12952.28762418076;13038.54365097255;14183.219922903927;14389.856101438852;14509.347079381241;14513.564593062225;14575.562733118024;12340.197887137307;12403.689907752378;12691.682707932376;12813.231206127663;12828.55100071493;11320.150988675718;11863.169578129447;12035.879641203475;12164.635060948185;12278.631641050782;13175.341232938506;13273.203607783564;13458.192508449525;13518.188908665512;13732.862671373558;13104.410301536644;13242.271675057964;13295;13314.86685133175;13398.129121607064;12769.808452873029;13084.542041028519;13169.53906613264;13211.405486752927;13263 08:57:16 INFO - PROCESS | 21384 | |3;mootools.html;1576.4561226376895;1586.468533041431;1599.176424141568;1619.7246468100423;1623.7239669256228;10944.5622175113;11723.53105875766;11745.177837551375;11753.236039657417;11755.941220293887;11521;11544.191906566544;11716.589919352824;11730.120240981918;11754.647360579196;2611.308003379101;2653.973460265392;2654.66816647919;2658.9468210635778;2660.5344064788683;2452.080469823816;2460.1266550374626;2469.8518088914634;2471.5180539794746;2472.3819045238693;2435.6468312094735;2484.8881800319027;2485.2420011896365;2486.676732024843;2486.8632225227607;2276.1805749930013;2281.851679640818;2350.435895385104;2429.6355546667965;2437.7805997460223;2384.7615238476014;2429.161939130997;2430.586800243945;2431.416460049593;2442.242904699549;11167.66497005083;11317.151213659008;11319.20765546412;11319.603813866557;11331;6034.36639152887;6166.660833654148;6177.104319873638;6183.845403864894;6184.659843708594;3502.2645244498685;3545.1137215696076;3577.2845430913662;3592.4134623071345;3669.412893936984;1280.5966120672051;1310.2204188507822;1325.4830616059744;1350.3653282956996;1372.444160115155;14837;15209.239538023054;16268.593285167966;17193.484195474157;17548.64902701939;4809.326694262806;4859.392575928009;4933.925991110136;4997.350344455198;5146.202338637517;11024.228304018641;11267.26762760418;12446.81329780054;13565.25391103479;13588;6148.508119350441;6245.219347581552;6254.655993920287;6258.280297765815;6259.71831267594;5738.282714660668;5794.275715535558;5797.043487824546;5804.970975145097;5875.970620146957;4130.421740956269;4176.895577610584;4191.161767646422;4233.322668373045;4237.618614324726;1018.134585602247;1019.6686077024997;1031.886492485826;1036.3212096077082;1039.293280569205;1085.3108276244484;1101.2346419238713;1104.1553211792984;1105.6075093341908;1111.6220485035126;7467.215942326086;7476.514026588255;7500.849983000309;7686.769396918102;7727.961360193163;1053.0943388685723;1080.8216644253766;1084.311462221495;1093.0818112785298;1119.7424592343807;4722.6221902247735;4748.430188377417;4780.139574876486;4864.1001414737675;4867.1969125093965;14243.85756142446;14760.926195368955;14800.185989770665;15367.308471118826;15369.308381122877;15222.021137252086;15233.542993710207;15241.085534867721;15283;15287.464938727091;10296.433696146634;10560.366378017343;10762.19283553721;10804;10820.458977051116;7506.43701722384;7854.41091918097;7882.132965373689;7917.802054948673;8053.6778528858185;12928.806067908801;12940.352982350656;12969.351532423152;13331.933340333237;13998.44006239739;13454.327283635583;13482.797758033441;13588.45646174142;13618.18290902549;13718.862811372157;9795.167410770164;10007.599696012368;10009.24930630191;10015.449150296803;10199.184065274909;10705.518251678537;10872.67381978542;10938.45307734626;10950.233483656068;10960;9373.953130234306;9538.475383853955;9617.71146865595;9763.46300953426;9850.802983940137;8996.820063598561;9022.819543608959;9065.229455496357;9086.182243598108;9207.355485115977;8577.142285771373;8586.184312490303;8602.78493037679;8673.696420625249;8696.434731742416;6511.73953041873;6593.044008618819;6605.678864227078;6620.437262832713;6649.63427011519;6841.281665425257;6921.307869213038;6938.098047253825;6955.130608673916;7013.8246543834775 08:57:16 INFO - PROCESS | 21384 | |4;prototype.html;411.38087178795905;432.80523764306065;435.76686472737094;439.0691733524928;439.9120175964808;5151.484851514847;5241.7903283868645;5252.789888404469;5258.44786297439;5272.182811664188;4999.950000499994;5157.6131790115705;5161.870953226171;5173.344529750481;5178.715170665621;4322.805473753689;4326.524082350938;4334.374609521429;4368.978155109228;4370.4099946507295;4350.260455722535;4352.39066530686;4352.73883566987;4364.7700701480835;4387.561243875619;4335.349697545362;4348.434703488542;4367.170237654851;4374.234508960935;4379.058502421979;4108.85619003335;4298.828046878121;4328.372386004027;4334.284843000901;4361.127774445092;4083.673306135502;4086.468759061303;4086.652634526039;4106.753594784323;4109.157622687342;5060.114479966028;5085.542301192873;5110.616703747197;5112.744362781883;5134.537891589737;4947.9270183249255;4998.925016124761;5066.71997601081;5101.923471147936;5113.923291150636;5442.945570544283;5448.665077056146;5460.7542660579975;5471.562275017989;5513.9448605513835;4601.493835678073;4607.470140933802;4610.585047345755;4637.188492013918;4649.442066951953;5465.600191845563;5608.775648974036;5625.437456254383;5663.546916246691;5709.74306156224;4683.016566521069;4715.693479923794;4727.409073865767;4760.500147484534;4807.61539076873;4827.565519103297;4838.395200599925;4858.95141048585;4861.854144375674;4864.586510146677;4875.756212189376;5109.821156259513;5127.359245041551;5141.53726164647;5160.0969830279555;5477.397486276506;5478.945210547923;5549.72251387429;5588.451333403318;5603.5237004854225;1568.4275239537715;1644.8272931342037;1653.9586510337338;1670.4237038222036;1686.5783554111472;1256.2462522486435;1268.733565951142;1276.387334079647;1292.3796577642597;1294.9158304710163;5248.740302327414;5311.442298558673;5339.41266460689;5340.946590534123;5343.332083489564;5588.30146231721;5621.213030175779;5657.915131273035;5660;5716.485516303553;5296.788128474895;5395.838124856261;5400.243965844785;5427.945720542744;5473.370562385297;2932;2954.2466670998756;2963.703629637019;2997.2806526433933;2998.710554461559;5816.272965879265;5895.970520147372;5901.262342207224;5901.7639294427745;5906.8818623627285;5406;5443.265159203536;5537;5690.943090569124;5789;5692.089265717548;5869.207656966255;5891.793787217427;5913.556483263773;5919.733611987471;5603.663780173202;5615.466530679579;5681.602287839812;5692.117721753135;5696.772129114955;5376.489233522809;5502.532284755841;5545.922013282506;5570.944290557042;5637.295338082739;5815.156802263564;5835.095560188178;5847.853803654943;5861.853453663693;5862.895972818958;4501.482329532015;4636.6058884995155;4652.116097941381;4687.62499000086;4693.014466961839;5653.858653533694;5669.262995810518;5670.914936275876;5675.148727690879;5684.408365657622;5482.369527504388;5499.725013749377;5520;5576.776928922797;5582.432081242674;5284.41871394154;5489.945100548944;5549.751305956128;5555.9443705695785;5558.777648893999;5541.891621675601;5556.833295001156;5570.91643625354;5590.552755779447;5602.607817452739;5503.917441238465;5537.421834777069;5563.7496312666835;5575.515302935665;5645.435456454322;5208.479152084761;5360.463953604608;5451.727413629382;5466.753996070107;5468.72656367188 08:57:16 INFO - PROCESS | 21384 | |5;yui.html;889.484099222451;900.4507250577151;900.8739076154804;920.6041402197053;924.5839372282474;17841.28634854606;19361.903190484027;19580;19670.11484483198;20285.492862678402;18411.90794046031;19124.904375478138;20150;20327.796722032774;20339.796602034014;880.7269746378611;898.707919926025;914.0448231597962;916.120524296676;917.1241464401514;900.9099090091004;911.5806728904712;911.5988964855443;914.1909410172012;916.0061333453211;948.7296120605629;953.7329547726648;954.89973552777;954.9427034377959;955.4506158958627;955.5652178258875;956.1967946924582;958.8801399825056;959.5873774277059;959.721680712596;701.5755467941873;702.3608516250188;704.989425158623;705.9188193357778;708.0052526200645;18584.163712632828;18819.964901930387;18956;19098.427047188467;19104.904475477673;17898.194581243875;18068.819311806845;18198.181081851348;18330.450086497425;18701.15844786988;13656.795148072786;13681.2993113173;13746.175229486264;13782.241976691277;13865.37605807741;4224.556421575721;4264.829406823724;4301;4353.216421044211;4407.07451435199;51137.48862511364;54664.18003729947;64613.35386646167;89843.99999999935;98265;13577.932110339385;13788.862111378958;13808.309584520734;13889.305534723426;13912.095713778572;27101.86449067742;27396.31509212246;27484.58773118405;27559.724402756117;27614.861925690242;10331.690049298533;10345.586176553004;10370;10376.481175941173;10381.169506439466;5282.6302158849285;5301.443348448434;5378.78484860609;5418.024755543961;5426.403095659474;4010.7393019453643;4175.916481670411;4194.266003449384;4216.072464057902;4233;659.0378048049901;659.6470888074857;662.3145044878528;676.3709749932511;676.4419354032942;851.9744407667656;853.3258725606701;856.4732689395977;856.8843206167212;873.1966590736411;11182.440877956069;11272;11308.773824523463;11486.540538378371;11773.293602383714;748.4498893313411;749.61394881636;757.5870258263823;766.1495739728897;767.1331395523023;5466.972665136649;5472.781088756406;5557.916631250535;5635.126555383922;5700.914486282709;86474.56762716146;88433.55783221043;88664.5566772162;90947;92627;76532;76864;77527;79875.20124798677;80349;67122.32877671259;67398.66300668563;68377.65811170913;68871.31128688648;70406.295937041;10559.63041293551;10847.40339281347;11048.889511105108;11124;11160.274582152135;46068.53931460642;46387.30419043785;46740.7662961683;46929.53070469388;48796;52950.470495294554;54305;55859.720701397855;58604;60201;12176.208546444454;12407.875921240671;12503.374831258583;12639.368031598568;12863.549775757803;16131.67736645237;16521.91739041297;16743.832561674226;16774.412895548598;17730.20214090343;15085.24573771285;15406;15783.68432631364;16192.595185120466;16396.590085247964;14650;14671.26643667791;15469.690606188038;15496.92251538735;15572.68854622924;14900.776488352467;14967.775483367976;15568.610784730472;15708.450204242796;15755.763663544827;10349;10430.165586753194;10771;10903.509342079784;10984.505697243483;12247.326397048248;12689.302088385228;12868;12927.703644692518;13117.672058198621 08:57:16 INFO - PROCESS | 21384 | __end_tp_report 08:57:16 INFO - PROCESS | 21384 | __start_cc_report 08:57:16 INFO - PROCESS | 21384 | _x_x_mozilla_cycle_collect,1321 08:57:16 INFO - PROCESS | 21384 | __end_cc_report 08:57:16 INFO - PROCESS | 21384 | __startTimestamp1494950236051__endTimestamp 08:57:16 INFO - PROCESS | 21384 | 08:57:16 INFO - PROCESS | 21384 | ------- Summary: start ------- 08:57:16 INFO - PROCESS | 21384 | Number of tests: 6 08:57:16 INFO - PROCESS | 21384 | 08:57:16 INFO - PROCESS | 21384 | [#0] dojo.html Cycles:165 Average:5733.68 Median:5980.25 stddev:668.46 (11.2%) stddev-sans-first:642.36 08:57:16 INFO - PROCESS | 21384 | Values: 3287.1 3367.2 3368.6 3391.9 3414.7 5682.3 5769.8 5770.3 5782.6 5792.0 5765.4 5817.2 5821.6 5826.0 5841.3 5128.7 5168.9 5178.9 5191.4 5209.0 4999.7 5045.4 5215.8 5216.7 5226.3 5152.3 5164.1 5164.2 5183.4 5197.1 5234.9 5235.1 5237.5 5252.0 5271.7 4987.4 5071.1 5144.0 5205.7 5214.5 5624.4 5648.5 5713.6 5796.9 5800.5 6168.6 6213.8 6216.3 6221.3 6226.2 6210.9 6268.5 6269.7 6287.9 6289.0 5629.4 5641.7 5753.9 5785.6 5790.2 6329.0 6335.4 6335.5 6337.1 6350.5 6037.6 6089.2 6099.1 6102.5 6103.6 5988.9 6014.3 6119.0 6176.1 6201.2 6194.0 6215.4 6220.8 6227.8 6234.0 5972.5 5989.2 6005.1 6110.7 6234.4 4698.4 4767.5 4768.0 4773.8 4799.1 4120.5 4206.9 4210.9 4233.1 4270.1 5827.9 5880.9 5913.7 5991.5 6025.8 6242.2 6250.7 6254.2 6257.5 6263.8 5965.8 5977.3 5978.4 5980.3 6014.7 5117.0 5144.2 5242.6 5270.3 5287.8 6525.2 6525.5 6543.6 6544.2 6548.1 6224.4 6232.7 6271.1 6276.2 6292.1 6209.4 6234.4 6283.5 6534.8 6539.3 6043.5 6051.2 6063.3 6080.8 6093.2 6151.8 6176.6 6187.8 6190.7 6192.5 5825.8 5932.9 6002.5 6004.1 6013.1 5958.0 6003.8 6007.1 6011.8 6030.6 6060.2 6065.2 6071.0 6071.1 6077.0 5883.4 5919.9 5944.4 5945.9 6109.6 5848.2 5914.2 5951.5 6039.5 6041.9 08:57:16 INFO - PROCESS | 21384 | 08:57:16 INFO - PROCESS | 21384 | [#1] ext.html Cycles:180 Average:43658.29 Median:167316.12 stddev:71044.15 (42.5%) stddev-sans-first:71170.44 08:57:16 INFO - PROCESS | 21384 | Values: 762.5 834.1 851.1 918.7 932.1 32533.0 43860.3 44734.6 44795.3 44841.3 46009.0 46053.0 46089.8 46208.5 46286.8 7802.1 7964.0 9192.5 9342.5 9346.7 10616.2 10844.2 10859.5 10864.4 10867.5 11026.6 11029.6 11036.8 11073.8 11088.5 10844.1 10847.9 10888.6 10892.5 10897.8 10631.6 10684.8 10690.8 10714.1 10735.4 44549.6 44636.8 44894.8 44959.8 44978.3 4374.2 4374.3 4413.0 4515.5 4542.8 30696.7 31462.1 31675.4 32280.8 32604.8 4329.8 4456.1 4471.8 4576.9 4578.2 211386.0 212252.0 214560.0 215112.0 215708.0 54305.0 54493.0 54667.2 54713.2 54742.2 55901.7 56474.7 56574.4 56689.1 56759.1 16248.3 16342.6 16346.6 16349.5 16360.0 12584.3 12794.6 14172.8 14181.8 14188.1 13383.7 13438.4 14055.4 14062.6 14141.9 5368.8 5393.4 5454.4 5520.8 5559.5 1038.7 1039.3 1046.1 1050.5 1066.9 29405.7 30089.5 32126.2 32558.5 33082.0 1114.5 1185.3 1204.5 1217.8 1234.6 12563.0 15432.0 15510.1 16026.7 16296.3 303935.0 304207.0 304657.0 305209.0 305497.0 50612.7 50684.2 50806.7 50810.2 50832.0 1502.3 1531.4 1531.6 1539.6 1566.8 28558.9 29450.0 29697.3 29811.7 29996.9 25764.7 25814.4 25889.4 25895.6 25905.9 287935.0 289070.0 290478.0 290789.0 290855.0 29664.1 30425.2 31472.4 31618.0 32393.5 38892.8 38901.2 38903.4 39037.2 39986.8 30629.2 30659.7 30952.7 30960.2 31052.5 30380.5 30434.2 30488.2 30838.0 31267.1 34193.8 34439.7 34513.8 34920.3 34946.7 28931.6 28983.6 29387.6 29571.4 30029.5 23871.5 23972.4 24061.0 24158.0 24283.2 08:57:16 INFO - PROCESS | 21384 | 08:57:16 INFO - PROCESS | 21384 | [#2] jquery.html Cycles:180 Average:36230.09 Median:23545.03 stddev:98114.86 (416.7%) stddev-sans-first:98353.33 08:57:16 INFO - PROCESS | 21384 | Values: 458.3 467.2 476.7 488.4 494.2 20298.8 22073.6 22561.3 22705.7 22710.0 23134.8 23524.4 23565.6 23679.5 23732.8 6713.3 6783.0 6936.5 6966.2 6967.3 6748.7 6869.8 7291.3 7564.3 7601.8 7562.7 7584.2 7586.9 7608.6 7614.7 7429.0 7623.5 7624.2 7628.0 7632.9 7334.0 7565.9 7635.9 7645.9 7646.5 24854.0 25224.0 25413.0 26474.7 26592.6 3226.8 3315.0 3321.5 3323.7 3368.9 16335.9 16628.3 16656.2 16761.2 17101.8 2159.5 2288.6 2314.7 2331.0 2383.8 110246.0 111652.0 114045.4 114592.7 118385.0 18083.9 18370.0 18547.6 18700.6 18890.0 32375.7 32441.8 32510.2 32768.0 32821.5 10184.9 10453.8 10489.9 10696.9 10711.4 8713.9 8721.4 8726.9 8931.6 8983.3 9053.7 9585.9 9600.5 9655.4 9747.2 3401.7 3750.6 3761.3 3907.6 4032.9 978.8 987.4 992.2 995.9 1006.0 21045.3 21180.0 21381.4 22015.9 22085.9 1049.1 1078.0 1078.1 1085.5 1109.7 8129.6 8137.6 8316.5 8417.3 11743.8 586595.0 589763.0 595695.0 596263.0 598539.0 80420.0 99897.0 111441.0 125501.0 129719.4 1443.4 1451.9 1494.8 1499.0 1501.6 18770.6 19157.4 19233.6 19265.7 19313.2 51416.7 53619.2 54212.0 54270.5 55121.7 73575.0 73735.0 74991.0 75015.6 75289.6 12490.8 12904.8 12929.7 12952.3 13038.5 14183.2 14389.9 14509.3 14513.6 14575.6 12340.2 12403.7 12691.7 12813.2 12828.6 11320.2 11863.2 12035.9 12164.6 12278.6 13175.3 13273.2 13458.2 13518.2 13732.9 13104.4 13242.3 13295.0 13314.9 13398.1 12769.8 13084.5 13169.5 13211.4 13263.0 08:57:16 INFO - PROCESS | 21384 | 08:57:16 INFO - PROCESS | 21384 | [#3] mootools.html Cycles:180 Average:7281.05 Median:2431.00 stddev:4549.09 (187.1%) stddev-sans-first:4541.66 08:57:16 INFO - PROCESS | 21384 | Values: 1576.5 1586.5 1599.2 1619.7 1623.7 10944.6 11723.5 11745.2 11753.2 11755.9 11521.0 11544.2 11716.6 11730.1 11754.6 2611.3 2654.0 2654.7 2658.9 2660.5 2452.1 2460.1 2469.9 2471.5 2472.4 2435.6 2484.9 2485.2 2486.7 2486.9 2276.2 2281.9 2350.4 2429.6 2437.8 2384.8 2429.2 2430.6 2431.4 2442.2 11167.7 11317.2 11319.2 11319.6 11331.0 6034.4 6166.7 6177.1 6183.8 6184.7 3502.3 3545.1 3577.3 3592.4 3669.4 1280.6 1310.2 1325.5 1350.4 1372.4 14837.0 15209.2 16268.6 17193.5 17548.6 4809.3 4859.4 4933.9 4997.4 5146.2 11024.2 11267.3 12446.8 13565.3 13588.0 6148.5 6245.2 6254.7 6258.3 6259.7 5738.3 5794.3 5797.0 5805.0 5876.0 4130.4 4176.9 4191.2 4233.3 4237.6 1018.1 1019.7 1031.9 1036.3 1039.3 1085.3 1101.2 1104.2 1105.6 1111.6 7467.2 7476.5 7500.8 7686.8 7728.0 1053.1 1080.8 1084.3 1093.1 1119.7 4722.6 4748.4 4780.1 4864.1 4867.2 14243.9 14760.9 14800.2 15367.3 15369.3 15222.0 15233.5 15241.1 15283.0 15287.5 10296.4 10560.4 10762.2 10804.0 10820.5 7506.4 7854.4 7882.1 7917.8 8053.7 12928.8 12940.4 12969.4 13331.9 13998.4 13454.3 13482.8 13588.5 13618.2 13718.9 9795.2 10007.6 10009.2 10015.4 10199.2 10705.5 10872.7 10938.5 10950.2 10960.0 9374.0 9538.5 9617.7 9763.5 9850.8 8996.8 9022.8 9065.2 9086.2 9207.4 8577.1 8586.2 8602.8 8673.7 8696.4 6511.7 6593.0 6605.7 6620.4 6649.6 6841.3 6921.3 6938.1 6955.1 7013.8 08:57:16 INFO - PROCESS | 21384 | 08:57:16 INFO - PROCESS | 21384 | [#4] prototype.html Cycles:180 Average:4799.03 Median:5265.32 stddev:1277.41 (24.3%) stddev-sans-first:1237.81 08:57:16 INFO - PROCESS | 21384 | Values: 411.4 432.8 435.8 439.1 439.9 5151.5 5241.8 5252.8 5258.4 5272.2 5000.0 5157.6 5161.9 5173.3 5178.7 4322.8 4326.5 4334.4 4369.0 4370.4 4350.3 4352.4 4352.7 4364.8 4387.6 4335.3 4348.4 4367.2 4374.2 4379.1 4108.9 4298.8 4328.4 4334.3 4361.1 4083.7 4086.5 4086.7 4106.8 4109.2 5060.1 5085.5 5110.6 5112.7 5134.5 4947.9 4998.9 5066.7 5101.9 5113.9 5442.9 5448.7 5460.8 5471.6 5513.9 4601.5 4607.5 4610.6 4637.2 4649.4 5465.6 5608.8 5625.4 5663.5 5709.7 4683.0 4715.7 4727.4 4760.5 4807.6 4827.6 4838.4 4859.0 4861.9 4864.6 4875.8 5109.8 5127.4 5141.5 5160.1 5477.4 5478.9 5549.7 5588.5 5603.5 1568.4 1644.8 1654.0 1670.4 1686.6 1256.2 1268.7 1276.4 1292.4 1294.9 5248.7 5311.4 5339.4 5340.9 5343.3 5588.3 5621.2 5657.9 5660.0 5716.5 5296.8 5395.8 5400.2 5427.9 5473.4 2932.0 2954.2 2963.7 2997.3 2998.7 5816.3 5896.0 5901.3 5901.8 5906.9 5406.0 5443.3 5537.0 5690.9 5789.0 5692.1 5869.2 5891.8 5913.6 5919.7 5603.7 5615.5 5681.6 5692.1 5696.8 5376.5 5502.5 5545.9 5570.9 5637.3 5815.2 5835.1 5847.9 5861.9 5862.9 4501.5 4636.6 4652.1 4687.6 4693.0 5653.9 5669.3 5670.9 5675.1 5684.4 5482.4 5499.7 5520.0 5576.8 5582.4 5284.4 5489.9 5549.8 5555.9 5558.8 5541.9 5556.8 5570.9 5590.6 5602.6 5503.9 5537.4 5563.7 5575.5 5645.4 5208.5 5360.5 5451.7 5466.8 5468.7 08:57:16 INFO - PROCESS | 21384 | 08:57:16 INFO - PROCESS | 21384 | [#5] yui.html Cycles:180 Average:19475.91 Median:4248.91 stddev:23945.14 (563.6%) stddev-sans-first:23971.63 08:57:16 INFO - PROCESS | 21384 | Values: 889.5 900.5 900.9 920.6 924.6 17841.3 19361.9 19580.0 19670.1 20285.5 18411.9 19124.9 20150.0 20327.8 20339.8 880.7 898.7 914.0 916.1 917.1 900.9 911.6 911.6 914.2 916.0 948.7 953.7 954.9 954.9 955.5 955.6 956.2 958.9 959.6 959.7 701.6 702.4 705.0 705.9 708.0 18584.2 18820.0 18956.0 19098.4 19104.9 17898.2 18068.8 18198.2 18330.5 18701.2 13656.8 13681.3 13746.2 13782.2 13865.4 4224.6 4264.8 4301.0 4353.2 4407.1 51137.5 54664.2 64613.4 89844.0 98265.0 13577.9 13788.9 13808.3 13889.3 13912.1 27101.9 27396.3 27484.6 27559.7 27614.9 10331.7 10345.6 10370.0 10376.5 10381.2 5282.6 5301.4 5378.8 5418.0 5426.4 4010.7 4175.9 4194.3 4216.1 4233.0 659.0 659.6 662.3 676.4 676.4 852.0 853.3 856.5 856.9 873.2 11182.4 11272.0 11308.8 11486.5 11773.3 748.4 749.6 757.6 766.1 767.1 5467.0 5472.8 5557.9 5635.1 5700.9 86474.6 88433.6 88664.6 90947.0 92627.0 76532.0 76864.0 77527.0 79875.2 80349.0 67122.3 67398.7 68377.7 68871.3 70406.3 10559.6 10847.4 11048.9 11124.0 11160.3 46068.5 46387.3 46740.8 46929.5 48796.0 52950.5 54305.0 55859.7 58604.0 60201.0 12176.2 12407.9 12503.4 12639.4 12863.5 16131.7 16521.9 16743.8 16774.4 17730.2 15085.2 15406.0 15783.7 16192.6 16396.6 14650.0 14671.3 15469.7 15496.9 15572.7 14900.8 14967.8 15568.6 15708.5 15755.8 10349.0 10430.2 10771.0 10903.5 10984.5 12247.3 12689.3 12868.0 12927.7 13117.7 08:57:16 INFO - PROCESS | 21384 | -------- Summary: end -------- 08:57:16 INFO - PROCESS | 21384 | 08:57:16 INFO - TEST-INFO | 21384: exit 0 08:57:16 INFO - TEST-OK | dromaeo_css | took 1115131ms 08:57:16 INFO - TEST-START | kraken 08:57:16 INFO - operating with platform_type : linux_ 08:57:16 INFO - Initialising browser for kraken test... 08:57:16 INFO - TEST-INFO | started process 21687 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxtdCzh/profile http://localhost:55038/getInfo.html) 08:57:20 INFO - PROCESS | 21687 | __metrics Screen width/height:1600/1200 08:57:20 INFO - PROCESS | 21687 | colorDepth:24 08:57:20 INFO - PROCESS | 21687 | Browser inner width/height: 1024/697 08:57:20 INFO - PROCESS | 21687 | __metrics 08:57:21 INFO - PROCESS | 21687 | 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" 08:57:21 INFO - PROCESS | 21687 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:57:22 INFO - TEST-INFO | 21687: exit 0 08:57:22 INFO - Browser initialized. 08:57:22 INFO - Running cycle 1/1 for kraken test... 08:57:22 INFO - TEST-INFO | started process 21778 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxtdCzh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:57:34 INFO - PROCESS | 21778 | RSS: Main: 141500416 08:57:34 INFO - PROCESS | 21778 | 08:57:36 INFO - PROCESS | 21778 | Cycle 1(1): loaded http://localhost:55038/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55038/tests/kraken/driver.html?testName=audio-beat-detection) 08:57:37 INFO - PROCESS | 21778 | RSS: Main: 183914496 08:57:37 INFO - PROCESS | 21778 | 08:57:39 INFO - PROCESS | 21778 | Cycle 1(1): loaded http://localhost:55038/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55038/tests/kraken/driver.html?testName=audio-dft) 08:57:40 INFO - PROCESS | 21778 | RSS: Main: 173854720 08:57:40 INFO - PROCESS | 21778 | 08:57:43 INFO - PROCESS | 21778 | Cycle 1(1): loaded http://localhost:55038/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55038/tests/kraken/driver.html?testName=audio-fft) 08:57:43 INFO - PROCESS | 21778 | RSS: Main: 264966144 08:57:43 INFO - PROCESS | 21778 | 08:57:45 INFO - PROCESS | 21778 | Cycle 1(1): loaded http://localhost:55038/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55038/tests/kraken/driver.html?testName=audio-oscillator) 08:57:46 INFO - PROCESS | 21778 | RSS: Main: 171573248 08:57:46 INFO - PROCESS | 21778 | 08:57:48 INFO - PROCESS | 21778 | Cycle 1(1): loaded http://localhost:55038/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55038/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:57:49 INFO - PROCESS | 21778 | RSS: Main: 174477312 08:57:49 INFO - PROCESS | 21778 | 08:57:52 INFO - PROCESS | 21778 | Cycle 1(1): loaded http://localhost:55038/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55038/tests/kraken/driver.html?testName=imaging-darkroom) 08:57:52 INFO - PROCESS | 21778 | RSS: Main: 212799488 08:57:52 INFO - PROCESS | 21778 | 08:57:56 INFO - PROCESS | 21778 | Cycle 1(1): loaded http://localhost:55038/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55038/tests/kraken/driver.html?testName=imaging-desaturate) 08:57:56 INFO - PROCESS | 21778 | RSS: Main: 212946944 08:57:56 INFO - PROCESS | 21778 | 08:57:59 INFO - PROCESS | 21778 | Cycle 1(1): loaded http://localhost:55038/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55038/tests/kraken/driver.html?testName=json-parse-financial) 08:58:00 INFO - PROCESS | 21778 | RSS: Main: 211603456 08:58:00 INFO - PROCESS | 21778 | 08:58:01 INFO - PROCESS | 21778 | Cycle 1(1): loaded http://localhost:55038/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55038/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:58:02 INFO - PROCESS | 21778 | RSS: Main: 173694976 08:58:02 INFO - PROCESS | 21778 | 08:58:03 INFO - PROCESS | 21778 | Cycle 1(1): loaded http://localhost:55038/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55038/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:58:04 INFO - PROCESS | 21778 | RSS: Main: 180281344 08:58:04 INFO - PROCESS | 21778 | 08:58:06 INFO - PROCESS | 21778 | Cycle 1(1): loaded http://localhost:55038/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55038/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:58:07 INFO - PROCESS | 21778 | RSS: Main: 190734336 08:58:07 INFO - PROCESS | 21778 | 08:58:09 INFO - PROCESS | 21778 | Cycle 1(1): loaded http://localhost:55038/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55038/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:58:10 INFO - PROCESS | 21778 | RSS: Main: 199999488 08:58:10 INFO - PROCESS | 21778 | 08:58:13 INFO - PROCESS | 21778 | Cycle 1(1): loaded http://localhost:55038/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55038/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:58:13 INFO - PROCESS | 21778 | RSS: Main: 195497984 08:58:13 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | Cycle 1(1): loaded http://localhost:55038/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55038/tests/kraken/driver.html?testName=ai-astar) 08:58:15 INFO - PROCESS | 21778 | RSS: Main: 246132736 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | __start_tp_report 08:58:15 INFO - PROCESS | 21778 | _x_x_mozilla_page_load 08:58:15 INFO - PROCESS | 21778 | _x_x_mozilla_page_load_details 08:58:15 INFO - PROCESS | 21778 | |i|pagename|runs| 08:58:15 INFO - PROCESS | 21778 | |0;ai-astar;104;112;114;112;113;112;115;114;113;112 08:58:15 INFO - PROCESS | 21778 | |1;audio-beat-detection;143;116;116;156;115;115;116;114;130;116 08:58:15 INFO - PROCESS | 21778 | |2;audio-dft;161;160;160;161;160;161;159;160;161;163 08:58:15 INFO - PROCESS | 21778 | |3;audio-fft;83;75;75;107;77;77;75;75;94;75 08:58:15 INFO - PROCESS | 21778 | |4;audio-oscillator;81;82;114;82;82;81;100;82;81;108 08:58:15 INFO - PROCESS | 21778 | |5;imaging-gaussian-blur;113;112;112;113;113;113;113;113;113;113 08:58:15 INFO - PROCESS | 21778 | |6;imaging-darkroom;146;146;146;147;147;146;146;147;147;146 08:58:15 INFO - PROCESS | 21778 | |7;imaging-desaturate;93;94;93;94;94;93;94;94;94;93 08:58:15 INFO - PROCESS | 21778 | |8;json-parse-financial;58;57;57;62;56;57;57;58;56;59 08:58:15 INFO - PROCESS | 21778 | |9;json-stringify-tinderbox;55;52;54;52;53;54;53;53;53;52 08:58:15 INFO - PROCESS | 21778 | |10;stanford-crypto-aes;75;79;76;79;79;83;79;75;75;87 08:58:15 INFO - PROCESS | 21778 | |11;stanford-crypto-ccm;121;126;129;131;121;119;119;122;168;121 08:58:15 INFO - PROCESS | 21778 | |12;stanford-crypto-pbkdf2;164;158;166;167;163;163;160;160;165;158 08:58:15 INFO - PROCESS | 21778 | |13;stanford-crypto-sha256-iterative;61;59;63;59;59;60;63;60;58;58 08:58:15 INFO - PROCESS | 21778 | __end_tp_report 08:58:15 INFO - PROCESS | 21778 | __start_cc_report 08:58:15 INFO - PROCESS | 21778 | _x_x_mozilla_cycle_collect,1652 08:58:15 INFO - PROCESS | 21778 | __end_cc_report 08:58:15 INFO - PROCESS | 21778 | __startTimestamp1494950295605__endTimestamp 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | ------- Summary: start ------- 08:58:15 INFO - PROCESS | 21778 | Number of tests: 14 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:3.03 (2.7%) stddev-sans-first:1.12 08:58:15 INFO - PROCESS | 21778 | Values: 104.0 112.0 114.0 112.0 113.0 112.0 115.0 114.0 113.0 112.0 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | [#1] audio-beat-detection Cycles:10 Average:123.70 Median:116.00 stddev:14.67 (12.7%) stddev-sans-first:13.80 08:58:15 INFO - PROCESS | 21778 | Values: 143.0 116.0 116.0 156.0 115.0 115.0 116.0 114.0 130.0 116.0 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:1.07 (0.7%) stddev-sans-first:1.13 08:58:15 INFO - PROCESS | 21778 | Values: 161.0 160.0 160.0 161.0 160.0 161.0 159.0 160.0 161.0 163.0 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.00 stddev:10.85 (14.3%) stddev-sans-first:11.49 08:58:15 INFO - PROCESS | 21778 | Values: 83.0 75.0 75.0 107.0 77.0 77.0 75.0 75.0 94.0 75.0 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:81.50 stddev:12.88 (15.8%) stddev-sans-first:13.31 08:58:15 INFO - PROCESS | 21778 | Values: 81.0 82.0 114.0 82.0 82.0 81.0 100.0 82.0 81.0 108.0 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 08:58:15 INFO - PROCESS | 21778 | Values: 113.0 112.0 112.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 08:58:15 INFO - PROCESS | 21778 | Values: 146.0 146.0 146.0 147.0 147.0 146.0 146.0 147.0 147.0 146.0 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:94.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 08:58:15 INFO - PROCESS | 21778 | Values: 93.0 94.0 93.0 94.0 94.0 93.0 94.0 94.0 94.0 93.0 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:57.50 stddev:1.77 (3.1%) stddev-sans-first:1.87 08:58:15 INFO - PROCESS | 21778 | Values: 58.0 57.0 57.0 62.0 56.0 57.0 57.0 58.0 56.0 59.0 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | [#9] json-stringify-tinderbox Cycles:10 Average:53.10 Median:53.00 stddev:0.99 (1.9%) stddev-sans-first:0.78 08:58:15 INFO - PROCESS | 21778 | Values: 55.0 52.0 54.0 52.0 53.0 54.0 53.0 53.0 53.0 52.0 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:79.00 stddev:3.89 (4.9%) stddev-sans-first:3.89 08:58:15 INFO - PROCESS | 21778 | Values: 75.0 79.0 76.0 79.0 79.0 83.0 79.0 75.0 75.0 87.0 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:124.00 stddev:14.75 (11.9%) stddev-sans-first:15.44 08:58:15 INFO - PROCESS | 21778 | Values: 121.0 126.0 129.0 131.0 121.0 119.0 119.0 122.0 168.0 121.0 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.40 Median:163.50 stddev:3.24 (2.0%) stddev-sans-first:3.38 08:58:15 INFO - PROCESS | 21778 | Values: 164.0 158.0 166.0 167.0 163.0 163.0 160.0 160.0 165.0 158.0 08:58:15 INFO - PROCESS | 21778 | 08:58:15 INFO - PROCESS | 21778 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.00 Median:60.00 stddev:1.83 (3.0%) stddev-sans-first:1.90 08:58:15 INFO - PROCESS | 21778 | Values: 61.0 59.0 63.0 59.0 59.0 60.0 63.0 60.0 58.0 58.0 08:58:15 INFO - PROCESS | 21778 | -------- Summary: end -------- 08:58:15 INFO - PROCESS | 21778 | 08:58:16 INFO - TEST-INFO | 21778: exit 0 08:58:16 INFO - TEST-OK | kraken | took 59976ms 08:58:16 INFO - SUITE-END | took 1175s 08:58:17 INFO - Completed test suite (00:19:35) 08:58:17 INFO - javascript benchmark 08:58:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7955.144738561073, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3287.1453422110253, 3367.1582104473882, 3368.578927634045, 3391.9491207631904, 3414.7097496712777, 5682.318121825381, 5769.82690519284, 5770.307563092435, 5782.595218334713, 5792.015357389243, 5765.423457654243, 5817.243758311413, 5821.592488525805, 5825.970870145643, 5841.3282472515675, 5128.717920514379, 5168.870778230536, 5178.922316165261, 5191.402988656297, 5209.010288045278, 4999.650024498286, 5045.369328833895, 5215.765290561933, 5216.713080780555, 5226.27212555034, 5152.252923326134, 5164.070467315882, 5164.225366195082, 5183.352080989876, 5197.1424714922, 5234.947650523484, 5235.11003129467, 5237.450067742909, 5251.973740131294, 5271.657342272779, 4987.351644286256, 5071.087204303224, 5144.0226356992425, 5205.713685747282, 5214.504622060898, 5624.437556244384, 5648.548116150698, 5713.6000479966415, 5796.8840623187725, 5800.506956908625, 6168.599041062316, 6213.782517611907, 6216.2540495140875, 6221.253449586078, 6226.221722284715, 6210.875782484371, 6268.498520118425, 6269.7178626961895, 6287.9056814147825, 6288.993760998218, 5629.437056294379, 5641.68970706611, 5753.942460575383, 5785.566082543826, 5790.189373487715, 6328.95572230586, 6335.429811317003, 6335.524835637253, 6337.144485494399, 6350.4602108820345, 6037.577369584174, 6089.177960975212, 6099.085137229451, 6102.481289090477, 6103.6032657877095, 5988.850278743066, 6014.308354539284, 6118.984682626245, 6176.104464852571, 6201.224846894138, 6193.969030154821, 6215.409536094064, 6220.813375598739, 6227.750889964351, 6233.96883015582, 5972.462478376967, 5989.221401217901, 6005.129256257906, 6110.694465276719, 6234.407731265523, 4698.36572062774, 4767.475577686451, 4768.0, 4773.785179666854, 4799.136155492045, 4120.464339635828, 4206.915861682749, 4210.85262015828, 4233.111046680232, 4270.145970805851, 5827.88344233113, 5880.911786323209, 5913.70431478433, 5991.460768530853, 6025.758969641253, 6242.157308763259, 6250.718717657716, 6254.249490061131, 6257.530685198628, 6263.812085637438, 5965.7613695452565, 5977.312609049928, 5978.432048955342, 5980.252468441445, 6014.699265036731, 5117.0, 5144.202648589475, 5242.580593552507, 5270.341207349082, 5287.814926477555, 6525.1517302750335, 6525.477961762953, 6543.640099794557, 6544.247411547638, 6548.083268342436, 6224.439800417984, 6232.657203853832, 6271.122042914087, 6276.215473065867, 6292.056191571301, 6209.4101060401, 6234.37656234391, 6283.465905398092, 6534.8366290843105, 6539.280679125388, 6043.516518678407, 6051.213342265477, 6063.302720187058, 6080.7567697291615, 6093.177421048102, 6151.753929842756, 6176.598521096114, 6187.81436556904, 6190.659513726789, 6192.504599632109, 5825.805709829559, 5932.940670593239, 6002.549808764273, 6004.1294012368835, 6013.098035294741, 5958.0, 6003.7898673546215, 6007.068904319837, 6011.789587364421, 6030.6080104793045, 6060.2424696912885, 6065.181200537871, 6071.0, 6071.058985857199, 6077.027675571887, 5883.411658834082, 5919.911201331897, 5944.375840536854, 5945.91081133792, 6109.602875813058, 5848.210491583582, 5914.172015917689, 5951.494122999594, 6039.456448919618, 6041.909371359521], "value": 5687.972559780026, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.5272331154684, 834.1241696218971, 851.1148405658116, 918.6784625381114, 932.1377725603814, 32533.0, 43860.3420948686, 44734.552654473446, 44795.32807007898, 44841.32738008932, 46009.0, 46053.0, 46089.76955115228, 46208.53791462084, 46286.7685661572, 7802.141764405911, 7964.0, 9192.494412807293, 9342.486163261017, 9346.719598412059, 10616.150707943347, 10844.186685998542, 10859.511321990529, 10864.402457864815, 10867.456627168649, 11026.614068507604, 11029.61396351128, 11036.779264414707, 11073.833892491619, 11088.501017454193, 10844.13246940243, 10847.891521084766, 10888.564457421733, 10892.509837057352, 10897.782044359068, 10631.627893023784, 10684.839727404094, 10690.839637405445, 10714.142868570496, 10735.40955247461, 44549.55450445486, 44636.776816116035, 44894.77552612216, 44959.77520112378, 44978.325325120146, 4374.19077470669, 4374.300111982099, 4412.977935110336, 4515.480719717241, 4542.818287268506, 30696.693033069605, 31462.056138315886, 31675.366492670248, 32280.838595807105, 32604.836975815208, 4329.805158767863, 4456.108778244364, 4471.754053527055, 4576.8626941191815, 4578.175928332898, 211386.0, 212252.0, 214560.0, 215112.0, 215708.0, 54305.0, 54493.0, 54667.179992300145, 54713.1793023105, 54742.17886731702, 55901.7204913981, 56474.717626411606, 56574.43425565692, 56689.149662754266, 56759.14861277084, 16248.268827902773, 16342.591435213977, 16346.591335216715, 16349.50951471434, 16360.0, 12584.30786306762, 12794.616161515169, 14172.787408188886, 14181.78727319091, 14188.148711077369, 13383.732325353438, 13438.395272212774, 14055.367508462145, 14062.648433789238, 14141.929290353482, 5368.773803486347, 5393.352797664305, 5454.372747134131, 5520.751566179531, 5559.527440167551, 1038.6520515627349, 1039.2672620905169, 1046.131710680149, 1050.4590136079926, 1066.8826429092794, 29405.705942940724, 30089.548656770166, 32126.196845078593, 32558.511622325685, 33082.0, 1114.4817659788237, 1185.2888267039707, 1204.5242129358853, 1217.7503611759569, 1234.6234398508361, 12563.0, 15432.0, 15510.146941918078, 16026.67946641084, 16296.26666799997, 303935.0, 304207.0, 304657.0, 305209.0, 305497.0, 50612.74693626582, 50684.239736403986, 50806.74596626993, 50810.237846432334, 50832.0, 1502.3239542206052, 1531.3721374236509, 1531.6017835362886, 1539.5535294764618, 1566.7884835547056, 28558.857205713837, 29450.0, 29697.25756856096, 29811.70188298089, 29996.85001574978, 25764.742352576985, 25814.354641134123, 25889.352766180244, 25895.611565826905, 25905.870470647526, 287935.0, 289070.0, 290478.0, 290789.0, 290855.0, 29664.110076697732, 30425.239369015067, 31472.370552589276, 31618.0, 32393.514097288087, 38892.80553597327, 38901.22197555976, 38903.41644875386, 39037.21925561416, 39986.80006599949, 30629.23426914345, 30659.693403065685, 30952.69047309498, 30960.225994350323, 31052.534211986385, 30380.544291836082, 30434.239144021576, 30488.237794055327, 30838.0, 31267.06198814039, 34193.82903085568, 34439.65560344465, 34513.82743086369, 34920.30159396747, 34946.65053349434, 28931.566026509197, 28983.56524652174, 29387.559186612645, 29571.40857182801, 30029.54955675623, 23871.522569548164, 23972.40068998289, 24061.0, 24157.97904990995, 24283.150089746774], "value": 18654.6711494084, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.3102430841583, 467.2174108368482, 476.67347866711293, 488.3700026965215, 494.15499495862076, 20298.797012029892, 22073.558528829435, 22561.323160305215, 22705.659415108745, 22710.0, 23134.768652313473, 23524.411889702766, 23565.646515302284, 23679.526409471844, 23732.762672373316, 6713.328667133296, 6782.96608516958, 6936.514443988923, 6966.198887127969, 6967.338102880243, 6748.696308666097, 6869.759558415456, 7291.30732580404, 7564.281393267631, 7601.7719468416035, 7562.697492100337, 7584.241575842427, 7586.886196707053, 7608.581528015957, 7614.695412183506, 7428.962855185717, 7623.504472209316, 7624.19945905683, 7628.0, 7632.923670763277, 7334.0, 7565.886511702329, 7635.923640763577, 7645.923540764632, 7646.502977306457, 24854.0, 25224.0, 25413.0, 26474.73525264742, 26592.60111098335, 3226.8063916165106, 3314.950275745866, 3321.5017747338093, 3323.7341012718925, 3368.8820891268933, 16335.91832040844, 16628.33486660532, 16656.167191640492, 16761.245743941552, 17101.828981710274, 2159.4709296222527, 2288.6338185890177, 2314.6875171851752, 2330.953380932389, 2383.797377222938, 110246.0, 111652.0, 114045.42977285144, 114592.70814583745, 118385.0, 18083.909580452015, 18370.0, 18547.629047418977, 18700.568926039527, 18890.0, 32375.676243237736, 32441.837790810896, 32510.187245318582, 32768.0, 32821.507677384856, 10184.949075254577, 10453.84319235212, 10489.947550262199, 10696.94651526753, 10711.357318560755, 8713.869291960626, 8721.433106848035, 8726.86909696355, 8931.598078086501, 8983.281337492985, 9053.68312109073, 9585.856212156823, 9600.519974001272, 9655.420674759536, 9747.220222382193, 3401.727861771052, 3750.6249375062275, 3761.32296186689, 3907.5701672815967, 4032.9395059074136, 978.7650963768787, 987.4223579206101, 992.1864071461332, 995.9053889880449, 1005.9849102263472, 21045.263415780373, 21180.0, 21381.358559243246, 22015.899205039685, 22085.889570552044, 1049.1344640671477, 1078.0405439159154, 1078.099786678117, 1085.4898197846999, 1109.7392112853472, 8129.634166462523, 8137.5931203439595, 8316.501009939302, 8417.28453081495, 11743.765124697458, 586595.0, 589763.0, 595695.0, 596263.0, 598539.0, 80420.0, 99897.0, 111441.0, 125501.0, 129719.35140324426, 1443.3793468808512, 1451.8693317601467, 1494.7907292978991, 1498.992505037468, 1501.5795577238391, 18770.6245875079, 19157.425277241706, 19233.61532769365, 19265.711014334516, 19313.227470901005, 51416.74291628518, 53619.19571206357, 54212.0, 54270.45729542654, 55121.724391377786, 73575.0, 73735.0, 74991.0, 75015.62492187505, 75289.62355188189, 12490.750184996068, 12904.8064279034, 12929.676758081123, 12952.28762418076, 13038.54365097255, 14183.219922903927, 14389.856101438852, 14509.347079381241, 14513.564593062225, 14575.562733118024, 12340.197887137307, 12403.689907752378, 12691.682707932376, 12813.231206127663, 12828.55100071493, 11320.150988675718, 11863.169578129447, 12035.879641203475, 12164.635060948185, 12278.631641050782, 13175.341232938506, 13273.203607783564, 13458.192508449525, 13518.188908665512, 13732.862671373558, 13104.410301536644, 13242.271675057964, 13295.0, 13314.86685133175, 13398.129121607064, 12769.808452873029, 13084.542041028519, 13169.53906613264, 13211.405486752927, 13263.0], "value": 11808.82554600201, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1576.4561226376895, 1586.468533041431, 1599.176424141568, 1619.7246468100423, 1623.7239669256228, 10944.5622175113, 11723.53105875766, 11745.177837551375, 11753.236039657417, 11755.941220293887, 11521.0, 11544.191906566544, 11716.589919352824, 11730.120240981918, 11754.647360579196, 2611.308003379101, 2653.973460265392, 2654.66816647919, 2658.9468210635778, 2660.5344064788683, 2452.080469823816, 2460.1266550374626, 2469.8518088914634, 2471.5180539794746, 2472.3819045238693, 2435.6468312094735, 2484.8881800319027, 2485.2420011896365, 2486.676732024843, 2486.8632225227607, 2276.1805749930013, 2281.851679640818, 2350.435895385104, 2429.6355546667965, 2437.7805997460223, 2384.7615238476014, 2429.161939130997, 2430.586800243945, 2431.416460049593, 2442.242904699549, 11167.66497005083, 11317.151213659008, 11319.20765546412, 11319.603813866557, 11331.0, 6034.36639152887, 6166.660833654148, 6177.104319873638, 6183.845403864894, 6184.659843708594, 3502.2645244498685, 3545.1137215696076, 3577.2845430913662, 3592.4134623071345, 3669.412893936984, 1280.5966120672051, 1310.2204188507822, 1325.4830616059744, 1350.3653282956996, 1372.444160115155, 14837.0, 15209.239538023054, 16268.593285167966, 17193.484195474157, 17548.64902701939, 4809.326694262806, 4859.392575928009, 4933.925991110136, 4997.350344455198, 5146.202338637517, 11024.228304018641, 11267.26762760418, 12446.81329780054, 13565.25391103479, 13588.0, 6148.508119350441, 6245.219347581552, 6254.655993920287, 6258.280297765815, 6259.71831267594, 5738.282714660668, 5794.275715535558, 5797.043487824546, 5804.970975145097, 5875.970620146957, 4130.421740956269, 4176.895577610584, 4191.161767646422, 4233.322668373045, 4237.618614324726, 1018.134585602247, 1019.6686077024997, 1031.886492485826, 1036.3212096077082, 1039.293280569205, 1085.3108276244484, 1101.2346419238713, 1104.1553211792984, 1105.6075093341908, 1111.6220485035126, 7467.215942326086, 7476.514026588255, 7500.849983000309, 7686.769396918102, 7727.961360193163, 1053.0943388685723, 1080.8216644253766, 1084.311462221495, 1093.0818112785298, 1119.7424592343807, 4722.6221902247735, 4748.430188377417, 4780.139574876486, 4864.1001414737675, 4867.1969125093965, 14243.85756142446, 14760.926195368955, 14800.185989770665, 15367.308471118826, 15369.308381122877, 15222.021137252086, 15233.542993710207, 15241.085534867721, 15283.0, 15287.464938727091, 10296.433696146634, 10560.366378017343, 10762.19283553721, 10804.0, 10820.458977051116, 7506.43701722384, 7854.41091918097, 7882.132965373689, 7917.802054948673, 8053.6778528858185, 12928.806067908801, 12940.352982350656, 12969.351532423152, 13331.933340333237, 13998.44006239739, 13454.327283635583, 13482.797758033441, 13588.45646174142, 13618.18290902549, 13718.862811372157, 9795.167410770164, 10007.599696012368, 10009.24930630191, 10015.449150296803, 10199.184065274909, 10705.518251678537, 10872.67381978542, 10938.45307734626, 10950.233483656068, 10960.0, 9373.953130234306, 9538.475383853955, 9617.71146865595, 9763.46300953426, 9850.802983940137, 8996.820063598561, 9022.819543608959, 9065.229455496357, 9086.182243598108, 9207.355485115977, 8577.142285771373, 8586.184312490303, 8602.78493037679, 8673.696420625249, 8696.434731742416, 6511.73953041873, 6593.044008618819, 6605.678864227078, 6620.437262832713, 6649.63427011519, 6841.281665425257, 6921.307869213038, 6938.098047253825, 6955.130608673916, 7013.8246543834775], "value": 5536.078731008636, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.38087178795905, 432.80523764306065, 435.76686472737094, 439.0691733524928, 439.9120175964808, 5151.484851514847, 5241.7903283868645, 5252.789888404469, 5258.44786297439, 5272.182811664188, 4999.950000499994, 5157.6131790115705, 5161.870953226171, 5173.344529750481, 5178.715170665621, 4322.805473753689, 4326.524082350938, 4334.374609521429, 4368.978155109228, 4370.4099946507295, 4350.260455722535, 4352.39066530686, 4352.73883566987, 4364.7700701480835, 4387.561243875619, 4335.349697545362, 4348.434703488542, 4367.170237654851, 4374.234508960935, 4379.058502421979, 4108.85619003335, 4298.828046878121, 4328.372386004027, 4334.284843000901, 4361.127774445092, 4083.673306135502, 4086.468759061303, 4086.652634526039, 4106.753594784323, 4109.157622687342, 5060.114479966028, 5085.542301192873, 5110.616703747197, 5112.744362781883, 5134.537891589737, 4947.9270183249255, 4998.925016124761, 5066.71997601081, 5101.923471147936, 5113.923291150636, 5442.945570544283, 5448.665077056146, 5460.7542660579975, 5471.562275017989, 5513.9448605513835, 4601.493835678073, 4607.470140933802, 4610.585047345755, 4637.188492013918, 4649.442066951953, 5465.600191845563, 5608.775648974036, 5625.437456254383, 5663.546916246691, 5709.74306156224, 4683.016566521069, 4715.693479923794, 4727.409073865767, 4760.500147484534, 4807.61539076873, 4827.565519103297, 4838.395200599925, 4858.95141048585, 4861.854144375674, 4864.586510146677, 4875.756212189376, 5109.821156259513, 5127.359245041551, 5141.53726164647, 5160.0969830279555, 5477.397486276506, 5478.945210547923, 5549.72251387429, 5588.451333403318, 5603.5237004854225, 1568.4275239537715, 1644.8272931342037, 1653.9586510337338, 1670.4237038222036, 1686.5783554111472, 1256.2462522486435, 1268.733565951142, 1276.387334079647, 1292.3796577642597, 1294.9158304710163, 5248.740302327414, 5311.442298558673, 5339.41266460689, 5340.946590534123, 5343.332083489564, 5588.30146231721, 5621.213030175779, 5657.915131273035, 5660.0, 5716.485516303553, 5296.788128474895, 5395.838124856261, 5400.243965844785, 5427.945720542744, 5473.370562385297, 2932.0, 2954.2466670998756, 2963.703629637019, 2997.2806526433933, 2998.710554461559, 5816.272965879265, 5895.970520147372, 5901.262342207224, 5901.7639294427745, 5906.8818623627285, 5406.0, 5443.265159203536, 5537.0, 5690.943090569124, 5789.0, 5692.089265717548, 5869.207656966255, 5891.793787217427, 5913.556483263773, 5919.733611987471, 5603.663780173202, 5615.466530679579, 5681.602287839812, 5692.117721753135, 5696.772129114955, 5376.489233522809, 5502.532284755841, 5545.922013282506, 5570.944290557042, 5637.295338082739, 5815.156802263564, 5835.095560188178, 5847.853803654943, 5861.853453663693, 5862.895972818958, 4501.482329532015, 4636.6058884995155, 4652.116097941381, 4687.62499000086, 4693.014466961839, 5653.858653533694, 5669.262995810518, 5670.914936275876, 5675.148727690879, 5684.408365657622, 5482.369527504388, 5499.725013749377, 5520.0, 5576.776928922797, 5582.432081242674, 5284.41871394154, 5489.945100548944, 5549.751305956128, 5555.9443705695785, 5558.777648893999, 5541.891621675601, 5556.833295001156, 5570.91643625354, 5590.552755779447, 5602.607817452739, 5503.917441238465, 5537.421834777069, 5563.7496312666835, 5575.515302935665, 5645.435456454322, 5208.479152084761, 5360.463953604608, 5451.727413629382, 5466.753996070107, 5468.72656367188], "value": 4431.594542790529, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [889.484099222451, 900.4507250577151, 900.8739076154804, 920.6041402197053, 924.5839372282474, 17841.28634854606, 19361.903190484027, 19580.0, 19670.11484483198, 20285.492862678402, 18411.90794046031, 19124.904375478138, 20150.0, 20327.796722032774, 20339.796602034014, 880.7269746378611, 898.707919926025, 914.0448231597962, 916.120524296676, 917.1241464401514, 900.9099090091004, 911.5806728904712, 911.5988964855443, 914.1909410172012, 916.0061333453211, 948.7296120605629, 953.7329547726648, 954.89973552777, 954.9427034377959, 955.4506158958627, 955.5652178258875, 956.1967946924582, 958.8801399825056, 959.5873774277059, 959.721680712596, 701.5755467941873, 702.3608516250188, 704.989425158623, 705.9188193357778, 708.0052526200645, 18584.163712632828, 18819.964901930387, 18956.0, 19098.427047188467, 19104.904475477673, 17898.194581243875, 18068.819311806845, 18198.181081851348, 18330.450086497425, 18701.15844786988, 13656.795148072786, 13681.2993113173, 13746.175229486264, 13782.241976691277, 13865.37605807741, 4224.556421575721, 4264.829406823724, 4301.0, 4353.216421044211, 4407.07451435199, 51137.48862511364, 54664.18003729947, 64613.35386646167, 89843.99999999935, 98265.0, 13577.932110339385, 13788.862111378958, 13808.309584520734, 13889.305534723426, 13912.095713778572, 27101.86449067742, 27396.31509212246, 27484.58773118405, 27559.724402756117, 27614.861925690242, 10331.690049298533, 10345.586176553004, 10370.0, 10376.481175941173, 10381.169506439466, 5282.6302158849285, 5301.443348448434, 5378.78484860609, 5418.024755543961, 5426.403095659474, 4010.7393019453643, 4175.916481670411, 4194.266003449384, 4216.072464057902, 4233.0, 659.0378048049901, 659.6470888074857, 662.3145044878528, 676.3709749932511, 676.4419354032942, 851.9744407667656, 853.3258725606701, 856.4732689395977, 856.8843206167212, 873.1966590736411, 11182.440877956069, 11272.0, 11308.773824523463, 11486.540538378371, 11773.293602383714, 748.4498893313411, 749.61394881636, 757.5870258263823, 766.1495739728897, 767.1331395523023, 5466.972665136649, 5472.781088756406, 5557.916631250535, 5635.126555383922, 5700.914486282709, 86474.56762716146, 88433.55783221043, 88664.5566772162, 90947.0, 92627.0, 76532.0, 76864.0, 77527.0, 79875.20124798677, 80349.0, 67122.32877671259, 67398.66300668563, 68377.65811170913, 68871.31128688648, 70406.295937041, 10559.63041293551, 10847.40339281347, 11048.889511105108, 11124.0, 11160.274582152135, 46068.53931460642, 46387.30419043785, 46740.7662961683, 46929.53070469388, 48796.0, 52950.470495294554, 54305.0, 55859.720701397855, 58604.0, 60201.0, 12176.208546444454, 12407.875921240671, 12503.374831258583, 12639.368031598568, 12863.549775757803, 16131.67736645237, 16521.91739041297, 16743.832561674226, 16774.412895548598, 17730.20214090343, 15085.24573771285, 15406.0, 15783.68432631364, 16192.595185120466, 16396.590085247964, 14650.0, 14671.26643667791, 15469.690606188038, 15496.92251538735, 15572.68854622924, 14900.776488352467, 14967.775483367976, 15568.610784730472, 15708.450204242796, 15755.763663544827, 10349.0, 10430.165586753194, 10771.0, 10903.509342079784, 10984.505697243483, 12247.326397048248, 12689.302088385228, 12868.0, 12927.703644692518, 13117.672058198621], "value": 8243.971262203022, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1459.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 114.0, 112.0, 113.0, 112.0, 115.0, 114.0, 113.0, 112.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 116.0, 116.0, 156.0, 115.0, 115.0, 116.0, 114.0, 130.0, 116.0], "value": 123.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 160.0, 161.0, 159.0, 160.0, 161.0, 163.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 75.0, 75.0, 107.0, 77.0, 77.0, 75.0, 75.0, 94.0, 75.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 114.0, 82.0, 82.0, 81.0, 100.0, 82.0, 81.0, 108.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 62.0, 56.0, 57.0, 57.0, 58.0, 56.0, 59.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 54.0, 52.0, 53.0, 54.0, 53.0, 53.0, 53.0, 52.0], "value": 53.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 79.0, 76.0, 79.0, 79.0, 83.0, 79.0, 75.0, 75.0, 87.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 126.0, 129.0, 131.0, 121.0, 119.0, 119.0, 122.0, 168.0, 121.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 158.0, 166.0, 167.0, 163.0, 163.0, 160.0, 160.0, 165.0, 158.0], "value": 162.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 63.0, 59.0, 59.0, 60.0, 63.0, 60.0, 58.0, 58.0], "value": 60.0, "unit": "score"}]}]} 08:58:17 INFO - Return code: 0 08:58:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:58:17 INFO - # TBPL SUCCESS # 08:58:17 INFO - Running post-action listener: _resource_record_post_action 08:58:17 INFO - [mozharness: 2017-05-16 15:58:17.398362Z] Finished run-tests step (success) 08:58:17 INFO - Running post-run listener: _resource_record_post_run 08:58:17 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328425472; Read time: 12; Write time: 152796 08:58:17 INFO - TinderboxPrint: CPU usage
13.1% 08:58:17 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:58:17 INFO - TinderboxPrint: I/O write bytes / time
328,425,472 / 152,796 08:58:17 INFO - TinderboxPrint: CPU idle
8,254.5 (86.9%) 08:58:17 INFO - TinderboxPrint: CPU user
1,198.8 (12.6%) 08:58:17 INFO - TinderboxPrint: Swap in / out
0 / 0 08:58:17 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 99131392; Read time: 0; Write time: 68980 08:58:17 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 229294080; Read time: 0; Write time: 83816 08:58:18 INFO - Running post-run listener: _upload_blobber_files 08:58:18 INFO - Blob upload gear active. 08:58:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:58:18 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 08:58:18 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'] 08:58:18 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 08:58:18 INFO - (blobuploader) - INFO - Open directory for files ... 08:58:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:58:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:58:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:19 INFO - (blobuploader) - INFO - Done attempting. 08:58:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:58:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:58:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:22 INFO - (blobuploader) - INFO - Done attempting. 08:58:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:58:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:58:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:23 INFO - (blobuploader) - INFO - Done attempting. 08:58:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:58:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:58:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:58:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:58:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:58:24 INFO - (blobuploader) - INFO - Done attempting. 08:58:24 INFO - (blobuploader) - INFO - Iteration through files over. 08:58:24 INFO - Return code: 0 08:58:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:58:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:58:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6411d89d12de637d192869478f03be7bd35501bcb38e43954576cf5047405af58a219a4aed37e437701127a8b380a15696ee40f31882a8ece94540c0a33a0e51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/654f046512e96043a27d53783c301cb6c3ea748141c6be61b26c42bf84bad7f4e72cfd77b5164f9ce9812f39b583199d0500b9d8171b66df0e51978830e8a13f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/354d5b73c4b83ca5c1b79c582265fd631388cbd3f5e05ede7fb65627a08501e2a0c6b215c78ad2ec9ed4c6ca99888771ebadc2337ea392a678be0cbfdcb565b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a4c278b5a684123b1555535f03bf7c6feaa5b2b315304c38e4e67cc76bc661d25e2376e246fbcb763eba6dc988af0bd7df8e8912eaecad7605db784815969839"} 08:58:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:58:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:58:24 INFO - Contents: 08:58:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6411d89d12de637d192869478f03be7bd35501bcb38e43954576cf5047405af58a219a4aed37e437701127a8b380a15696ee40f31882a8ece94540c0a33a0e51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/654f046512e96043a27d53783c301cb6c3ea748141c6be61b26c42bf84bad7f4e72cfd77b5164f9ce9812f39b583199d0500b9d8171b66df0e51978830e8a13f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/354d5b73c4b83ca5c1b79c582265fd631388cbd3f5e05ede7fb65627a08501e2a0c6b215c78ad2ec9ed4c6ca99888771ebadc2337ea392a678be0cbfdcb565b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a4c278b5a684123b1555535f03bf7c6feaa5b2b315304c38e4e67cc76bc661d25e2376e246fbcb763eba6dc988af0bd7df8e8912eaecad7605db784815969839"} 08:58:24 INFO - Running post-run listener: copy_logs_to_upload_dir 08:58:24 INFO - Copying logs to upload dir... 08:58:24 INFO - mkdir: /builds/slave/test/build/upload/logs 08:58:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.002862 ========= master_lag: -0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-05-16 08:58:23.961419) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 08:58:23.966159) ========= 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=31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668 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/6411d89d12de637d192869478f03be7bd35501bcb38e43954576cf5047405af58a219a4aed37e437701127a8b380a15696ee40f31882a8ece94540c0a33a0e51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/654f046512e96043a27d53783c301cb6c3ea748141c6be61b26c42bf84bad7f4e72cfd77b5164f9ce9812f39b583199d0500b9d8171b66df0e51978830e8a13f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/354d5b73c4b83ca5c1b79c582265fd631388cbd3f5e05ede7fb65627a08501e2a0c6b215c78ad2ec9ed4c6ca99888771ebadc2337ea392a678be0cbfdcb565b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a4c278b5a684123b1555535f03bf7c6feaa5b2b315304c38e4e67cc76bc661d25e2376e246fbcb763eba6dc988af0bd7df8e8912eaecad7605db784815969839"} build_url:https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011333 build_url: 'https://queue.taskcluster.net/v1/task/WulGc60wQ2esZXSXyvJz-g/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6411d89d12de637d192869478f03be7bd35501bcb38e43954576cf5047405af58a219a4aed37e437701127a8b380a15696ee40f31882a8ece94540c0a33a0e51", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/654f046512e96043a27d53783c301cb6c3ea748141c6be61b26c42bf84bad7f4e72cfd77b5164f9ce9812f39b583199d0500b9d8171b66df0e51978830e8a13f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/354d5b73c4b83ca5c1b79c582265fd631388cbd3f5e05ede7fb65627a08501e2a0c6b215c78ad2ec9ed4c6ca99888771ebadc2337ea392a678be0cbfdcb565b6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/a4c278b5a684123b1555535f03bf7c6feaa5b2b315304c38e4e67cc76bc661d25e2376e246fbcb763eba6dc988af0bd7df8e8912eaecad7605db784815969839"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 08:58:23.995490) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 08:58:23.995878) ========= 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=31acecf42b6a79ec49471d4a000001bb-1494944198.682462-946382668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004443 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 08:58:24.074519) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 08:58:24.077897) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 08:58:24.078249) ========= ========= Total master_lag: 0.11 =========