builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-071 starttime: 1502274003.91 results: success (0) revision: c93fa2271ee761546d469fb1edc68254f2577b5a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:03.908670) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:03.909217) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:03.909540) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:03.926878) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:03.927280) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-09 03:20:04-- 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% 46.1M=0s 2017-08-09 03:20:04 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.081787 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:04.022804) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:04.026196) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019305 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 03:20:04.076534) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 mins, 19 secs) (at 2017-08-09 03:20:04.076935) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c93fa2271ee761546d469fb1edc68254f2577b5a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev c93fa2271ee761546d469fb1edc68254f2577b5a --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=dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-09 03:20:04,800 truncating revision to first 12 chars 2017-08-09 03:20:04,800 Setting DEBUG logging. 2017-08-09 03:20:04,800 attempt 1/10 2017-08-09 03:20:04,801 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c93fa2271ee7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-09 03:20:05,815 attempt 1/10 2017-08-09 03:20:06,652 current task status: no status available at this point. state: PENDING 2017-08-09 03:20:06,652 sleeping for 10.00s (attempt 1/10) 2017-08-09 03:20:16,663 attempt 2/10 2017-08-09 03:20:17,220 current task status: no status available at this point. state: PENDING 2017-08-09 03:20:17,220 sleeping for 14.00s (attempt 2/10) 2017-08-09 03:20:31,235 attempt 3/10 2017-08-09 03:20:31,766 current task status: no status available at this point. state: PENDING 2017-08-09 03:20:31,766 sleeping for 20.00s (attempt 3/10) 2017-08-09 03:20:51,786 attempt 4/10 2017-08-09 03:20:52,313 current task status: no status available at this point. state: PENDING 2017-08-09 03:20:52,313 sleeping for 31.00s (attempt 4/10) 2017-08-09 03:21:23,345 attempt 5/10 2017-08-09 03:21:23,683 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-09 03:21:23,899 unpacking tar archive at: mozilla-central-c93fa2271ee7/testing/mozharness/ program finished with exit code 0 elapsedTime=79.444095 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 mins, 19 secs) (at 2017-08-09 03:21:23.541583) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-09 03:21:23.541947) ========= script_repo_revision: c93fa2271ee761546d469fb1edc68254f2577b5a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-09 03:21:23.542358) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-09 03:21:23.542620) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-09 03:21:23.551868) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-08-09 03:21:23.552173) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:21:24 INFO - MultiFileLogger online at 20170809 03:21:24 in /builds/slave/test 03:21:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:21:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:21:24 INFO - {'append_to_log': False, 03:21:24 INFO - 'base_work_dir': '/builds/slave/test', 03:21:24 INFO - 'blob_upload_branch': 'Firefox', 03:21:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:21:24 INFO - 'branch': 'Firefox', 03:21:24 INFO - 'buildbot_json_path': 'buildprops.json', 03:21:24 INFO - 'code_coverage': False, 03:21:24 INFO - 'config_files': ('talos/linux_config.py',), 03:21:24 INFO - 'default_actions': ('clobber', 03:21:24 INFO - 'read-buildbot-config', 03:21:24 INFO - 'download-and-extract', 03:21:24 INFO - 'populate-webroot', 03:21:24 INFO - 'create-virtualenv', 03:21:24 INFO - 'install', 03:21:24 INFO - 'setup-mitmproxy', 03:21:24 INFO - 'run-tests'), 03:21:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:21:24 INFO - 'disable_ccov_upload': False, 03:21:24 INFO - 'download_minidump_stackwalk': True, 03:21:24 INFO - 'download_symbols': 'ondemand', 03:21:24 INFO - 'enable_stylo': False, 03:21:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:21:24 INFO - 'tooltool.py': '/tools/tooltool.py', 03:21:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:21:24 INFO - '/tools/misc-python/virtualenv.py')}, 03:21:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:21:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:21:24 INFO - 'gecko_profile': False, 03:21:24 INFO - 'gecko_profile_interval': 0, 03:21:24 INFO - 'installer_path': 'installer.exe', 03:21:24 INFO - 'log_level': 'info', 03:21:24 INFO - 'log_name': 'talos', 03:21:24 INFO - 'log_to_console': True, 03:21:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:21:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:21:24 INFO - 'opt_config_files': (), 03:21:24 INFO - 'pip_index': False, 03:21:24 INFO - 'suite': 'dromaeojs-e10s', 03:21:24 INFO - 'system_bits': '32', 03:21:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:21:24 INFO - 'title': 'talos-linux64-ix-071', 03:21:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:21:24 INFO - 'use_talos_json': True, 03:21:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:21:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:21:24 INFO - 'work_dir': 'build'} 03:21:24 INFO - [mozharness: 2017-08-09 10:21:24.379873Z] Running clobber step. 03:21:24 INFO - Running pre-action listener: _resource_record_pre_action 03:21:24 INFO - Running main action method: clobber 03:21:24 INFO - rmtree: /builds/slave/test/build 03:21:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:21:25 INFO - Running post-action listener: _resource_record_post_action 03:21:25 INFO - [mozharness: 2017-08-09 10:21:25.411248Z] Finished clobber step (success) 03:21:25 INFO - [mozharness: 2017-08-09 10:21:25.411351Z] Running read-buildbot-config step. 03:21:25 INFO - Running pre-action listener: _resource_record_pre_action 03:21:25 INFO - Running main action method: read_buildbot_config 03:21:25 INFO - Using buildbot properties: 03:21:25 INFO - { 03:21:25 INFO - "project": "", 03:21:25 INFO - "product": "firefox", 03:21:25 INFO - "who": "cbook@mozilla.com", 03:21:25 INFO - "installer_path": "public/build/target.tar.bz2", 03:21:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 03:21:25 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:21:25 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 03:21:25 INFO - "stage_platform": "linux64-stylo", 03:21:25 INFO - "basedir": "/builds/slave/test", 03:21:25 INFO - "buildnumber": 19, 03:21:25 INFO - "platform": "ubuntu64_hw_stylo", 03:21:25 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 03:21:25 INFO - "slavebuilddir": "test", 03:21:25 INFO - "taskId": "ZiBVSFpPQNaCv5glbNKmKw", 03:21:25 INFO - "branch": "mozilla-central", 03:21:25 INFO - "script_repo_revision": "production", 03:21:25 INFO - "revision": "c93fa2271ee761546d469fb1edc68254f2577b5a", 03:21:25 INFO - "slavename": "talos-linux64-ix-071", 03:21:25 INFO - "repo_path": "mozilla-central" 03:21:25 INFO - } 03:21:25 INFO - Finding installer, test and symbols from parent task. 03:21:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZiBVSFpPQNaCv5glbNKmKw'}, attempt #1 03:21:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZiBVSFpPQNaCv5glbNKmKw'}, attempt #1 03:21:26 INFO - Task dependencies: XrRJHuDETWmVhpX8_j42gA 03:21:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA'}, attempt #1 03:21:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2 03:21:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.test_packages.json 03:21:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.crashreporter-symbols.zip 03:21:26 INFO - Running post-action listener: _resource_record_post_action 03:21:26 INFO - [mozharness: 2017-08-09 10:21:26.865982Z] Finished read-buildbot-config step (success) 03:21:26 INFO - [mozharness: 2017-08-09 10:21:26.866203Z] Running download-and-extract step. 03:21:26 INFO - Running pre-action listener: _resource_record_pre_action 03:21:26 INFO - Running main action method: download_and_extract 03:21:26 INFO - mkdir: /builds/slave/test/build/tests 03:21: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')]} 03:21:26 INFO - trying https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.test_packages.json 03:21:26 INFO - Downloading https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:21:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:21:27 INFO - Downloaded 989 bytes. 03:21:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:21:27 INFO - Using the following test package requirements: 03:21:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:21:27 INFO - u'common': [u'target.common.tests.zip'], 03:21:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:21:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:21:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:21:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:21:27 INFO - u'mozbase': [u'target.common.tests.zip'], 03:21:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:21:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:21:27 INFO - u'web-platform': [u'target.common.tests.zip', 03:21:27 INFO - u'target.web-platform.tests.tar.gz'], 03:21:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:21:27 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:21:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.common.tests.zip 03:21:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:21:27 INFO - Fetch https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.common.tests.zip into memory 03:21:28 INFO - Content-Length response header: 54285505 03:21:28 INFO - Bytes received: 54285505 03:21:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:21:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.common.tests.zip 03:21:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:21:39 INFO - Fetch https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.common.tests.zip into memory 03:21:40 INFO - Content-Length response header: 54285505 03:21:40 INFO - Bytes received: 54285505 03:21:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.talos.tests.zip 03:21:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:21:52 INFO - Fetch https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.talos.tests.zip into memory 03:21:53 INFO - Content-Length response header: 14496008 03:21:53 INFO - Bytes received: 14496008 03:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:53 INFO - trying https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2 03:21:53 INFO - Downloading https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:21:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:21:55 INFO - Downloaded 61866904 bytes. 03:21:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2 03:21:55 INFO - mkdir: /builds/slave/test/properties 03:21:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:21:55 INFO - Writing to file /builds/slave/test/properties/build_url 03:21:55 INFO - Contents: 03:21:55 INFO - build_url:https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2 03:21:55 INFO - Running post-action listener: _resource_record_post_action 03:21:55 INFO - Running post-action listener: set_extra_try_arguments 03:21:55 INFO - [mozharness: 2017-08-09 10:21:55.135161Z] Finished download-and-extract step (success) 03:21:55 INFO - [mozharness: 2017-08-09 10:21:55.135352Z] Running populate-webroot step. 03:21:55 INFO - Running pre-action listener: _resource_record_pre_action 03:21:55 INFO - Running main action method: populate_webroot 03:21:55 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:21:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:21:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:21:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:21:55 INFO - 'tests': ['damp', 'tps']}, 03:21:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:21:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:21:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:21:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:21:55 INFO - 'other-e10s': {'tests': ['a11yr', 03:21:55 INFO - 'ts_paint', 03:21:55 INFO - 'tpaint', 03:21:55 INFO - 'sessionrestore', 03:21:55 INFO - 'sessionrestore_many_windows', 03:21:55 INFO - 'sessionrestore_no_auto_restore', 03:21:55 INFO - 'tabpaint']}, 03:21:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:21:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:21:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:21:55 INFO - 'tsvgr_opacity', 03:21:55 INFO - 'tart', 03:21:55 INFO - 'tscrollx', 03:21:55 INFO - 'tsvg_static']}, 03:21:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:21:55 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:21:55 INFO - 'talos_options': ['--mitmproxy', 03:21:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:21:55 INFO - '--firstNonBlankPaint'], 03:21:55 INFO - 'tests': ['tp6_google', 03:21:55 INFO - 'tp6_youtube', 03:21:55 INFO - 'tp6_amazon', 03:21:55 INFO - 'tp6_facebook']}, 03:21:55 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:21:55 INFO - 'talos_options': ['--stylo', 03:21:55 INFO - '--mitmproxy', 03:21:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:21:55 INFO - '--firstNonBlankPaint'], 03:21:55 INFO - 'tests': ['tp6_google', 03:21:55 INFO - 'tp6_youtube', 03:21:55 INFO - 'tp6_amazon', 03:21:55 INFO - 'tp6_facebook']}, 03:21:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:21:55 INFO - 'talos_options': ['--stylo', 03:21:55 INFO - '--stylo-threads=1', 03:21:55 INFO - '--mitmproxy', 03:21:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:21:55 INFO - '--firstNonBlankPaint'], 03:21:55 INFO - 'tests': ['tp6_google', 03:21:55 INFO - 'tp6_youtube', 03:21:55 INFO - 'tp6_amazon', 03:21:55 INFO - 'tp6_facebook']}, 03:21:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:21:55 INFO - 'talos_options': ['--xperf_path', 03:21:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:55 INFO - 'tests': ['tp5n']}}} 03:21:55 INFO - Running post-action listener: _resource_record_post_action 03:21:55 INFO - [mozharness: 2017-08-09 10:21:55.149207Z] Finished populate-webroot step (success) 03:21:55 INFO - [mozharness: 2017-08-09 10:21:55.149297Z] Running create-virtualenv step. 03:21:55 INFO - Running pre-action listener: _resource_record_pre_action 03:21:55 INFO - Running main action method: create_virtualenv 03:21:55 INFO - Creating virtualenv /builds/slave/test/build/venv 03:21:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:21:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:21:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:21:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:21:55 INFO - Using real prefix '/usr' 03:21:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:21:55 INFO - Installing distribute.............................................................................................................................................................................................done. 03:21:57 INFO - Installing pip.................done. 03:21:57 INFO - Return code: 0 03:21:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:21:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:21:57 INFO - Reading from file tmpfile_stdout 03:21:57 INFO - Output received: 03:21:57 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 03:21:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:57 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 0x210d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247df00>, '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 0x2478ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2479050>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:21:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:21:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:21:57 INFO - Using env: {'DISPLAY': ':0', 03:21:57 INFO - 'HOME': '/home/cltbld', 03:21:57 INFO - 'LANG': 'en_US.UTF-8', 03:21:57 INFO - 'LANGUAGE': 'en_US:en', 03:21:57 INFO - 'LOGNAME': 'cltbld', 03:21:57 INFO - 'MAIL': '/var/mail/cltbld', 03:21:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:57 INFO - 'MOZ_NO_REMOTE': '1', 03:21:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:57 INFO - 'NO_EM_RESTART': '1', 03:21:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:57 INFO - 'PWD': '/builds/slave/test', 03:21:57 INFO - 'SHELL': '/bin/bash', 03:21:57 INFO - 'SHLVL': '1', 03:21:57 INFO - 'TERM': 'linux', 03:21:57 INFO - 'TMOUT': '86400', 03:21:57 INFO - 'USER': 'cltbld', 03:21:57 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 03:21:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:57 INFO - '_': '/tools/buildbot/bin/python'} 03:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:21:58 INFO - Cleaning up... 03:21:58 INFO - Return code: 0 03:21:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x210d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247df00>, '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 0x2478ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2479050>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:21:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:21:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:21:58 INFO - Using env: {'DISPLAY': ':0', 03:21:58 INFO - 'HOME': '/home/cltbld', 03:21:58 INFO - 'LANG': 'en_US.UTF-8', 03:21:58 INFO - 'LANGUAGE': 'en_US:en', 03:21:58 INFO - 'LOGNAME': 'cltbld', 03:21:58 INFO - 'MAIL': '/var/mail/cltbld', 03:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:58 INFO - 'MOZ_NO_REMOTE': '1', 03:21:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:21:58 INFO - 'NO_EM_RESTART': '1', 03:21:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:21:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:58 INFO - 'PWD': '/builds/slave/test', 03:21:58 INFO - 'SHELL': '/bin/bash', 03:21:58 INFO - 'SHLVL': '1', 03:21:58 INFO - 'TERM': 'linux', 03:21:58 INFO - 'TMOUT': '86400', 03:21:58 INFO - 'USER': 'cltbld', 03:21:58 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 03:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:58 INFO - '_': '/tools/buildbot/bin/python'} 03:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:58 INFO - Downloading/unpacking psutil>=3.1.1 03:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:21:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:59 INFO - Installing collected packages: psutil 03:21:59 INFO - Running setup.py install for psutil 03:21:59 INFO - building 'psutil._psutil_linux' extension 03:21:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:21:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:21:59 INFO - building 'psutil._psutil_posix' extension 03:21:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:21:59 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:22:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:22:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:22:00 INFO - Successfully installed psutil 03:22:00 INFO - Cleaning up... 03:22:00 INFO - Return code: 0 03:22:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247df00>, '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 0x2478ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2479050>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:22:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:22:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:22:00 INFO - Using env: {'DISPLAY': ':0', 03:22:00 INFO - 'HOME': '/home/cltbld', 03:22:00 INFO - 'LANG': 'en_US.UTF-8', 03:22:00 INFO - 'LANGUAGE': 'en_US:en', 03:22:00 INFO - 'LOGNAME': 'cltbld', 03:22:00 INFO - 'MAIL': '/var/mail/cltbld', 03:22:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:00 INFO - 'MOZ_NO_REMOTE': '1', 03:22:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:00 INFO - 'NO_EM_RESTART': '1', 03:22:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:00 INFO - 'PWD': '/builds/slave/test', 03:22:00 INFO - 'SHELL': '/bin/bash', 03:22:00 INFO - 'SHLVL': '1', 03:22:00 INFO - 'TERM': 'linux', 03:22:00 INFO - 'TMOUT': '86400', 03:22:00 INFO - 'USER': 'cltbld', 03:22:00 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 03:22:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:00 INFO - '_': '/tools/buildbot/bin/python'} 03:22:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:22:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:22:01 INFO - Installing collected packages: mozsystemmonitor 03:22:01 INFO - Running setup.py install for mozsystemmonitor 03:22:01 INFO - Successfully installed mozsystemmonitor 03:22:01 INFO - Cleaning up... 03:22:01 INFO - Return code: 0 03:22:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x210d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247df00>, '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 0x2478ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2479050>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:22:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:22:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:22:01 INFO - Using env: {'DISPLAY': ':0', 03:22:01 INFO - 'HOME': '/home/cltbld', 03:22:01 INFO - 'LANG': 'en_US.UTF-8', 03:22:01 INFO - 'LANGUAGE': 'en_US:en', 03:22:01 INFO - 'LOGNAME': 'cltbld', 03:22:01 INFO - 'MAIL': '/var/mail/cltbld', 03:22:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:01 INFO - 'MOZ_NO_REMOTE': '1', 03:22:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:01 INFO - 'NO_EM_RESTART': '1', 03:22:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:01 INFO - 'PWD': '/builds/slave/test', 03:22:01 INFO - 'SHELL': '/bin/bash', 03:22:01 INFO - 'SHLVL': '1', 03:22:01 INFO - 'TERM': 'linux', 03:22:01 INFO - 'TMOUT': '86400', 03:22:01 INFO - 'USER': 'cltbld', 03:22:01 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 03:22:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:01 INFO - '_': '/tools/buildbot/bin/python'} 03:22:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:01 INFO - Downloading/unpacking jsonschema==2.5.1 03:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:22:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:03 INFO - Downloading functools32-3.2.3-2.tar.gz 03:22:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:22:03 INFO - warning: no files found matching '*.txt' 03:22:03 INFO - no previously-included directories found matching 'build' 03:22:03 INFO - no previously-included directories found matching 'dist' 03:22:03 INFO - no previously-included directories found matching '.git*' 03:22:03 INFO - Installing collected packages: jsonschema, functools32 03:22:03 INFO - Running setup.py install for functools32 03:22:03 INFO - warning: no files found matching '*.txt' 03:22:03 INFO - no previously-included directories found matching 'build' 03:22:03 INFO - no previously-included directories found matching 'dist' 03:22:03 INFO - no previously-included directories found matching '.git*' 03:22:03 INFO - Successfully installed jsonschema functools32 03:22:03 INFO - Cleaning up... 03:22:03 INFO - Return code: 0 03:22:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:03 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 0x210d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247df00>, '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 0x2478ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2479050>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:22:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:22:03 INFO - Using env: {'DISPLAY': ':0', 03:22:03 INFO - 'HOME': '/home/cltbld', 03:22:03 INFO - 'LANG': 'en_US.UTF-8', 03:22:03 INFO - 'LANGUAGE': 'en_US:en', 03:22:03 INFO - 'LOGNAME': 'cltbld', 03:22:03 INFO - 'MAIL': '/var/mail/cltbld', 03:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:03 INFO - 'MOZ_NO_REMOTE': '1', 03:22:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:03 INFO - 'NO_EM_RESTART': '1', 03:22:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:03 INFO - 'PWD': '/builds/slave/test', 03:22:03 INFO - 'SHELL': '/bin/bash', 03:22:03 INFO - 'SHLVL': '1', 03:22:03 INFO - 'TERM': 'linux', 03:22:03 INFO - 'TMOUT': '86400', 03:22:03 INFO - 'USER': 'cltbld', 03:22:03 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 03:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:03 INFO - '_': '/tools/buildbot/bin/python'} 03:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:22:03 INFO - Cleaning up... 03:22:03 INFO - Return code: 0 03:22:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:03 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 0x210d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247df00>, '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 0x2478ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2479050>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:22:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:22:03 INFO - Using env: {'DISPLAY': ':0', 03:22:03 INFO - 'HOME': '/home/cltbld', 03:22:03 INFO - 'LANG': 'en_US.UTF-8', 03:22:03 INFO - 'LANGUAGE': 'en_US:en', 03:22:03 INFO - 'LOGNAME': 'cltbld', 03:22:03 INFO - 'MAIL': '/var/mail/cltbld', 03:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:03 INFO - 'MOZ_NO_REMOTE': '1', 03:22:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:03 INFO - 'NO_EM_RESTART': '1', 03:22:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:03 INFO - 'PWD': '/builds/slave/test', 03:22:03 INFO - 'SHELL': '/bin/bash', 03:22:03 INFO - 'SHLVL': '1', 03:22:03 INFO - 'TERM': 'linux', 03:22:03 INFO - 'TMOUT': '86400', 03:22:03 INFO - 'USER': 'cltbld', 03:22:03 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 03:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:03 INFO - '_': '/tools/buildbot/bin/python'} 03:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:03 INFO - Downloading/unpacking blobuploader==1.2.4 03:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:04 INFO - Downloading blobuploader-1.2.4.tar.gz 03:22:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:22:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:22:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:05 INFO - Downloading docopt-0.6.1.tar.gz 03:22:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:22:05 INFO - Installing collected packages: blobuploader, requests, docopt 03:22:05 INFO - Running setup.py install for blobuploader 03:22:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:22:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:22:05 INFO - Running setup.py install for requests 03:22:05 INFO - Running setup.py install for docopt 03:22:06 INFO - Successfully installed blobuploader requests docopt 03:22:06 INFO - Cleaning up... 03:22:06 INFO - Return code: 0 03:22:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22: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')]} 03:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:06 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 0x210d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247df00>, '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 0x2478ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2479050>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:22:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:22:06 INFO - Using env: {'DISPLAY': ':0', 03:22:06 INFO - 'HOME': '/home/cltbld', 03:22:06 INFO - 'LANG': 'en_US.UTF-8', 03:22:06 INFO - 'LANGUAGE': 'en_US:en', 03:22:06 INFO - 'LOGNAME': 'cltbld', 03:22:06 INFO - 'MAIL': '/var/mail/cltbld', 03:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:06 INFO - 'MOZ_NO_REMOTE': '1', 03:22:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:06 INFO - 'NO_EM_RESTART': '1', 03:22:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:06 INFO - 'PWD': '/builds/slave/test', 03:22:06 INFO - 'SHELL': '/bin/bash', 03:22:06 INFO - 'SHLVL': '1', 03:22:06 INFO - 'TERM': 'linux', 03:22:06 INFO - 'TMOUT': '86400', 03:22:06 INFO - 'USER': 'cltbld', 03:22:06 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 03:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:06 INFO - '_': '/tools/buildbot/bin/python'} 03:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:06 INFO - Running setup.py (path:/tmp/pip-FMUMZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:06 INFO - Running setup.py (path:/tmp/pip-xtbmwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:06 INFO - Running setup.py (path:/tmp/pip-OylG1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:06 INFO - Running setup.py (path:/tmp/pip-4e0u4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:06 INFO - Running setup.py (path:/tmp/pip-IjDMfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:06 INFO - Running setup.py (path:/tmp/pip-J0eD0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:06 INFO - Running setup.py (path:/tmp/pip-LGpoIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:06 INFO - Running setup.py (path:/tmp/pip-J3jgdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:06 INFO - Running setup.py (path:/tmp/pip-gag6vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:06 INFO - Running setup.py (path:/tmp/pip-IDlZvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:06 INFO - Running setup.py (path:/tmp/pip-53Vlqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:07 INFO - Running setup.py (path:/tmp/pip-_8jPjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:07 INFO - Running setup.py (path:/tmp/pip-YuDoJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:07 INFO - Running setup.py (path:/tmp/pip-VCu3fA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:07 INFO - Running setup.py (path:/tmp/pip-UQDx7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:07 INFO - Running setup.py (path:/tmp/pip-R0wbKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:07 INFO - Running setup.py (path:/tmp/pip-IaqW6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:22:07 INFO - Running setup.py install for manifestparser 03:22:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:22:07 INFO - Running setup.py install for mozcrash 03:22:07 INFO - Running setup.py install for mozdebug 03:22:07 INFO - Running setup.py install for mozdevice 03:22:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:22:07 INFO - Running setup.py install for mozfile 03:22:07 INFO - Running setup.py install for mozhttpd 03:22:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:22:08 INFO - Running setup.py install for mozinfo 03:22:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:22:08 INFO - Running setup.py install for mozInstall 03:22:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:22:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:22:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:22:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:22:08 INFO - Running setup.py install for mozleak 03:22:08 INFO - Running setup.py install for mozlog 03:22:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:22:08 INFO - Running setup.py install for moznetwork 03:22:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:22:08 INFO - Running setup.py install for mozprocess 03:22:08 INFO - Running setup.py install for mozprofile 03:22:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:22:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:22:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:22:08 INFO - Running setup.py install for mozrunner 03:22:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:22:08 INFO - Running setup.py install for mozscreenshot 03:22:09 INFO - Running setup.py install for moztest 03:22:09 INFO - Running setup.py install for mozversion 03:22:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:22:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:22:09 INFO - Cleaning up... 03:22:09 INFO - Return code: 0 03:22:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:09 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 0x210d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247df00>, '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 0x2478ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2479050>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:22:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:22:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:22:09 INFO - Using env: {'DISPLAY': ':0', 03:22:09 INFO - 'HOME': '/home/cltbld', 03:22:09 INFO - 'LANG': 'en_US.UTF-8', 03:22:09 INFO - 'LANGUAGE': 'en_US:en', 03:22:09 INFO - 'LOGNAME': 'cltbld', 03:22:09 INFO - 'MAIL': '/var/mail/cltbld', 03:22:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:09 INFO - 'MOZ_NO_REMOTE': '1', 03:22:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:09 INFO - 'NO_EM_RESTART': '1', 03:22:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:09 INFO - 'PWD': '/builds/slave/test', 03:22:09 INFO - 'SHELL': '/bin/bash', 03:22:09 INFO - 'SHLVL': '1', 03:22:09 INFO - 'TERM': 'linux', 03:22:09 INFO - 'TMOUT': '86400', 03:22:09 INFO - 'USER': 'cltbld', 03:22:09 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 03:22:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:09 INFO - '_': '/tools/buildbot/bin/python'} 03:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:09 INFO - Running setup.py (path:/tmp/pip-Uo5dew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:09 INFO - Running setup.py (path:/tmp/pip-1JvXpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:09 INFO - Running setup.py (path:/tmp/pip-mAB4tM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:09 INFO - Running setup.py (path:/tmp/pip-Bd89Ih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:09 INFO - Running setup.py (path:/tmp/pip-CFfTQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:09 INFO - Running setup.py (path:/tmp/pip-_vUaBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:09 INFO - Running setup.py (path:/tmp/pip-zs94RW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:22:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:09 INFO - Running setup.py (path:/tmp/pip-Ddy9pj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:10 INFO - Running setup.py (path:/tmp/pip-HMWLpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:10 INFO - Running setup.py (path:/tmp/pip-2t2By4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:10 INFO - Running setup.py (path:/tmp/pip-1OL5mS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:10 INFO - Running setup.py (path:/tmp/pip-VcPjn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:10 INFO - Running setup.py (path:/tmp/pip-7gOpqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:10 INFO - Running setup.py (path:/tmp/pip-f9gzuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:10 INFO - Running setup.py (path:/tmp/pip-1DQOFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:10 INFO - Running setup.py (path:/tmp/pip-Bd2eh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:22:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:10 INFO - Running setup.py (path:/tmp/pip-B06pi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:22:10 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:22:10 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:22:10 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:22:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:11 INFO - Downloading blessings-1.6.tar.gz 03:22:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:22:11 INFO - Installing collected packages: blessings 03:22:11 INFO - Running setup.py install for blessings 03:22:11 INFO - Successfully installed blessings 03:22:12 INFO - Cleaning up... 03:22:12 INFO - Return code: 0 03:22:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:22:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:12 INFO - Reading from file tmpfile_stdout 03:22:12 INFO - Current package versions: 03:22:12 INFO - argparse == 1.2.1 03:22:12 INFO - blessings == 1.6 03:22:12 INFO - blobuploader == 1.2.4 03:22:12 INFO - docopt == 0.6.1 03:22:12 INFO - functools32 == 3.2.3-2 03:22:12 INFO - jsonschema == 2.5.1 03:22:12 INFO - manifestparser == 1.1 03:22:12 INFO - mozInstall == 1.13 03:22:12 INFO - mozcrash == 1.0 03:22:12 INFO - mozdebug == 0.1 03:22:12 INFO - mozdevice == 0.51 03:22:12 INFO - mozfile == 1.2 03:22:12 INFO - mozhttpd == 0.7 03:22:12 INFO - mozinfo == 0.10 03:22:12 INFO - mozleak == 0.1 03:22:12 INFO - mozlog == 3.5 03:22:12 INFO - moznetwork == 0.27 03:22:12 INFO - mozprocess == 0.25 03:22:12 INFO - mozprofile == 0.28 03:22:12 INFO - mozrunner == 6.13 03:22:12 INFO - mozscreenshot == 0.1 03:22:12 INFO - mozsystemmonitor == 0.3 03:22:12 INFO - moztest == 0.8 03:22:12 INFO - mozversion == 1.4 03:22:12 INFO - psutil == 3.1.1 03:22:12 INFO - requests == 1.2.3 03:22:12 INFO - wsgiref == 0.1.2 03:22:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22: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')]} 03:22:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:12 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 0x210d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247df00>, '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 0x2478ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2479050>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:22:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:22:12 INFO - Using env: {'DISPLAY': ':0', 03:22:12 INFO - 'HOME': '/home/cltbld', 03:22:12 INFO - 'LANG': 'en_US.UTF-8', 03:22:12 INFO - 'LANGUAGE': 'en_US:en', 03:22:12 INFO - 'LOGNAME': 'cltbld', 03:22:12 INFO - 'MAIL': '/var/mail/cltbld', 03:22:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:12 INFO - 'MOZ_NO_REMOTE': '1', 03:22:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:12 INFO - 'NO_EM_RESTART': '1', 03:22:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:12 INFO - 'PWD': '/builds/slave/test', 03:22:12 INFO - 'SHELL': '/bin/bash', 03:22:12 INFO - 'SHLVL': '1', 03:22:12 INFO - 'TERM': 'linux', 03:22:12 INFO - 'TMOUT': '86400', 03:22:12 INFO - 'USER': 'cltbld', 03:22:12 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 03:22:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:12 INFO - '_': '/tools/buildbot/bin/python'} 03:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:22:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:22:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:22:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:22:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:22:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:22:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:22:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:22:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:22:12 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:22: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 03:22: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 03:22: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 03:22: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 03:22:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:22:13 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:22:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:22:13 INFO - Installing collected packages: simplejson 03:22:13 INFO - Running setup.py install for simplejson 03:22:13 INFO - building 'simplejson._speedups' extension 03:22:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 03:22:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 03:22:14 INFO - Successfully installed simplejson 03:22:14 INFO - Cleaning up... 03:22:14 INFO - Return code: 0 03:22:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22: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')]} 03:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x210d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x247ab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247df00>, '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 0x2478ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2479050>, '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': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:22:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:22:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:22:14 INFO - Using env: {'DISPLAY': ':0', 03:22:14 INFO - 'HOME': '/home/cltbld', 03:22:14 INFO - 'LANG': 'en_US.UTF-8', 03:22:14 INFO - 'LANGUAGE': 'en_US:en', 03:22:14 INFO - 'LOGNAME': 'cltbld', 03:22:14 INFO - 'MAIL': '/var/mail/cltbld', 03:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:14 INFO - 'MOZ_NO_REMOTE': '1', 03:22:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:14 INFO - 'NO_EM_RESTART': '1', 03:22:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:14 INFO - 'PWD': '/builds/slave/test', 03:22:14 INFO - 'SHELL': '/bin/bash', 03:22:14 INFO - 'SHLVL': '1', 03:22:14 INFO - 'TERM': 'linux', 03:22:14 INFO - 'TMOUT': '86400', 03:22:14 INFO - 'USER': 'cltbld', 03:22:14 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 03:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:14 INFO - '_': '/tools/buildbot/bin/python'} 03:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:22:14 INFO - Cleaning up... 03:22:14 INFO - Return code: 0 03:22:14 INFO - Running post-action listener: _resource_record_post_action 03:22:14 INFO - Running post-action listener: _start_resource_monitoring 03:22:14 INFO - Starting resource monitoring. 03:22:14 INFO - [mozharness: 2017-08-09 10:22:14.973914Z] Finished create-virtualenv step (success) 03:22:14 INFO - [mozharness: 2017-08-09 10:22:14.974438Z] Running install step. 03:22:14 INFO - Running pre-action listener: _resource_record_pre_action 03:22:14 INFO - Running main action method: install 03:22:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:15 INFO - Reading from file tmpfile_stdout 03:22:15 INFO - Detecting whether we're running mozinstall >=1.0... 03:22:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:22:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:22:15 INFO - Reading from file tmpfile_stdout 03:22:15 INFO - Output received: 03:22:15 INFO - Usage: mozinstall [options] installer 03:22:15 INFO - Options: 03:22:15 INFO - -h, --help show this help message and exit 03:22:15 INFO - -d DEST, --destination=DEST 03:22:15 INFO - Directory to install application into. [default: 03:22:15 INFO - "/builds/slave/test"] 03:22:15 INFO - --app=APP Application being installed. [default: firefox] 03:22:15 INFO - mkdir: /builds/slave/test/build/application 03:22:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:22:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:22:33 INFO - Reading from file tmpfile_stdout 03:22:33 INFO - Output received: 03:22:33 INFO - /builds/slave/test/build/application/firefox/firefox 03:22:33 INFO - Running post-action listener: _resource_record_post_action 03:22:33 INFO - [mozharness: 2017-08-09 10:22:33.916629Z] Finished install step (success) 03:22:33 INFO - [mozharness: 2017-08-09 10:22:33.916912Z] Running setup-mitmproxy step. 03:22:33 INFO - Running pre-action listener: _resource_record_pre_action 03:22:33 INFO - Running main action method: setup_mitmproxy 03:22:33 INFO - Skipping: mitmproxy is not required 03:22:33 INFO - Running post-action listener: _resource_record_post_action 03:22:33 INFO - [mozharness: 2017-08-09 10:22:33.918147Z] Finished setup-mitmproxy step (success) 03:22:33 INFO - [mozharness: 2017-08-09 10:22:33.918369Z] Running run-tests step. 03:22:33 INFO - Running pre-action listener: _resource_record_pre_action 03:22:33 INFO - Running pre-action listener: _set_gcov_prefix 03:22:33 INFO - Running main action method: run_tests 03:22:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:22:33 INFO - Python 2.7.3 03:22:33 INFO - Return code: 0 03:22:33 INFO - grabbing minidump binary from tooltool 03:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:22:33 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 0x247df00>, '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 0x2478ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2479050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:22:33 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:22:33 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:22:33 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:22:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:22:34 INFO - Return code: 0 03:22:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:22:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:22:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:22:34 INFO - ENV: RUST_BACKTRACE is now full 03:22:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:22:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:22:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-071', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:22:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-071 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:22:34 INFO - Using env: {'DISPLAY': ':0', 03:22:34 INFO - 'HOME': '/home/cltbld', 03:22:34 INFO - 'LANG': 'en_US.UTF-8', 03:22:34 INFO - 'LANGUAGE': 'en_US:en', 03:22:34 INFO - 'LOGNAME': 'cltbld', 03:22:34 INFO - 'MAIL': '/var/mail/cltbld', 03:22:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:22:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:22:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:34 INFO - 'MOZ_NO_REMOTE': '1', 03:22:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:22:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:34 INFO - 'NO_EM_RESTART': '1', 03:22:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:34 INFO - 'PWD': '/builds/slave/test', 03:22:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:22:34 INFO - 'RUST_BACKTRACE': 'full', 03:22:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:22:34 INFO - 'SHELL': '/bin/bash', 03:22:34 INFO - 'SHLVL': '1', 03:22:34 INFO - 'STYLO_FORCE_ENABLED': '1', 03:22:34 INFO - 'TERM': 'linux', 03:22:34 INFO - 'TMOUT': '86400', 03:22:34 INFO - 'USER': 'cltbld', 03:22:34 INFO - 'XDG_SESSION_COOKIE': 'dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758', 03:22:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:34 INFO - '_': '/tools/buildbot/bin/python'} 03:22:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-071', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:22:34 INFO - mozversion application_buildid: 20170809093407 03:22:34 INFO - mozversion application_changeset: c93fa2271ee761546d469fb1edc68254f2577b5a 03:22:34 INFO - mozversion application_display_name: Nightly 03:22:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:22:34 INFO - mozversion application_name: Firefox 03:22:34 INFO - mozversion application_remotingname: firefox 03:22:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:22:34 INFO - mozversion application_vendor: Mozilla 03:22:34 INFO - mozversion application_version: 57.0a1 03:22:34 INFO - mozversion platform_buildid: 20170809093407 03:22:34 INFO - mozversion platform_changeset: c93fa2271ee761546d469fb1edc68254f2577b5a 03:22:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:22:34 INFO - mozversion platform_version: 57.0a1 03:22:34 INFO - using testdate: 1502274154 03:22:34 INFO - actual date: 1502274154 03:22:34 INFO - starting webserver on 'localhost:47324' 03:22:34 INFO - SUITE-START | Running 2 tests 03:22:34 INFO - TEST-START | dromaeo_css 03:22:34 INFO - Initialising browser for dromaeo_css test... 03:22:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47324/getInfo.html -profile /tmp/tmpUyOfVD/profile 03:22:34 INFO - TEST-INFO | started process 23095 (/builds/slave/test/build/application/firefox/firefox http://localhost:47324/getInfo.html) 03:22:40 INFO - TEST-INFO | 23095: exit 0 03:22:41 INFO - Browser initialized. 03:22:41 INFO - Running cycle 1/1 for dromaeo_css test... 03:22:41 INFO - TEST-INFO | started process 23324 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUyOfVD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:22:42 INFO - PID 23324 | 03:22:42 INFO - PID 23324 | (/builds/slave/test/build/application/firefox/firefox:23378): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:22:42 INFO - PID 23324 | 03:22:52 INFO - PID 23324 | RSS: Main: 170913792 03:22:52 INFO - PID 23324 | 03:25:52 INFO - PID 23324 | Cycle 1(1): loaded http://localhost:47324/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47324/tests/dromaeo/cssquery-ext.html) 03:25:53 INFO - PID 23324 | RSS: Main: 175501312 03:25:53 INFO - PID 23324 | 03:28:55 INFO - PID 23324 | Cycle 1(1): loaded http://localhost:47324/tests/dromaeo/cssquery-ext.html (next: http://localhost:47324/tests/dromaeo/cssquery-jquery.html) 03:28:55 INFO - PID 23324 | RSS: Main: 170848256 03:28:55 INFO - PID 23324 | 03:31:57 INFO - PID 23324 | Cycle 1(1): loaded http://localhost:47324/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47324/tests/dromaeo/cssquery-mootools.html) 03:31:58 INFO - PID 23324 | RSS: Main: 173219840 03:31:58 INFO - PID 23324 | 03:35:00 INFO - PID 23324 | Cycle 1(1): loaded http://localhost:47324/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47324/tests/dromaeo/cssquery-prototype.html) 03:35:00 INFO - PID 23324 | RSS: Main: 173445120 03:35:00 INFO - PID 23324 | 03:38:04 INFO - PID 23324 | Cycle 1(1): loaded http://localhost:47324/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47324/tests/dromaeo/cssquery-yui.html) 03:38:05 INFO - PID 23324 | RSS: Main: 177004544 03:38:05 INFO - PID 23324 | 03:41:07 INFO - PID 23324 | Cycle 1(1): loaded http://localhost:47324/tests/dromaeo/cssquery-yui.html (next: http://localhost:47324/tests/dromaeo/cssquery-dojo.html) 03:41:07 INFO - PID 23324 | RSS: Main: 177225728 03:41:07 INFO - PID 23324 | 03:41:07 INFO - PID 23324 | __start_tp_report 03:41:07 INFO - PID 23324 | _x_x_mozilla_page_load 03:41:07 INFO - PID 23324 | _x_x_mozilla_page_load_details 03:41:07 INFO - PID 23324 | |i|pagename|runs| 03:41:07 INFO - PID 23324 | |0;dojo.html;3100.0854747849367;3127.5934128563285;3131.530270459431;3148.7166155046043;3176.761742869284;6118.969405152973;6253.343398943114;6279.246490421156;6302.0861975013595;6303.401176888194;6109;6210.56526043176;6230.688465576714;6231.937680623192;6263.217097862767;5652.576056795757;5683.403242659513;5703.74333155009;5731.541476681856;5732.054211055191;5732.68470234139;5736.4550367715;5737.139429085629;5739.827805165852;5744.195812586241;5707.571932105088;5719.6854173020465;5721.599488035839;5722.284714410699;5735;5706.400827913051;5753.165790960329;5790.623609465413;5822.7088645567555;5834.649921004753;5650.547956163538;5700.201971723962;5777.740001699934;5820.039693450575;5829.067349224104;5929.584929054968;6077.1491991121275;6089.878202435926;6192.071189321593;6223.906641400383;6573.408393244583;6590.439812615886;6707.396334329885;6723.125993620798;6724.058631791553;6750.1562304711915;6766.560173588701;6806.931930680679;6813.591184528944;6814.829629259259;6212.9378706213265;6235.251769787564;6298.905516417258;6299.338569450188;6305.4009869062365;6752.864942701119;6907;6920.5847649141215;6955.547889387173;7014.22843487216;6615.966920165369;6629.801105966829;6631.30371311015;6632.83417914556;6633.4693224541925;6658.500612454085;6744.291849355845;6745.08941292929;6745.831354216184;6751.122354093936;6634.966825165843;6679.76620818269;6680.465562754968;6694.430973367221;6737.8652426951185;6661.3005634408655;6661.867482527982;6760.932390676128;6763.526553141233;6765.763198288135;5365.275687782178;5440.455954404528;5451.918221226685;5505.724713764296;5522.834314970557;4604.562566556172;4687.226607609774;4694.671373003857;4698.929516057194;4757;5820.039693450618;5883.794067207627;5898;5900.881982360329;5901.439363260483;6716.5634233774645;6733.0573719678305;6811.4550835934;6832.829179270558;6837.897431538531;5731.484166425042;5805.042168042226;5817.156512305692;5850;5877.706114694333;5955.67243801595;5957.374475680078;5966.492848107872;5966.95578273809;5998.550108741774;6456.063870738717;6457.515686323639;6538.215414150332;6538.476921846336;6548.246951600531;6623.635700036544;6626.801195964129;6632.436242919213;6723.4957378197805;6819.352161544645;6424.38968298011;6476.805695829133;6497.057926600711;6561.114249576437;6566.967165164144;6648.368405001517;6659.167604049494;6673.566218195801;6673.599584024974;6677.3656502632175;6729.69716362755;6771.559848609824;6806.55757375769;6842.144731908512;6844.315568443116;6531.216254049545;6543.770968016097;6610.702518386588;6623.105880706043;6646.1027761253545;6428.357164283534;6512.967435162794;6553.967230163818;6594.802155935329;6620.205575330799;6418.454431373386;6420.6147631142285;6430.582012169194;6432.935670643421;6435.06691529735;6520.413162815369;6525.347465253437;6550.115734375989;6579.572327798678;6649.634270114997;6231.781887633911;6323.241211054703;6341.143945567289;6365.172527571386;6448.838779030561 03:41:07 INFO - PID 23324 | |1;ext.html;825.0800357601277;859.9140085991402;884.8938127424706;887.9955600221998;1133.5012594458433;32698;33245.66754332456;44621.33068003983;45352.77323613386;45503;46146.84632884171;46483.53516464835;46719.29921051187;46739;46774.12903483857;7895.921040789591;7898.447108702393;8053.59732013397;8066.959665201666;8068.152843951389;7884.053913530384;8002.19978002201;8003.759887203393;8003.8799418008775;8008;7954.562499062549;7959.482633628824;8018.839623207532;8020.358371330309;8026.277635012848;7931.8413631727335;7973.1628179040945;7980.920190798105;7984.600769961478;7998.360131189491;7868.606569671493;7878.487898286593;7940.602969851542;7941.364690824778;7950.681972721084;40784;40786.79606601978;40911;40926.38610420846;41076.589234107574;3530.8411121499594;3581.5702115746276;3592.802395868226;3593.9101522462147;3599.4240921452442;32557;32752;32828.507572386436;32870.671293287;32889.50665740016;4912.38595175603;5095.541401273903;5097.337346144977;5135.614828887811;5135.87160320995;196133;196313;197055;197577;198177.00911495494;51001.48998509967;51012.74493627508;51213.231801523005;51456.48543514517;51555;52607.21089183665;52977.2053419199;53028.20457693138;53088.469115308355;53235.201471977954;14654.56036318912;14866.331015104346;16424.507264782074;16816.915915420344;16832.242549085076;13607.183568985893;13742.93128534371;13772.10481318711;13781;13816.585502434944;15280.465183718516;15396.615084622974;15408.768868466981;15450.922745386426;15574.532764017098;5770.480656740913;5901.557383196192;6005.759769609167;6023.427774361428;6053.51571874249;984.8424252119627;1010.1111022300475;1011.4993078426226;1016.3495362967707;1016.9694909152737;22935.885320573623;23207.76792232056;23229.186978455673;23237.070517179123;23485.647715284285;1097.5170924793065;1119.6172726682541;1119.7760447910287;1122.0967121467297;1139.6410130808933;12324.445399957023;12424.254544727346;12436.761382950976;12516.186447880858;12605.621831345074;237520;238291;238481;240740;241410;56184;56766.716166418904;56848.431515685144;56942.71528642413;57175.714121429126;1431.0984080029723;1472.9926350368394;1474.941002359915;1478.5564330700747;1478.556433070096;22981.195658151886;23130.884345578164;23151.421214469774;23374.883125584263;23403.648945266174;38843.417348739225;38900.41649375318;38904;38929.80535097307;39001;198655;198706;199672;199965;200640;33326;33463.66536334605;33572.832135840144;33785.493217601266;37762.811185943894;46778.29832552582;47178.29232561583;47333.290000650704;47339.76330118327;48038.759806200746;34372;34557.654423455446;34681.82659086688;34966.30067398587;35280.29439411249;34362.656373435944;34414.48378274278;34438.48342274918;34505;34594;37365.81317093506;37380.25239495141;37626;37749.4337584942;39187.80406097951;34684.47973280352;35260.64739352574;35457;35464.822675886455;35672.64327356693;24855.502889941738;25508.362290942874;25659.87170064138;25876.223713288633;26037 03:41:07 INFO - PID 23324 | |2;jquery.html;575.6459777237001;582.2547139661235;582.9941700582993;596.3350863786877;604.5858586867995;22510.437239069033;22633.77366226337;23500.29499115025;23616.64575031374;23617.881910590444;26456;26973.190804275855;27162.864185679093;27178.184654460347;27232.45535089302;6403.48772098231;6584.275729669733;6586.27550969393;6587.505937054716;6588.176477940257;3925.528936527606;4100.938485922714;6244.469220116295;6548.738050477975;6608.339166083378;6465.676716164173;6521.217453905538;6523.9673801630925;6524.771632992846;6525.706343214543;6515.7719479818215;6523.5107366947195;6534.738610455576;6534.836629084287;6538.117354157223;6033.969830150865;6047.818565443044;6067.302260240085;6073.331933487313;6073.483753880925;29404;29633.111006669835;29747.553786693217;30338.696613033808;30637.38725225483;1933.5939452714947;1987.1951859496783;1987.3143765400914;1988.1550341105117;1989.3634037108131;19495.80504194954;19870.503237419034;20115.698264526043;20116.698249526267;20118.396448106432;2372.6357344526796;2429.8906549205326;2474.4927289905527;2486.0925762096704;2498.5877330240487;140570;144488;145126;145194;145228;14100;15142.772858407132;15310.923445382925;15494;15905.761413578806;23248.883755581115;23463.178788742312;23526.176583819484;24167.758322416903;24317.39206519851;10184.287099903084;10357.792844143225;10490.947545262377;10506.579736810594;10577.735556611146;9517.428954262767;9552.37909535878;9582.520873956275;9716.271279654055;9744.658936937314;9463.574139163755;9531;9657.565409556586;9767.560459779326;9824.852627210597;2780.7636350910398;2793.8742756576003;2842.7867909906427;2848.1740295314125;2916.7229113234207;971.8590804333473;982.543117450389;983.4394395194813;999.8450240212778;1008.2589296866797;20003.59992800136;20069;20079.899600502195;20225.393238202876;20327.288544900857;1029.5367084811865;1045.2160879340495;1053.1732589916878;1055.2929537210089;1058.6083149234832;10971.83542246867;10976.286541374786;11003.284786488852;11204.943975280072;11229.66311010671;459658;460031;461195;465274;479430;115679;118012;123979.38010309891;124420.37789810993;125085.37457312837;1246.8067449545335;1260.1179174577833;1311.6983093888268;1316.789313709802;1317.3742472325569;21056.68414973746;21059.57880842344;21060.104590586307;21126;21195.25816596412;55959;56717;57391;58474.7076264616;59547.70226148841;68318.3168168312;69053.65473172603;69385.30614693991;69739.65130174317;70424.29575704178;15045.473408430653;15390.923045384701;15449.459268925533;15514.844851551792;15520.922395388403;24130.63804042976;24207.51584968326;24524.754752452962;24614;24714.876425617756;19196.136173872488;19245.422637320902;19294.7105793416;19346.129424175666;19458.416247512596;17087.316507340063;17628.206730696893;18166.455006349832;18261.452156435327;18360.449186524427;18814.905925470284;20111.698324525438;20264.392068237976;20329.898350508152;20389.38831834988;14862.033967792058;14912.105273683614;15180.316885740389;15275.159866207465;15322.770158447855;19044.904775476032;19359.903200483906;19369.32207372735;19385.903070484557;19414 03:41:07 INFO - PID 23324 | |3;mootools.html;1333.9733205335895;1399.9370028348724;1400.8178936738223;1407;1420;13625.318734063294;14161.504347347845;14189.361478733455;14223.644408889782;14439.855601443982;14646.34091465884;14919.77620335693;14936.701265974674;14936.701265974702;14941;2658.9601155982627;2683.7852971762213;2703.0809524761576;2705.4318593095472;2747.8076534642582;2649.2052384284734;2663.5205662980657;2686.1001564475923;2687.0595291647865;2687.6774787025583;2626.8292560983473;2645.973540264592;2647.1793743939343;2658.2689760315875;2667.1198504493473;2559.769620734124;2561.4620929604807;2570.958761701514;2596.1692258477296;2596.6754155730628;2527.5324065048026;2569.357660584854;2577.5231582157176;2630.592258199963;2643.722409147031;13641.454341826315;13717.794233086512;13751.931240343734;13814.792778108336;13820.861791382058;7528.435367347471;7774.144844067148;7793.415493837985;7810.531368117931;7825.060992680857;5009.849704508871;5162.1224391853475;5206.765695543671;5272.314599102092;5320.308359913187;1809.3757653609484;1873.6908410112314;1892.9526761830928;1911.808819118077;1937.2251099560149;21193.152273909025;21758.673619895715;21778.455538611503;21793.346199614036;21846.453838654;6692.431143352869;6697.263301036882;6789;6918.06606108179;6922.996165555966;17327.133643318037;17391.217395217245;17392.65214695699;17444.127793610267;17480.47558573245;6891.517593768488;7947.880781788278;8180.304674102649;8195.795105122419;8206.261436470746;6343.238811342669;6800.897986530206;7061.223265440797;7188.137423509108;7238.638068096574;5015.27278544609;5023.120953833065;5028.924566131511;5125.384953805567;5127.897561999563;1321.6497628128554;1328.734253149374;1330;1344.4554955243143;1367.8700523450257;884.9778755531034;912.0742446416892;918.8897332320031;919.8206349761866;931.6785708930408;7230.566166030055;7316.670749816271;7324.780256592311;7371.6682749276415;7372.741954031583;732.5311800447718;734.181387752652;739.5082270290196;744.8547533231076;747.7868807389867;5092.719900405513;5200.375954885362;5259.0796610593;5269.1832765921345;5363.19552067193;18641.347552835858;19226.134823932956;19330.226790928486;19734.802651973583;19794.109265083105;18661;19262.903685481764;19554.902225488782;19648.70526942097;19659.311924082587;10630.893691062991;10768.676939691823;11046;11151.442427878736;11213.60752373663;8156.714514991947;8326.875096873673;8481.36389770782;8577.228049475327;8643.697470588499;16716.498505044867;17307;17338.30646774115;17707.46877593674;17730.20214090343;17217.13914304305;17245.22396492186;17582.560435989202;17659.823401765818;17699.646007080042;10596.788064238826;10825.242233043611;10875.293105948089;11600.593979210687;11850;13057.08600397963;13261.933690331487;13309.401076951754;13330.733385332433;13337.866621334051;10849.783004339712;10919.181061420584;10949.562017519529;10950.671479855617;10984.725381865517;10417.739556511147;10579.682609521727;10792.244542881923;10858.185636077167;10883;8961.372703910663;9141.817163656822;9169.679061232824;9176.724698259062;9209.033051529494;7005.649717514206;7005.964970175117;7066.04608377883;7069.222385537484;7107.18267399235;6785.762498312535;6838.726450942106;6915.481338899502;6968.825779355557;6999.2583633223085 03:41:07 INFO - PID 23324 | |4;prototype.html;490.7717911171305;495.7298272441519;520.2170733046764;528.4372143666994;531.6677076826983;6255.030470277109;6267.840449516837;6283.4030767077165;6354.33279505651;6385.553011289212;5930.199423077883;6138.685110040573;6143.907841382383;6210.844728881781;6219.346968568303;4994.625403094782;5011.198208286675;5023.120953833065;5024.497550244982;5043.84868453947;4962.702237865721;4985.700857948516;4996.225585034325;5004.299398084271;5055.696658200502;5003.624728145385;5151.227315902607;5174.741262936838;5199.428062913076;5200.92198617021;5169.6898186108765;5202.505761952609;5223.817166399158;5237;5237.214417837316;4819.349387832633;4835.226363781813;4852.563269305744;4860.708357498562;4865.245886887538;6101.237345331833;6154.384561543855;6156.384361563808;6158.3841615838055;6167.814965551041;5954.583179177459;5989.341172471025;5999.340072592011;6028.156058151862;6056.909146362808;6578.703958321887;6578.967105164492;6607.966960165217;6611.950295408391;6619.834504137387;5227.424983251839;5278.841634750964;5288.735563221823;5309.840704778863;5316.308879845634;6882.931170688279;6932.757353492654;6941.861162776716;7008.509404341698;7062.258462861429;5423.430539793344;5447.319085114361;5487.972560137254;5489.1217405215775;5499.890002199933;5551.306086739158;5572.414896435897;5607.943920560824;5610;5617.887642247132;5941.257342832146;5981.730822113015;5986.281646202484;5997.6401415914315;6022.57841951068;6536.117624120778;6543.625838573953;6608.4052435281055;6674.566153200123;6675.399214070757;1809.719493478513;1881.2757088521018;1890.2344550457087;1913.158210387425;1954.5993071420326;1441.942322307096;1461.919594422317;1462.4588902106289;1471.5144002479155;1480.8371079181281;5640.407757185437;5684.658920464785;5699.515541178963;5720.713964301768;5736.311642602915;6471.935280647227;6582.80251592453;6608.4052435281055;6637.668116594247;6723.327667233335;5698.373178950312;5736.598438109292;5748.741306641211;5758.445219790717;5770.182392546419;3556.3598552260323;3562.412201986695;3584.7311451640708;3609.4766258892314;3616.4213725803747;6390.584612000298;6456.192975878015;6486.837829054217;6491.350864913471;6562.80311590653;6865.798485265059;6877.9656101720175;6933.237343892168;6934.479914006369;6949.861002780017;6452.967735161482;6455.160829092188;6498.707558159799;6508.66572352676;6569.868602628017;6695.598264104169;6702.396784289437;6726.428253598499;6728.259891411843;6737.86524269502;6659.334066593302;6668.899966500603;6683.398494135551;6772.25505194438;6787.5588086774205;6437.967810160919;6498.64257465825;6515.478761698958;6532.183477065367;6563.310852360624;5034.672746271627;5065.594752419724;5068.82259120929;5076.340075790124;5080.7205603692155;6801.421879140131;6807.591544507345;6846.623435711083;6852.5203235773015;6854.588724676535;6509.511786615928;6519.771807986698;6520.978496666049;6523.478121750343;6546.574472659262;6337.176167098248;6505.5446118773125;6541.771038013647;6560;6599.175103112111;6195.876082478415;6364.268109167505;6381.691753190492;6382.7766028190645;6389;6477.384648458389;6480.546361754632;6487.22153341602;6498.252700939453;6507.837304067436;6327.588706734047;6328.715207815567;6347.5556711029785;6367.140436041261;6374.776882809079 03:41:07 INFO - PID 23324 | |5;yui.html;905.1401168889556;909.6225066597362;916.9679061232856;925.1026504290836;931.1759093202513;18699.532511687197;19756.901215493937;19812.90093549532;19849.900750496246;21513.677294840552;21149.577008459823;21864.8906755466;21982.89008554959;22105.66841497379;22105.889470552625;1032.7986042721673;1047.5181416548396;1047.6228557719214;1047.9056884880358;1052.9157667386628;1047.1047254597324;1047.7433028907928;1051.7633532455213;1052.1319911073356;1052.2003277509073;1021.8109649714789;1100.3287994323457;1104.618906477264;1113.5879724501945;1114.788190243855;1103.0293341859192;1103.3104309806372;1104.5581767293068;1105.7401510645032;1107.800595892739;804.8631732605471;806.7942674617935;807.0275318241505;807.1444269074799;807.2815194476921;19801.207951681914;20259.189632414687;20601.381958541268;20881.164753409845;20897.268595599082;21373;21527.354179374644;21848.344549663536;22013.779862201332;22066.117355305767;16492.752608710878;16535.83464165355;16657.583560410967;16665;16712.74930876038;3592.586852511942;3635.600083990759;3635.836387362575;3636.7454278200535;3637.418013117888;123662.38168809189;128682;129791.99999999905;132033;133102;16839.831601683825;16873.325066997182;17395.47813565595;17453.73819392685;17511.824881751276;30429.391412172074;30882.382352352826;31179.688203118134;31611.52582711261;31865;7680.846383072308;8016.158303378178;8036.156203598655;8514.48913065218;8574.3997920145;3774.3772277573894;4048.0891799344913;4149.564295748903;4180;4180.226658068267;5312.309399778082;5358.11591087474;5406.972965135228;5450.727463626803;5451.318585176853;1060.3160961179997;1088.7931293054294;1089.2538611051455;1096.7477480179602;1099.8405231241425;694.6804469943883;705.4673721340355;709.549436108075;713.0801266366329;724.2757242757243;11699.590514331958;12308.199967002116;12446.705886469153;12813;13062.412191451218;695.6660802814577;696.6098984568657;701.4038067642565;706.219627311813;717.3651318583092;5550.153339815976;5576.247206627054;5633.0987042073075;5662;5790.594658373958;107250.46374768077;107627.46186269018;107673;108116.45941770398;111241.44379278051;95191;96682;96764;99073.50463247782;99758.5012074935;80089.59955200186;80545;81611.59194203992;82413;84843.15156848353;11083.667489975314;11220.887791121984;11222;11269.323840569592;11348.546058157912;38564.22871542609;38926.80536597299;39529;40619.1876162481;41401.585984139776;48469.75765121152;48891.26663100127;49146;49581.50418495913;50149.24776128434;15386.692266154838;15582.298796553956;15612.141332226836;15706.607334816721;15715.764263535828;16346.749845040613;20822.27122050721;21188.682169767773;21227.36317910465;21346.14615415366;18183.727244091086;18231.635367292845;18490.537736556693;18666.160022798736;19160.329388471335;17326.13369331503;17327.047012413936;17376.82623173752;17386.652266954843;17546.21042053085;18167.36414225496;18418.72371914449;18436.81563184351;18570.071496424855;18579.81420185835;13715.451381944611;13879.236641984788;13986.811121054825;13989.930050349683;14053.36759845789;13243.271620060988;13288.867111328764;13484.797728034284;13497.39261733244;13552.525661601796 03:41:07 INFO - PID 23324 | __end_tp_report 03:41:07 INFO - PID 23324 | __start_cc_report 03:41:07 INFO - PID 23324 | _x_x_mozilla_cycle_collect,390 03:41:07 INFO - PID 23324 | __end_cc_report 03:41:07 INFO - PID 23324 | __startTimestamp1502275267559__endTimestamp 03:41:07 INFO - PID 23324 | 03:41:07 INFO - PID 23324 | ------- Summary: start ------- 03:41:07 INFO - PID 23324 | Number of tests: 6 03:41:07 INFO - PID 23324 | 03:41:07 INFO - PID 23324 | [#0] dojo.html Cycles:165 Average:6182.62 Median:6428.36 stddev:731.18 (11.4%) stddev-sans-first:692.28 03:41:07 INFO - PID 23324 | Values: 3100.1 3127.6 3131.5 3148.7 3176.8 6119.0 6253.3 6279.2 6302.1 6303.4 6109.0 6210.6 6230.7 6231.9 6263.2 5652.6 5683.4 5703.7 5731.5 5732.1 5732.7 5736.5 5737.1 5739.8 5744.2 5707.6 5719.7 5721.6 5722.3 5735.0 5706.4 5753.2 5790.6 5822.7 5834.6 5650.5 5700.2 5777.7 5820.0 5829.1 5929.6 6077.1 6089.9 6192.1 6223.9 6573.4 6590.4 6707.4 6723.1 6724.1 6750.2 6766.6 6806.9 6813.6 6814.8 6212.9 6235.3 6298.9 6299.3 6305.4 6752.9 6907.0 6920.6 6955.5 7014.2 6616.0 6629.8 6631.3 6632.8 6633.5 6658.5 6744.3 6745.1 6745.8 6751.1 6635.0 6679.8 6680.5 6694.4 6737.9 6661.3 6661.9 6760.9 6763.5 6765.8 5365.3 5440.5 5451.9 5505.7 5522.8 4604.6 4687.2 4694.7 4698.9 4757.0 5820.0 5883.8 5898.0 5900.9 5901.4 6716.6 6733.1 6811.5 6832.8 6837.9 5731.5 5805.0 5817.2 5850.0 5877.7 5955.7 5957.4 5966.5 5967.0 5998.6 6456.1 6457.5 6538.2 6538.5 6548.2 6623.6 6626.8 6632.4 6723.5 6819.4 6424.4 6476.8 6497.1 6561.1 6567.0 6648.4 6659.2 6673.6 6673.6 6677.4 6729.7 6771.6 6806.6 6842.1 6844.3 6531.2 6543.8 6610.7 6623.1 6646.1 6428.4 6513.0 6554.0 6594.8 6620.2 6418.5 6420.6 6430.6 6432.9 6435.1 6520.4 6525.3 6550.1 6579.6 6649.6 6231.8 6323.2 6341.1 6365.2 6448.8 03:41:07 INFO - PID 23324 | 03:41:07 INFO - PID 23324 | [#1] ext.html Cycles:180 Average:39138.83 Median:35568.73 stddev:55171.99 (155.1%) stddev-sans-first:55251.76 03:41:07 INFO - PID 23324 | Values: 825.1 859.9 884.9 888.0 1133.5 32698.0 33245.7 44621.3 45352.8 45503.0 46146.8 46483.5 46719.3 46739.0 46774.1 7895.9 7898.4 8053.6 8067.0 8068.2 7884.1 8002.2 8003.8 8003.9 8008.0 7954.6 7959.5 8018.8 8020.4 8026.3 7931.8 7973.2 7980.9 7984.6 7998.4 7868.6 7878.5 7940.6 7941.4 7950.7 40784.0 40786.8 40911.0 40926.4 41076.6 3530.8 3581.6 3592.8 3593.9 3599.4 32557.0 32752.0 32828.5 32870.7 32889.5 4912.4 5095.5 5097.3 5135.6 5135.9 196133.0 196313.0 197055.0 197577.0 198177.0 51001.5 51012.7 51213.2 51456.5 51555.0 52607.2 52977.2 53028.2 53088.5 53235.2 14654.6 14866.3 16424.5 16816.9 16832.2 13607.2 13742.9 13772.1 13781.0 13816.6 15280.5 15396.6 15408.8 15450.9 15574.5 5770.5 5901.6 6005.8 6023.4 6053.5 984.8 1010.1 1011.5 1016.3 1017.0 22935.9 23207.8 23229.2 23237.1 23485.6 1097.5 1119.6 1119.8 1122.1 1139.6 12324.4 12424.3 12436.8 12516.2 12605.6 237520.0 238291.0 238481.0 240740.0 241410.0 56184.0 56766.7 56848.4 56942.7 57175.7 1431.1 1473.0 1474.9 1478.6 1478.6 22981.2 23130.9 23151.4 23374.9 23403.6 38843.4 38900.4 38904.0 38929.8 39001.0 198655.0 198706.0 199672.0 199965.0 200640.0 33326.0 33463.7 33572.8 33785.5 37762.8 46778.3 47178.3 47333.3 47339.8 48038.8 34372.0 34557.7 34681.8 34966.3 35280.3 34362.7 34414.5 34438.5 34505.0 34594.0 37365.8 37380.3 37626.0 37749.4 39187.8 34684.5 35260.6 35457.0 35464.8 35672.6 24855.5 25508.4 25659.9 25876.2 26037.0 03:41:07 INFO - PID 23324 | 03:41:07 INFO - PID 23324 | [#2] jquery.html Cycles:180 Average:34790.39 Median:23513.24 stddev:79076.23 (336.3%) stddev-sans-first:79256.34 03:41:07 INFO - PID 23324 | Values: 575.6 582.3 583.0 596.3 604.6 22510.4 22633.8 23500.3 23616.6 23617.9 26456.0 26973.2 27162.9 27178.2 27232.5 6403.5 6584.3 6586.3 6587.5 6588.2 3925.5 4100.9 6244.5 6548.7 6608.3 6465.7 6521.2 6524.0 6524.8 6525.7 6515.8 6523.5 6534.7 6534.8 6538.1 6034.0 6047.8 6067.3 6073.3 6073.5 29404.0 29633.1 29747.6 30338.7 30637.4 1933.6 1987.2 1987.3 1988.2 1989.4 19495.8 19870.5 20115.7 20116.7 20118.4 2372.6 2429.9 2474.5 2486.1 2498.6 140570.0 144488.0 145126.0 145194.0 145228.0 14100.0 15142.8 15310.9 15494.0 15905.8 23248.9 23463.2 23526.2 24167.8 24317.4 10184.3 10357.8 10490.9 10506.6 10577.7 9517.4 9552.4 9582.5 9716.3 9744.7 9463.6 9531.0 9657.6 9767.6 9824.9 2780.8 2793.9 2842.8 2848.2 2916.7 971.9 982.5 983.4 999.8 1008.3 20003.6 20069.0 20079.9 20225.4 20327.3 1029.5 1045.2 1053.2 1055.3 1058.6 10971.8 10976.3 11003.3 11204.9 11229.7 459658.0 460031.0 461195.0 465274.0 479430.0 115679.0 118012.0 123979.4 124420.4 125085.4 1246.8 1260.1 1311.7 1316.8 1317.4 21056.7 21059.6 21060.1 21126.0 21195.3 55959.0 56717.0 57391.0 58474.7 59547.7 68318.3 69053.7 69385.3 69739.7 70424.3 15045.5 15390.9 15449.5 15514.8 15520.9 24130.6 24207.5 24524.8 24614.0 24714.9 19196.1 19245.4 19294.7 19346.1 19458.4 17087.3 17628.2 18166.5 18261.5 18360.4 18814.9 20111.7 20264.4 20329.9 20389.4 14862.0 14912.1 15180.3 15275.2 15322.8 19044.9 19359.9 19369.3 19385.9 19414.0 03:41:07 INFO - PID 23324 | 03:41:07 INFO - PID 23324 | [#3] mootools.html Cycles:180 Average:8765.18 Median:2586.85 stddev:5965.77 (230.6%) stddev-sans-first:5956.37 03:41:07 INFO - PID 23324 | Values: 1334.0 1399.9 1400.8 1407.0 1420.0 13625.3 14161.5 14189.4 14223.6 14439.9 14646.3 14919.8 14936.7 14936.7 14941.0 2659.0 2683.8 2703.1 2705.4 2747.8 2649.2 2663.5 2686.1 2687.1 2687.7 2626.8 2646.0 2647.2 2658.3 2667.1 2559.8 2561.5 2571.0 2596.2 2596.7 2527.5 2569.4 2577.5 2630.6 2643.7 13641.5 13717.8 13751.9 13814.8 13820.9 7528.4 7774.1 7793.4 7810.5 7825.1 5009.8 5162.1 5206.8 5272.3 5320.3 1809.4 1873.7 1893.0 1911.8 1937.2 21193.2 21758.7 21778.5 21793.3 21846.5 6692.4 6697.3 6789.0 6918.1 6923.0 17327.1 17391.2 17392.7 17444.1 17480.5 6891.5 7947.9 8180.3 8195.8 8206.3 6343.2 6800.9 7061.2 7188.1 7238.6 5015.3 5023.1 5028.9 5125.4 5127.9 1321.6 1328.7 1330.0 1344.5 1367.9 885.0 912.1 918.9 919.8 931.7 7230.6 7316.7 7324.8 7371.7 7372.7 732.5 734.2 739.5 744.9 747.8 5092.7 5200.4 5259.1 5269.2 5363.2 18641.3 19226.1 19330.2 19734.8 19794.1 18661.0 19262.9 19554.9 19648.7 19659.3 10630.9 10768.7 11046.0 11151.4 11213.6 8156.7 8326.9 8481.4 8577.2 8643.7 16716.5 17307.0 17338.3 17707.5 17730.2 17217.1 17245.2 17582.6 17659.8 17699.6 10596.8 10825.2 10875.3 11600.6 11850.0 13057.1 13261.9 13309.4 13330.7 13337.9 10849.8 10919.2 10949.6 10950.7 10984.7 10417.7 10579.7 10792.2 10858.2 10883.0 8961.4 9141.8 9169.7 9176.7 9209.0 7005.6 7006.0 7066.0 7069.2 7107.2 6785.8 6838.7 6915.5 6968.8 6999.3 03:41:07 INFO - PID 23324 | 03:41:07 INFO - PID 23324 | [#4] prototype.html Cycles:180 Average:5604.46 Median:6157.38 stddev:1503.55 (24.4%) stddev-sans-first:1457.96 03:41:07 INFO - PID 23324 | Values: 490.8 495.7 520.2 528.4 531.7 6255.0 6267.8 6283.4 6354.3 6385.6 5930.2 6138.7 6143.9 6210.8 6219.3 4994.6 5011.2 5023.1 5024.5 5043.8 4962.7 4985.7 4996.2 5004.3 5055.7 5003.6 5151.2 5174.7 5199.4 5200.9 5169.7 5202.5 5223.8 5237.0 5237.2 4819.3 4835.2 4852.6 4860.7 4865.2 6101.2 6154.4 6156.4 6158.4 6167.8 5954.6 5989.3 5999.3 6028.2 6056.9 6578.7 6579.0 6608.0 6612.0 6619.8 5227.4 5278.8 5288.7 5309.8 5316.3 6882.9 6932.8 6941.9 7008.5 7062.3 5423.4 5447.3 5488.0 5489.1 5499.9 5551.3 5572.4 5607.9 5610.0 5617.9 5941.3 5981.7 5986.3 5997.6 6022.6 6536.1 6543.6 6608.4 6674.6 6675.4 1809.7 1881.3 1890.2 1913.2 1954.6 1441.9 1461.9 1462.5 1471.5 1480.8 5640.4 5684.7 5699.5 5720.7 5736.3 6471.9 6582.8 6608.4 6637.7 6723.3 5698.4 5736.6 5748.7 5758.4 5770.2 3556.4 3562.4 3584.7 3609.5 3616.4 6390.6 6456.2 6486.8 6491.4 6562.8 6865.8 6878.0 6933.2 6934.5 6949.9 6453.0 6455.2 6498.7 6508.7 6569.9 6695.6 6702.4 6726.4 6728.3 6737.9 6659.3 6668.9 6683.4 6772.3 6787.6 6438.0 6498.6 6515.5 6532.2 6563.3 5034.7 5065.6 5068.8 5076.3 5080.7 6801.4 6807.6 6846.6 6852.5 6854.6 6509.5 6519.8 6521.0 6523.5 6546.6 6337.2 6505.5 6541.8 6560.0 6599.2 6195.9 6364.3 6381.7 6382.8 6389.0 6477.4 6480.5 6487.2 6498.3 6507.8 6327.6 6328.7 6347.6 6367.1 6374.8 03:41:07 INFO - PID 23324 | 03:41:07 INFO - PID 23324 | [#5] yui.html Cycles:180 Average:23109.33 Median:20054.55 stddev:31400.84 (156.6%) stddev-sans-first:31444.66 03:41:07 INFO - PID 23324 | Values: 905.1 909.6 917.0 925.1 931.2 18699.5 19756.9 19812.9 19849.9 21513.7 21149.6 21864.9 21982.9 22105.7 22105.9 1032.8 1047.5 1047.6 1047.9 1052.9 1047.1 1047.7 1051.8 1052.1 1052.2 1021.8 1100.3 1104.6 1113.6 1114.8 1103.0 1103.3 1104.6 1105.7 1107.8 804.9 806.8 807.0 807.1 807.3 19801.2 20259.2 20601.4 20881.2 20897.3 21373.0 21527.4 21848.3 22013.8 22066.1 16492.8 16535.8 16657.6 16665.0 16712.7 3592.6 3635.6 3635.8 3636.7 3637.4 123662.4 128682.0 129792.0 132033.0 133102.0 16839.8 16873.3 17395.5 17453.7 17511.8 30429.4 30882.4 31179.7 31611.5 31865.0 7680.8 8016.2 8036.2 8514.5 8574.4 3774.4 4048.1 4149.6 4180.0 4180.2 5312.3 5358.1 5407.0 5450.7 5451.3 1060.3 1088.8 1089.3 1096.7 1099.8 694.7 705.5 709.5 713.1 724.3 11699.6 12308.2 12446.7 12813.0 13062.4 695.7 696.6 701.4 706.2 717.4 5550.2 5576.2 5633.1 5662.0 5790.6 107250.5 107627.5 107673.0 108116.5 111241.4 95191.0 96682.0 96764.0 99073.5 99758.5 80089.6 80545.0 81611.6 82413.0 84843.2 11083.7 11220.9 11222.0 11269.3 11348.5 38564.2 38926.8 39529.0 40619.2 41401.6 48469.8 48891.3 49146.0 49581.5 50149.2 15386.7 15582.3 15612.1 15706.6 15715.8 16346.7 20822.3 21188.7 21227.4 21346.1 18183.7 18231.6 18490.5 18666.2 19160.3 17326.1 17327.0 17376.8 17386.7 17546.2 18167.4 18418.7 18436.8 18570.1 18579.8 13715.5 13879.2 13986.8 13989.9 14053.4 13243.3 13288.9 13484.8 13497.4 13552.5 03:41:07 INFO - PID 23324 | -------- Summary: end -------- 03:41:07 INFO - PID 23324 | 03:41:08 INFO - TEST-INFO | 23324: exit 0 03:41:08 INFO - TEST-OK | dromaeo_css | took 1114014ms 03:41:08 INFO - TEST-START | kraken 03:41:08 INFO - Initialising browser for kraken test... 03:41:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47324/getInfo.html -profile /tmp/tmpOYY5pI/profile 03:41:08 INFO - TEST-INFO | started process 23693 (/builds/slave/test/build/application/firefox/firefox http://localhost:47324/getInfo.html) 03:41:14 INFO - TEST-INFO | 23693: exit 0 03:41:14 INFO - Browser initialized. 03:41:14 INFO - Running cycle 1/1 for kraken test... 03:41:14 INFO - TEST-INFO | started process 23927 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOYY5pI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:41:15 INFO - PID 23927 | 03:41:15 INFO - PID 23927 | (/builds/slave/test/build/application/firefox/firefox:23982): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:41:15 INFO - PID 23927 | 03:41:25 INFO - PID 23927 | RSS: Main: 170778624 03:41:25 INFO - PID 23927 | 03:41:28 INFO - PID 23927 | Cycle 1(1): loaded http://localhost:47324/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47324/tests/kraken/driver.html?testName=audio-beat-detection) 03:41:29 INFO - PID 23927 | RSS: Main: 175382528 03:41:29 INFO - PID 23927 | 03:41:32 INFO - PID 23927 | Cycle 1(1): loaded http://localhost:47324/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47324/tests/kraken/driver.html?testName=audio-dft) 03:41:32 INFO - PID 23927 | RSS: Main: 176021504 03:41:32 INFO - PID 23927 | 03:41:36 INFO - PID 23927 | Cycle 1(1): loaded http://localhost:47324/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47324/tests/kraken/driver.html?testName=audio-fft) 03:41:36 INFO - PID 23927 | RSS: Main: 170291200 03:41:36 INFO - PID 23927 | 03:41:38 INFO - PID 23927 | Cycle 1(1): loaded http://localhost:47324/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47324/tests/kraken/driver.html?testName=audio-oscillator) 03:41:39 INFO - PID 23927 | RSS: Main: 170790912 03:41:39 INFO - PID 23927 | 03:41:41 INFO - PID 23927 | Cycle 1(1): loaded http://localhost:47324/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47324/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:41:42 INFO - PID 23927 | RSS: Main: 171229184 03:41:42 INFO - PID 23927 | 03:41:45 INFO - PID 23927 | Cycle 1(1): loaded http://localhost:47324/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47324/tests/kraken/driver.html?testName=imaging-darkroom) 03:41:46 INFO - PID 23927 | RSS: Main: 173834240 03:41:46 INFO - PID 23927 | 03:41:49 INFO - PID 23927 | Cycle 1(1): loaded http://localhost:47324/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47324/tests/kraken/driver.html?testName=imaging-desaturate) 03:41:50 INFO - PID 23927 | RSS: Main: 173645824 03:41:50 INFO - PID 23927 | 03:41:53 INFO - PID 23927 | Cycle 1(1): loaded http://localhost:47324/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47324/tests/kraken/driver.html?testName=json-parse-financial) 03:41:53 INFO - PID 23927 | RSS: Main: 174473216 03:41:53 INFO - PID 23927 | 03:41:55 INFO - PID 23927 | Cycle 1(1): loaded http://localhost:47324/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47324/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:41:56 INFO - PID 23927 | RSS: Main: 174383104 03:41:56 INFO - PID 23927 | 03:41:57 INFO - PID 23927 | Cycle 1(1): loaded http://localhost:47324/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47324/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:41:58 INFO - PID 23927 | RSS: Main: 174067712 03:41:58 INFO - PID 23927 | 03:42:00 INFO - PID 23927 | Cycle 1(1): loaded http://localhost:47324/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47324/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:42:01 INFO - PID 23927 | RSS: Main: 174309376 03:42:01 INFO - PID 23927 | 03:42:04 INFO - PID 23927 | Cycle 1(1): loaded http://localhost:47324/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47324/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:42:05 INFO - PID 23927 | RSS: Main: 174710784 03:42:05 INFO - PID 23927 | 03:42:08 INFO - PID 23927 | Cycle 1(1): loaded http://localhost:47324/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47324/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:42:08 INFO - PID 23927 | RSS: Main: 174567424 03:42:08 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | Cycle 1(1): loaded http://localhost:47324/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47324/tests/kraken/driver.html?testName=ai-astar) 03:42:10 INFO - PID 23927 | RSS: Main: 176005120 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | __start_tp_report 03:42:10 INFO - PID 23927 | _x_x_mozilla_page_load 03:42:10 INFO - PID 23927 | _x_x_mozilla_page_load_details 03:42:10 INFO - PID 23927 | |i|pagename|runs| 03:42:10 INFO - PID 23927 | |0;ai-astar;98;106;99;107;112;110;107;100;116;111 03:42:10 INFO - PID 23927 | |1;audio-beat-detection;147;125;145;173;123;146;145;117;180;118 03:42:10 INFO - PID 23927 | |2;audio-dft;164;170;164;166;166;167;168;163;164;166 03:42:10 INFO - PID 23927 | |3;audio-fft;74;73;74;184;75;75;75;74;149;79 03:42:10 INFO - PID 23927 | |4;audio-oscillator;82;80;126;84;78;79;129;81;83;132 03:42:10 INFO - PID 23927 | |5;imaging-gaussian-blur;117;117;114;113;114;114;118;117;119;115 03:42:10 INFO - PID 23927 | |6;imaging-darkroom;150;151;151;150;151;151;152;150;151;152 03:42:10 INFO - PID 23927 | |7;imaging-desaturate;94;94;95;99;94;94;94;95;95;95 03:42:10 INFO - PID 23927 | |8;json-parse-financial;59;53;59;55;54;57;54;53;55;54 03:42:10 INFO - PID 23927 | |9;json-stringify-tinderbox;54;61;54;53;54;54;54;56;54;54 03:42:10 INFO - PID 23927 | |10;stanford-crypto-aes;90;80;79;87;78;80;87;89;88;90 03:42:10 INFO - PID 23927 | |11;stanford-crypto-ccm;127;128;144;135;134;129;146;137;138;166 03:42:10 INFO - PID 23927 | |12;stanford-crypto-pbkdf2;184;173;178;183;170;172;176;172;169;169 03:42:10 INFO - PID 23927 | |13;stanford-crypto-sha256-iterative;68;73;71;69;65;68;66;64;66;65 03:42:10 INFO - PID 23927 | __end_tp_report 03:42:10 INFO - PID 23927 | __start_cc_report 03:42:10 INFO - PID 23927 | _x_x_mozilla_cycle_collect,964 03:42:10 INFO - PID 23927 | __end_cc_report 03:42:10 INFO - PID 23927 | __startTimestamp1502275330805__endTimestamp 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | ------- Summary: start ------- 03:42:10 INFO - PID 23927 | Number of tests: 14 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | [#0] ai-astar Cycles:10 Average:106.60 Median:111.50 stddev:6.00 (5.4%) stddev-sans-first:5.50 03:42:10 INFO - PID 23927 | Values: 98.0 106.0 99.0 107.0 112.0 110.0 107.0 100.0 116.0 111.0 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | [#1] audio-beat-detection Cycles:10 Average:141.90 Median:145.50 stddev:21.90 (15.0%) stddev-sans-first:23.15 03:42:10 INFO - PID 23927 | Values: 147.0 125.0 145.0 173.0 123.0 146.0 145.0 117.0 180.0 118.0 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | [#2] audio-dft Cycles:10 Average:165.80 Median:166.00 stddev:2.15 (1.3%) stddev-sans-first:2.18 03:42:10 INFO - PID 23927 | Values: 164.0 170.0 164.0 166.0 166.0 167.0 168.0 163.0 164.0 166.0 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | [#3] audio-fft Cycles:10 Average:93.20 Median:74.50 stddev:39.54 (53.1%) stddev-sans-first:41.32 03:42:10 INFO - PID 23927 | Values: 74.0 73.0 74.0 184.0 75.0 75.0 75.0 74.0 149.0 79.0 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:80.50 stddev:23.30 (28.9%) stddev-sans-first:24.20 03:42:10 INFO - PID 23927 | Values: 82.0 80.0 126.0 84.0 78.0 79.0 129.0 81.0 83.0 132.0 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | [#5] imaging-gaussian-blur Cycles:10 Average:115.80 Median:117.00 stddev:2.04 (1.7%) stddev-sans-first:2.12 03:42:10 INFO - PID 23927 | Values: 117.0 117.0 114.0 113.0 114.0 114.0 118.0 117.0 119.0 115.0 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.00 stddev:0.74 (0.5%) stddev-sans-first:0.71 03:42:10 INFO - PID 23927 | Values: 150.0 151.0 151.0 150.0 151.0 151.0 152.0 150.0 151.0 152.0 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:1.52 (1.6%) stddev-sans-first:1.58 03:42:10 INFO - PID 23927 | Values: 94.0 94.0 95.0 99.0 94.0 94.0 94.0 95.0 95.0 95.0 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.00 stddev:2.26 (4.1%) stddev-sans-first:1.96 03:42:10 INFO - PID 23927 | Values: 59.0 53.0 59.0 55.0 54.0 57.0 54.0 53.0 55.0 54.0 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | [#9] json-stringify-tinderbox Cycles:10 Average:54.80 Median:54.00 stddev:2.30 (4.3%) stddev-sans-first:2.42 03:42:10 INFO - PID 23927 | Values: 54.0 61.0 54.0 53.0 54.0 54.0 54.0 56.0 54.0 54.0 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:87.50 stddev:4.92 (5.6%) stddev-sans-first:4.84 03:42:10 INFO - PID 23927 | Values: 90.0 80.0 79.0 87.0 78.0 80.0 87.0 89.0 88.0 90.0 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | [#11] stanford-crypto-ccm Cycles:10 Average:138.40 Median:137.50 stddev:11.60 (8.4%) stddev-sans-first:11.54 03:42:10 INFO - PID 23927 | Values: 127.0 128.0 144.0 135.0 134.0 129.0 146.0 137.0 138.0 166.0 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.60 Median:174.50 stddev:5.50 (3.2%) stddev-sans-first:4.67 03:42:10 INFO - PID 23927 | Values: 184.0 173.0 178.0 183.0 170.0 172.0 176.0 172.0 169.0 169.0 03:42:10 INFO - PID 23927 | 03:42:10 INFO - PID 23927 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:2.88 (4.2%) stddev-sans-first:3.05 03:42:10 INFO - PID 23927 | Values: 68.0 73.0 71.0 69.0 65.0 68.0 66.0 64.0 66.0 65.0 03:42:10 INFO - PID 23927 | -------- Summary: end -------- 03:42:10 INFO - PID 23927 | 03:42:11 INFO - PID 23927 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:42:11 INFO - TEST-INFO | 23927: exit 0 03:42:11 INFO - TEST-OK | kraken | took 63240ms 03:42:11 INFO - SUITE-END | took 1177s 03:42:11 INFO - Completed test suite (00:19:37) 03:42:11 INFO - javascript benchmark 03:42:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8589.04610964471, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3100.0854747849367, 3127.5934128563285, 3131.530270459431, 3148.7166155046043, 3176.761742869284, 6118.969405152973, 6253.343398943114, 6279.246490421156, 6302.0861975013595, 6303.401176888194, 6109.0, 6210.56526043176, 6230.688465576714, 6231.937680623192, 6263.217097862767, 5652.576056795757, 5683.403242659513, 5703.74333155009, 5731.541476681856, 5732.054211055191, 5732.68470234139, 5736.4550367715, 5737.139429085629, 5739.827805165852, 5744.195812586241, 5707.571932105088, 5719.6854173020465, 5721.599488035839, 5722.284714410699, 5735.0, 5706.400827913051, 5753.165790960329, 5790.623609465413, 5822.7088645567555, 5834.649921004753, 5650.547956163538, 5700.201971723962, 5777.740001699934, 5820.039693450575, 5829.067349224104, 5929.584929054968, 6077.1491991121275, 6089.878202435926, 6192.071189321593, 6223.906641400383, 6573.408393244583, 6590.439812615886, 6707.396334329885, 6723.125993620798, 6724.058631791553, 6750.1562304711915, 6766.560173588701, 6806.931930680679, 6813.591184528944, 6814.829629259259, 6212.9378706213265, 6235.251769787564, 6298.905516417258, 6299.338569450188, 6305.4009869062365, 6752.864942701119, 6907.0, 6920.5847649141215, 6955.547889387173, 7014.22843487216, 6615.966920165369, 6629.801105966829, 6631.30371311015, 6632.83417914556, 6633.4693224541925, 6658.500612454085, 6744.291849355845, 6745.08941292929, 6745.831354216184, 6751.122354093936, 6634.966825165843, 6679.76620818269, 6680.465562754968, 6694.430973367221, 6737.8652426951185, 6661.3005634408655, 6661.867482527982, 6760.932390676128, 6763.526553141233, 6765.763198288135, 5365.275687782178, 5440.455954404528, 5451.918221226685, 5505.724713764296, 5522.834314970557, 4604.562566556172, 4687.226607609774, 4694.671373003857, 4698.929516057194, 4757.0, 5820.039693450618, 5883.794067207627, 5898.0, 5900.881982360329, 5901.439363260483, 6716.5634233774645, 6733.0573719678305, 6811.4550835934, 6832.829179270558, 6837.897431538531, 5731.484166425042, 5805.042168042226, 5817.156512305692, 5850.0, 5877.706114694333, 5955.67243801595, 5957.374475680078, 5966.492848107872, 5966.95578273809, 5998.550108741774, 6456.063870738717, 6457.515686323639, 6538.215414150332, 6538.476921846336, 6548.246951600531, 6623.635700036544, 6626.801195964129, 6632.436242919213, 6723.4957378197805, 6819.352161544645, 6424.38968298011, 6476.805695829133, 6497.057926600711, 6561.114249576437, 6566.967165164144, 6648.368405001517, 6659.167604049494, 6673.566218195801, 6673.599584024974, 6677.3656502632175, 6729.69716362755, 6771.559848609824, 6806.55757375769, 6842.144731908512, 6844.315568443116, 6531.216254049545, 6543.770968016097, 6610.702518386588, 6623.105880706043, 6646.1027761253545, 6428.357164283534, 6512.967435162794, 6553.967230163818, 6594.802155935329, 6620.205575330799, 6418.454431373386, 6420.6147631142285, 6430.582012169194, 6432.935670643421, 6435.06691529735, 6520.413162815369, 6525.347465253437, 6550.115734375989, 6579.572327798678, 6649.634270114997, 6231.781887633911, 6323.241211054703, 6341.143945567289, 6365.172527571386, 6448.838779030561], "value": 6127.232271401223, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [825.0800357601277, 859.9140085991402, 884.8938127424706, 887.9955600221998, 1133.5012594458433, 32698.0, 33245.66754332456, 44621.33068003983, 45352.77323613386, 45503.0, 46146.84632884171, 46483.53516464835, 46719.29921051187, 46739.0, 46774.12903483857, 7895.921040789591, 7898.447108702393, 8053.59732013397, 8066.959665201666, 8068.152843951389, 7884.053913530384, 8002.19978002201, 8003.759887203393, 8003.8799418008775, 8008.0, 7954.562499062549, 7959.482633628824, 8018.839623207532, 8020.358371330309, 8026.277635012848, 7931.8413631727335, 7973.1628179040945, 7980.920190798105, 7984.600769961478, 7998.360131189491, 7868.606569671493, 7878.487898286593, 7940.602969851542, 7941.364690824778, 7950.681972721084, 40784.0, 40786.79606601978, 40911.0, 40926.38610420846, 41076.589234107574, 3530.8411121499594, 3581.5702115746276, 3592.802395868226, 3593.9101522462147, 3599.4240921452442, 32557.0, 32752.0, 32828.507572386436, 32870.671293287, 32889.50665740016, 4912.38595175603, 5095.541401273903, 5097.337346144977, 5135.614828887811, 5135.87160320995, 196133.0, 196313.0, 197055.0, 197577.0, 198177.00911495494, 51001.48998509967, 51012.74493627508, 51213.231801523005, 51456.48543514517, 51555.0, 52607.21089183665, 52977.2053419199, 53028.20457693138, 53088.469115308355, 53235.201471977954, 14654.56036318912, 14866.331015104346, 16424.507264782074, 16816.915915420344, 16832.242549085076, 13607.183568985893, 13742.93128534371, 13772.10481318711, 13781.0, 13816.585502434944, 15280.465183718516, 15396.615084622974, 15408.768868466981, 15450.922745386426, 15574.532764017098, 5770.480656740913, 5901.557383196192, 6005.759769609167, 6023.427774361428, 6053.51571874249, 984.8424252119627, 1010.1111022300475, 1011.4993078426226, 1016.3495362967707, 1016.9694909152737, 22935.885320573623, 23207.76792232056, 23229.186978455673, 23237.070517179123, 23485.647715284285, 1097.5170924793065, 1119.6172726682541, 1119.7760447910287, 1122.0967121467297, 1139.6410130808933, 12324.445399957023, 12424.254544727346, 12436.761382950976, 12516.186447880858, 12605.621831345074, 237520.0, 238291.0, 238481.0, 240740.0, 241410.0, 56184.0, 56766.716166418904, 56848.431515685144, 56942.71528642413, 57175.714121429126, 1431.0984080029723, 1472.9926350368394, 1474.941002359915, 1478.5564330700747, 1478.556433070096, 22981.195658151886, 23130.884345578164, 23151.421214469774, 23374.883125584263, 23403.648945266174, 38843.417348739225, 38900.41649375318, 38904.0, 38929.80535097307, 39001.0, 198655.0, 198706.0, 199672.0, 199965.0, 200640.0, 33326.0, 33463.66536334605, 33572.832135840144, 33785.493217601266, 37762.811185943894, 46778.29832552582, 47178.29232561583, 47333.290000650704, 47339.76330118327, 48038.759806200746, 34372.0, 34557.654423455446, 34681.82659086688, 34966.30067398587, 35280.29439411249, 34362.656373435944, 34414.48378274278, 34438.48342274918, 34505.0, 34594.0, 37365.81317093506, 37380.25239495141, 37626.0, 37749.4337584942, 39187.80406097951, 34684.47973280352, 35260.64739352574, 35457.0, 35464.822675886455, 35672.64327356693, 24855.502889941738, 25508.362290942874, 25659.87170064138, 25876.223713288633, 26037.0], "value": 17847.920784784925, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.6459777237001, 582.2547139661235, 582.9941700582993, 596.3350863786877, 604.5858586867995, 22510.437239069033, 22633.77366226337, 23500.29499115025, 23616.64575031374, 23617.881910590444, 26456.0, 26973.190804275855, 27162.864185679093, 27178.184654460347, 27232.45535089302, 6403.48772098231, 6584.275729669733, 6586.27550969393, 6587.505937054716, 6588.176477940257, 3925.528936527606, 4100.938485922714, 6244.469220116295, 6548.738050477975, 6608.339166083378, 6465.676716164173, 6521.217453905538, 6523.9673801630925, 6524.771632992846, 6525.706343214543, 6515.7719479818215, 6523.5107366947195, 6534.738610455576, 6534.836629084287, 6538.117354157223, 6033.969830150865, 6047.818565443044, 6067.302260240085, 6073.331933487313, 6073.483753880925, 29404.0, 29633.111006669835, 29747.553786693217, 30338.696613033808, 30637.38725225483, 1933.5939452714947, 1987.1951859496783, 1987.3143765400914, 1988.1550341105117, 1989.3634037108131, 19495.80504194954, 19870.503237419034, 20115.698264526043, 20116.698249526267, 20118.396448106432, 2372.6357344526796, 2429.8906549205326, 2474.4927289905527, 2486.0925762096704, 2498.5877330240487, 140570.0, 144488.0, 145126.0, 145194.0, 145228.0, 14100.0, 15142.772858407132, 15310.923445382925, 15494.0, 15905.761413578806, 23248.883755581115, 23463.178788742312, 23526.176583819484, 24167.758322416903, 24317.39206519851, 10184.287099903084, 10357.792844143225, 10490.947545262377, 10506.579736810594, 10577.735556611146, 9517.428954262767, 9552.37909535878, 9582.520873956275, 9716.271279654055, 9744.658936937314, 9463.574139163755, 9531.0, 9657.565409556586, 9767.560459779326, 9824.852627210597, 2780.7636350910398, 2793.8742756576003, 2842.7867909906427, 2848.1740295314125, 2916.7229113234207, 971.8590804333473, 982.543117450389, 983.4394395194813, 999.8450240212778, 1008.2589296866797, 20003.59992800136, 20069.0, 20079.899600502195, 20225.393238202876, 20327.288544900857, 1029.5367084811865, 1045.2160879340495, 1053.1732589916878, 1055.2929537210089, 1058.6083149234832, 10971.83542246867, 10976.286541374786, 11003.284786488852, 11204.943975280072, 11229.66311010671, 459658.0, 460031.0, 461195.0, 465274.0, 479430.0, 115679.0, 118012.0, 123979.38010309891, 124420.37789810993, 125085.37457312837, 1246.8067449545335, 1260.1179174577833, 1311.6983093888268, 1316.789313709802, 1317.3742472325569, 21056.68414973746, 21059.57880842344, 21060.104590586307, 21126.0, 21195.25816596412, 55959.0, 56717.0, 57391.0, 58474.7076264616, 59547.70226148841, 68318.3168168312, 69053.65473172603, 69385.30614693991, 69739.65130174317, 70424.29575704178, 15045.473408430653, 15390.923045384701, 15449.459268925533, 15514.844851551792, 15520.922395388403, 24130.63804042976, 24207.51584968326, 24524.754752452962, 24614.0, 24714.876425617756, 19196.136173872488, 19245.422637320902, 19294.7105793416, 19346.129424175666, 19458.416247512596, 17087.316507340063, 17628.206730696893, 18166.455006349832, 18261.452156435327, 18360.449186524427, 18814.905925470284, 20111.698324525438, 20264.392068237976, 20329.898350508152, 20389.38831834988, 14862.033967792058, 14912.105273683614, 15180.316885740389, 15275.159866207465, 15322.770158447855, 19044.904775476032, 19359.903200483906, 19369.32207372735, 19385.903070484557, 19414.0], "value": 12258.3335753641, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.9733205335895, 1399.9370028348724, 1400.8178936738223, 1407.0, 1420.0, 13625.318734063294, 14161.504347347845, 14189.361478733455, 14223.644408889782, 14439.855601443982, 14646.34091465884, 14919.77620335693, 14936.701265974674, 14936.701265974702, 14941.0, 2658.9601155982627, 2683.7852971762213, 2703.0809524761576, 2705.4318593095472, 2747.8076534642582, 2649.2052384284734, 2663.5205662980657, 2686.1001564475923, 2687.0595291647865, 2687.6774787025583, 2626.8292560983473, 2645.973540264592, 2647.1793743939343, 2658.2689760315875, 2667.1198504493473, 2559.769620734124, 2561.4620929604807, 2570.958761701514, 2596.1692258477296, 2596.6754155730628, 2527.5324065048026, 2569.357660584854, 2577.5231582157176, 2630.592258199963, 2643.722409147031, 13641.454341826315, 13717.794233086512, 13751.931240343734, 13814.792778108336, 13820.861791382058, 7528.435367347471, 7774.144844067148, 7793.415493837985, 7810.531368117931, 7825.060992680857, 5009.849704508871, 5162.1224391853475, 5206.765695543671, 5272.314599102092, 5320.308359913187, 1809.3757653609484, 1873.6908410112314, 1892.9526761830928, 1911.808819118077, 1937.2251099560149, 21193.152273909025, 21758.673619895715, 21778.455538611503, 21793.346199614036, 21846.453838654, 6692.431143352869, 6697.263301036882, 6789.0, 6918.06606108179, 6922.996165555966, 17327.133643318037, 17391.217395217245, 17392.65214695699, 17444.127793610267, 17480.47558573245, 6891.517593768488, 7947.880781788278, 8180.304674102649, 8195.795105122419, 8206.261436470746, 6343.238811342669, 6800.897986530206, 7061.223265440797, 7188.137423509108, 7238.638068096574, 5015.27278544609, 5023.120953833065, 5028.924566131511, 5125.384953805567, 5127.897561999563, 1321.6497628128554, 1328.734253149374, 1330.0, 1344.4554955243143, 1367.8700523450257, 884.9778755531034, 912.0742446416892, 918.8897332320031, 919.8206349761866, 931.6785708930408, 7230.566166030055, 7316.670749816271, 7324.780256592311, 7371.6682749276415, 7372.741954031583, 732.5311800447718, 734.181387752652, 739.5082270290196, 744.8547533231076, 747.7868807389867, 5092.719900405513, 5200.375954885362, 5259.0796610593, 5269.1832765921345, 5363.19552067193, 18641.347552835858, 19226.134823932956, 19330.226790928486, 19734.802651973583, 19794.109265083105, 18661.0, 19262.903685481764, 19554.902225488782, 19648.70526942097, 19659.311924082587, 10630.893691062991, 10768.676939691823, 11046.0, 11151.442427878736, 11213.60752373663, 8156.714514991947, 8326.875096873673, 8481.36389770782, 8577.228049475327, 8643.697470588499, 16716.498505044867, 17307.0, 17338.30646774115, 17707.46877593674, 17730.20214090343, 17217.13914304305, 17245.22396492186, 17582.560435989202, 17659.823401765818, 17699.646007080042, 10596.788064238826, 10825.242233043611, 10875.293105948089, 11600.593979210687, 11850.0, 13057.08600397963, 13261.933690331487, 13309.401076951754, 13330.733385332433, 13337.866621334051, 10849.783004339712, 10919.181061420584, 10949.562017519529, 10950.671479855617, 10984.725381865517, 10417.739556511147, 10579.682609521727, 10792.244542881923, 10858.185636077167, 10883.0, 8961.372703910663, 9141.817163656822, 9169.679061232824, 9176.724698259062, 9209.033051529494, 7005.649717514206, 7005.964970175117, 7066.04608377883, 7069.222385537484, 7107.18267399235, 6785.762498312535, 6838.726450942106, 6915.481338899502, 6968.825779355557, 6999.2583633223085], "value": 6346.384272351347, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.7717911171305, 495.7298272441519, 520.2170733046764, 528.4372143666994, 531.6677076826983, 6255.030470277109, 6267.840449516837, 6283.4030767077165, 6354.33279505651, 6385.553011289212, 5930.199423077883, 6138.685110040573, 6143.907841382383, 6210.844728881781, 6219.346968568303, 4994.625403094782, 5011.198208286675, 5023.120953833065, 5024.497550244982, 5043.84868453947, 4962.702237865721, 4985.700857948516, 4996.225585034325, 5004.299398084271, 5055.696658200502, 5003.624728145385, 5151.227315902607, 5174.741262936838, 5199.428062913076, 5200.92198617021, 5169.6898186108765, 5202.505761952609, 5223.817166399158, 5237.0, 5237.214417837316, 4819.349387832633, 4835.226363781813, 4852.563269305744, 4860.708357498562, 4865.245886887538, 6101.237345331833, 6154.384561543855, 6156.384361563808, 6158.3841615838055, 6167.814965551041, 5954.583179177459, 5989.341172471025, 5999.340072592011, 6028.156058151862, 6056.909146362808, 6578.703958321887, 6578.967105164492, 6607.966960165217, 6611.950295408391, 6619.834504137387, 5227.424983251839, 5278.841634750964, 5288.735563221823, 5309.840704778863, 5316.308879845634, 6882.931170688279, 6932.757353492654, 6941.861162776716, 7008.509404341698, 7062.258462861429, 5423.430539793344, 5447.319085114361, 5487.972560137254, 5489.1217405215775, 5499.890002199933, 5551.306086739158, 5572.414896435897, 5607.943920560824, 5610.0, 5617.887642247132, 5941.257342832146, 5981.730822113015, 5986.281646202484, 5997.6401415914315, 6022.57841951068, 6536.117624120778, 6543.625838573953, 6608.4052435281055, 6674.566153200123, 6675.399214070757, 1809.719493478513, 1881.2757088521018, 1890.2344550457087, 1913.158210387425, 1954.5993071420326, 1441.942322307096, 1461.919594422317, 1462.4588902106289, 1471.5144002479155, 1480.8371079181281, 5640.407757185437, 5684.658920464785, 5699.515541178963, 5720.713964301768, 5736.311642602915, 6471.935280647227, 6582.80251592453, 6608.4052435281055, 6637.668116594247, 6723.327667233335, 5698.373178950312, 5736.598438109292, 5748.741306641211, 5758.445219790717, 5770.182392546419, 3556.3598552260323, 3562.412201986695, 3584.7311451640708, 3609.4766258892314, 3616.4213725803747, 6390.584612000298, 6456.192975878015, 6486.837829054217, 6491.350864913471, 6562.80311590653, 6865.798485265059, 6877.9656101720175, 6933.237343892168, 6934.479914006369, 6949.861002780017, 6452.967735161482, 6455.160829092188, 6498.707558159799, 6508.66572352676, 6569.868602628017, 6695.598264104169, 6702.396784289437, 6726.428253598499, 6728.259891411843, 6737.86524269502, 6659.334066593302, 6668.899966500603, 6683.398494135551, 6772.25505194438, 6787.5588086774205, 6437.967810160919, 6498.64257465825, 6515.478761698958, 6532.183477065367, 6563.310852360624, 5034.672746271627, 5065.594752419724, 5068.82259120929, 5076.340075790124, 5080.7205603692155, 6801.421879140131, 6807.591544507345, 6846.623435711083, 6852.5203235773015, 6854.588724676535, 6509.511786615928, 6519.771807986698, 6520.978496666049, 6523.478121750343, 6546.574472659262, 6337.176167098248, 6505.5446118773125, 6541.771038013647, 6560.0, 6599.175103112111, 6195.876082478415, 6364.268109167505, 6381.691753190492, 6382.7766028190645, 6389.0, 6477.384648458389, 6480.546361754632, 6487.22153341602, 6498.252700939453, 6507.837304067436, 6327.588706734047, 6328.715207815567, 6347.5556711029785, 6367.140436041261, 6374.776882809079], "value": 5173.014219510334, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [905.1401168889556, 909.6225066597362, 916.9679061232856, 925.1026504290836, 931.1759093202513, 18699.532511687197, 19756.901215493937, 19812.90093549532, 19849.900750496246, 21513.677294840552, 21149.577008459823, 21864.8906755466, 21982.89008554959, 22105.66841497379, 22105.889470552625, 1032.7986042721673, 1047.5181416548396, 1047.6228557719214, 1047.9056884880358, 1052.9157667386628, 1047.1047254597324, 1047.7433028907928, 1051.7633532455213, 1052.1319911073356, 1052.2003277509073, 1021.8109649714789, 1100.3287994323457, 1104.618906477264, 1113.5879724501945, 1114.788190243855, 1103.0293341859192, 1103.3104309806372, 1104.5581767293068, 1105.7401510645032, 1107.800595892739, 804.8631732605471, 806.7942674617935, 807.0275318241505, 807.1444269074799, 807.2815194476921, 19801.207951681914, 20259.189632414687, 20601.381958541268, 20881.164753409845, 20897.268595599082, 21373.0, 21527.354179374644, 21848.344549663536, 22013.779862201332, 22066.117355305767, 16492.752608710878, 16535.83464165355, 16657.583560410967, 16665.0, 16712.74930876038, 3592.586852511942, 3635.600083990759, 3635.836387362575, 3636.7454278200535, 3637.418013117888, 123662.38168809189, 128682.0, 129791.99999999905, 132033.0, 133102.0, 16839.831601683825, 16873.325066997182, 17395.47813565595, 17453.73819392685, 17511.824881751276, 30429.391412172074, 30882.382352352826, 31179.688203118134, 31611.52582711261, 31865.0, 7680.846383072308, 8016.158303378178, 8036.156203598655, 8514.48913065218, 8574.3997920145, 3774.3772277573894, 4048.0891799344913, 4149.564295748903, 4180.0, 4180.226658068267, 5312.309399778082, 5358.11591087474, 5406.972965135228, 5450.727463626803, 5451.318585176853, 1060.3160961179997, 1088.7931293054294, 1089.2538611051455, 1096.7477480179602, 1099.8405231241425, 694.6804469943883, 705.4673721340355, 709.549436108075, 713.0801266366329, 724.2757242757243, 11699.590514331958, 12308.199967002116, 12446.705886469153, 12813.0, 13062.412191451218, 695.6660802814577, 696.6098984568657, 701.4038067642565, 706.219627311813, 717.3651318583092, 5550.153339815976, 5576.247206627054, 5633.0987042073075, 5662.0, 5790.594658373958, 107250.46374768077, 107627.46186269018, 107673.0, 108116.45941770398, 111241.44379278051, 95191.0, 96682.0, 96764.0, 99073.50463247782, 99758.5012074935, 80089.59955200186, 80545.0, 81611.59194203992, 82413.0, 84843.15156848353, 11083.667489975314, 11220.887791121984, 11222.0, 11269.323840569592, 11348.546058157912, 38564.22871542609, 38926.80536597299, 39529.0, 40619.1876162481, 41401.585984139776, 48469.75765121152, 48891.26663100127, 49146.0, 49581.50418495913, 50149.24776128434, 15386.692266154838, 15582.298796553956, 15612.141332226836, 15706.607334816721, 15715.764263535828, 16346.749845040613, 20822.27122050721, 21188.682169767773, 21227.36317910465, 21346.14615415366, 18183.727244091086, 18231.635367292845, 18490.537736556693, 18666.160022798736, 19160.329388471335, 17326.13369331503, 17327.047012413936, 17376.82623173752, 17386.652266954843, 17546.21042053085, 18167.36414225496, 18418.72371914449, 18436.81563184351, 18570.071496424855, 18579.81420185835, 13715.451381944611, 13879.236641984788, 13986.811121054825, 13989.930050349683, 14053.36759845789, 13243.271620060988, 13288.867111328764, 13484.797728034284, 13497.39261733244, 13552.525661601796], "value": 9121.975718956246, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1539.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 106.0, 99.0, 107.0, 112.0, 110.0, 107.0, 100.0, 116.0, 111.0], "value": 106.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 125.0, 145.0, 173.0, 123.0, 146.0, 145.0, 117.0, 180.0, 118.0], "value": 141.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 170.0, 164.0, 166.0, 166.0, 167.0, 168.0, 163.0, 164.0, 166.0], "value": 165.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 74.0, 184.0, 75.0, 75.0, 75.0, 74.0, 149.0, 79.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 126.0, 84.0, 78.0, 79.0, 129.0, 81.0, 83.0, 132.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 114.0, 113.0, 114.0, 114.0, 118.0, 117.0, 119.0, 115.0], "value": 115.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 151.0, 150.0, 151.0, 151.0, 152.0, 150.0, 151.0, 152.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 99.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 53.0, 59.0, 55.0, 54.0, 57.0, 54.0, 53.0, 55.0, 54.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 61.0, 54.0, 53.0, 54.0, 54.0, 54.0, 56.0, 54.0, 54.0], "value": 54.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 80.0, 79.0, 87.0, 78.0, 80.0, 87.0, 89.0, 88.0, 90.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 128.0, 144.0, 135.0, 134.0, 129.0, 146.0, 137.0, 138.0, 166.0], "value": 138.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 173.0, 178.0, 183.0, 170.0, 172.0, 176.0, 172.0, 169.0, 169.0], "value": 174.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 71.0, 69.0, 65.0, 68.0, 66.0, 64.0, 66.0, 65.0], "value": 67.5, "unit": "score"}]}]} 03:42:12 INFO - Return code: 0 03:42:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:42:12 INFO - # TBPL SUCCESS # 03:42:12 INFO - Running post-action listener: _package_coverage_data 03:42:12 INFO - Running post-action listener: _resource_record_post_action 03:42:12 INFO - [mozharness: 2017-08-09 10:42:12.104992Z] Finished run-tests step (success) 03:42:12 INFO - Running post-run listener: _resource_record_post_run 03:42:12 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 0; Write bytes: 430694400; Read time: 0; Write time: 280624 03:42:12 INFO - TinderboxPrint: CPU usage
13.2% 03:42:12 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 03:42:12 INFO - TinderboxPrint: I/O write bytes / time
430,694,400 / 280,624 03:42:12 INFO - TinderboxPrint: CPU idle
8,286.2 (86.8%) 03:42:12 INFO - TinderboxPrint: CPU user
1,205.3 (12.6%) 03:42:12 INFO - TinderboxPrint: Swap in / out
0 / 0 03:42:12 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 106188800; Read time: 0; Write time: 71552 03:42:12 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:42:12 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 324120576; Read time: 0; Write time: 209068 03:42:12 INFO - Running post-run listener: _upload_blobber_files 03:42:12 INFO - Blob upload gear active. 03:42:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:42:12 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 03:42:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:42:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:42:13 INFO - (blobuploader) - INFO - Open directory for files ... 03:42:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:42:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:42:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:14 INFO - (blobuploader) - INFO - Done attempting. 03:42:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:42:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:42:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:14 INFO - (blobuploader) - INFO - Done attempting. 03:42:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:42:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:42:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:21 INFO - (blobuploader) - INFO - Done attempting. 03:42:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:42:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:42:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:22 INFO - (blobuploader) - INFO - Done attempting. 03:42:22 INFO - (blobuploader) - INFO - Iteration through files over. 03:42:22 INFO - Return code: 0 03:42:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:42:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:42:22 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24fc896e04fd98fa17d43a5802ae7cd95deec94514ce4f699bf305c9856c32a7767dffb35180e3a9e154af9245202078584d2e3d78425792904ea46763df3719", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22d89d943b1fad172b2e69ed98248e0a1ab0ecc737c84ea7430ba57fd5b80003e130e5aa6fcfe00858991378e11113c78ece94c6d83dac1c59aa50ad6ff3230d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edc93124aeb43c396659813f655ff197e2b851b4620b97df2c81a101c938b7a85308a02ab3b8940001c1f13d24e883b098e83818549af2d207134466fae651a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35a760409a2bf98123acf12134fa6de72f5bc78bb224642bd338b6f2770ba79ff443dc3f10cea3d17ce8ba7a3e66be450ac8cb34c24df48f38a2b115ff6bc1b8"} 03:42:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:42:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:42:22 INFO - Contents: 03:42:22 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24fc896e04fd98fa17d43a5802ae7cd95deec94514ce4f699bf305c9856c32a7767dffb35180e3a9e154af9245202078584d2e3d78425792904ea46763df3719", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22d89d943b1fad172b2e69ed98248e0a1ab0ecc737c84ea7430ba57fd5b80003e130e5aa6fcfe00858991378e11113c78ece94c6d83dac1c59aa50ad6ff3230d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edc93124aeb43c396659813f655ff197e2b851b4620b97df2c81a101c938b7a85308a02ab3b8940001c1f13d24e883b098e83818549af2d207134466fae651a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35a760409a2bf98123acf12134fa6de72f5bc78bb224642bd338b6f2770ba79ff443dc3f10cea3d17ce8ba7a3e66be450ac8cb34c24df48f38a2b115ff6bc1b8"} 03:42:22 INFO - Running post-run listener: copy_logs_to_upload_dir 03:42:22 INFO - Copying logs to upload dir... 03:42:22 INFO - mkdir: /builds/slave/test/build/upload/logs 03:42:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.151439 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-08-09 03:42:21.768276) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-09 03:42:21.775484) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24fc896e04fd98fa17d43a5802ae7cd95deec94514ce4f699bf305c9856c32a7767dffb35180e3a9e154af9245202078584d2e3d78425792904ea46763df3719", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22d89d943b1fad172b2e69ed98248e0a1ab0ecc737c84ea7430ba57fd5b80003e130e5aa6fcfe00858991378e11113c78ece94c6d83dac1c59aa50ad6ff3230d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edc93124aeb43c396659813f655ff197e2b851b4620b97df2c81a101c938b7a85308a02ab3b8940001c1f13d24e883b098e83818549af2d207134466fae651a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35a760409a2bf98123acf12134fa6de72f5bc78bb224642bd338b6f2770ba79ff443dc3f10cea3d17ce8ba7a3e66be450ac8cb34c24df48f38a2b115ff6bc1b8"} build_url:https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011453 build_url: 'https://queue.taskcluster.net/v1/task/XrRJHuDETWmVhpX8_j42gA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/24fc896e04fd98fa17d43a5802ae7cd95deec94514ce4f699bf305c9856c32a7767dffb35180e3a9e154af9245202078584d2e3d78425792904ea46763df3719", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/22d89d943b1fad172b2e69ed98248e0a1ab0ecc737c84ea7430ba57fd5b80003e130e5aa6fcfe00858991378e11113c78ece94c6d83dac1c59aa50ad6ff3230d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edc93124aeb43c396659813f655ff197e2b851b4620b97df2c81a101c938b7a85308a02ab3b8940001c1f13d24e883b098e83818549af2d207134466fae651a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/35a760409a2bf98123acf12134fa6de72f5bc78bb224642bd338b6f2770ba79ff443dc3f10cea3d17ce8ba7a3e66be450ac8cb34c24df48f38a2b115ff6bc1b8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-09 03:42:21.803339) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 03:42:21.803671) ========= 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=dbb0bb31bf4a4c7db9ba1648000001b6-1502270274.676745-1420218758 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004401 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-09 03:42:21.888129) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-09 03:42:21.888474) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-09 03:42:21.888786) ========= ========= Total master_lag: 0.23 =========