builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-011 starttime: 1495813515.93 results: success (0) revision: 43b0fdd5b94a28a6b8f4da567b9e6af46a6b5d8e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 08:45:15.926963) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 08:45:15.927464) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 08:45:15.929820) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 08:45:15.970252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 08:45:15.970682) ========= 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=5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-26 08:45:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.9M=0s 2017-05-26 08:45:16 (69.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.314956 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 08:45:16.357243) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 08:45:16.357644) ========= 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=5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020355 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 08:45:16.411731) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 08:45:16.412081) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 43b0fdd5b94a28a6b8f4da567b9e6af46a6b5d8e --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 43b0fdd5b94a28a6b8f4da567b9e6af46a6b5d8e --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=5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-26 08:45:16,407 truncating revision to first 12 chars 2017-05-26 08:45:16,407 Setting DEBUG logging. 2017-05-26 08:45:16,407 attempt 1/10 2017-05-26 08:45:16,407 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/43b0fdd5b94a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-26 08:45:16,695 unpacking tar archive at: mozilla-central-43b0fdd5b94a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.631657 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 08:45:17.056490) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 08:45:17.061113) ========= script_repo_revision: 43b0fdd5b94a28a6b8f4da567b9e6af46a6b5d8e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 08:45:17.061750) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 08:45:17.062122) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 08:45:17.078060) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 2 secs) (at 2017-05-26 08:45:17.078486) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:45:17 INFO - MultiFileLogger online at 20170526 08:45:17 in /builds/slave/test 08:45:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:45:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:45:17 INFO - {'append_to_log': False, 08:45:17 INFO - 'base_work_dir': '/builds/slave/test', 08:45:17 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:45:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:45:17 INFO - 'branch': 'Firefox-Non-PGO', 08:45:17 INFO - 'buildbot_json_path': 'buildprops.json', 08:45:17 INFO - 'config_files': ('talos/linux_config.py',), 08:45:17 INFO - 'default_actions': ('clobber', 08:45:17 INFO - 'read-buildbot-config', 08:45:17 INFO - 'download-and-extract', 08:45:17 INFO - 'populate-webroot', 08:45:17 INFO - 'create-virtualenv', 08:45:17 INFO - 'install', 08:45:17 INFO - 'run-tests'), 08:45:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:45:17 INFO - 'download_minidump_stackwalk': True, 08:45:17 INFO - 'download_symbols': 'ondemand', 08:45:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:45:17 INFO - 'tooltool.py': '/tools/tooltool.py', 08:45:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:45:17 INFO - '/tools/misc-python/virtualenv.py')}, 08:45:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:45:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:45:17 INFO - 'gecko_profile': False, 08:45:17 INFO - 'gecko_profile_interval': 0, 08:45:17 INFO - 'installer_path': 'installer.exe', 08:45:17 INFO - 'log_level': 'info', 08:45:17 INFO - 'log_name': 'talos', 08:45:17 INFO - 'log_to_console': True, 08:45:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:45:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:45:17 INFO - 'opt_config_files': (), 08:45:17 INFO - 'pip_index': False, 08:45:17 INFO - 'suite': 'dromaeojs-e10s', 08:45:17 INFO - 'system_bits': '32', 08:45:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:45:17 INFO - 'title': 'talos-linux64-ix-011', 08:45:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:45:17 INFO - 'use_talos_json': True, 08:45:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:45:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:45:17 INFO - 'work_dir': 'build'} 08:45:17 INFO - [mozharness: 2017-05-26 15:45:17.298986Z] Running clobber step. 08:45:17 INFO - Running pre-action listener: _resource_record_pre_action 08:45:17 INFO - Running main action method: clobber 08:45:17 INFO - rmtree: /builds/slave/test/build 08:45:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:45:18 INFO - Running post-action listener: _resource_record_post_action 08:45:18 INFO - [mozharness: 2017-05-26 15:45:18.273003Z] Finished clobber step (success) 08:45:18 INFO - [mozharness: 2017-05-26 15:45:18.273103Z] Running read-buildbot-config step. 08:45:18 INFO - Running pre-action listener: _resource_record_pre_action 08:45:18 INFO - Running main action method: read_buildbot_config 08:45:18 INFO - Using buildbot properties: 08:45:18 INFO - { 08:45:18 INFO - "project": "", 08:45:18 INFO - "product": "firefox", 08:45:18 INFO - "who": "ffxbld@noreply.mozilla.org", 08:45:18 INFO - "installer_path": "public/build/target.tar.bz2", 08:45:18 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 08:45:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:45:18 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 08:45:18 INFO - "stage_platform": "linux64", 08:45:18 INFO - "basedir": "/builds/slave/test", 08:45:18 INFO - "buildnumber": 855, 08:45:18 INFO - "platform": "ubuntu64_hw", 08:45:18 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:45:18 INFO - "slavebuilddir": "test", 08:45:18 INFO - "taskId": "Q-qHmTf_QcOBHAVn8OI_XA", 08:45:18 INFO - "branch": "mozilla-central", 08:45:18 INFO - "script_repo_revision": "production", 08:45:18 INFO - "revision": "43b0fdd5b94a28a6b8f4da567b9e6af46a6b5d8e", 08:45:18 INFO - "slavename": "talos-linux64-ix-011", 08:45:18 INFO - "repo_path": "mozilla-central" 08:45:18 INFO - } 08:45:18 INFO - Finding installer, test and symbols from parent task. 08:45:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q-qHmTf_QcOBHAVn8OI_XA'}, attempt #1 08:45:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q-qHmTf_QcOBHAVn8OI_XA'}, attempt #1 08:45:19 INFO - Task dependencies: SbFTaHztR-yIz2-oJJAJmg 08:45:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg'}, attempt #1 08:45:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.tar.bz2 08:45:19 INFO - Set test_url: https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/test_packages.json 08:45:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.crashreporter-symbols.zip 08:45:19 INFO - Running post-action listener: _resource_record_post_action 08:45:19 INFO - [mozharness: 2017-05-26 15:45:19.666137Z] Finished read-buildbot-config step (success) 08:45:19 INFO - [mozharness: 2017-05-26 15:45:19.666358Z] Running download-and-extract step. 08:45:19 INFO - Running pre-action listener: _resource_record_pre_action 08:45:19 INFO - Running main action method: download_and_extract 08:45:19 INFO - mkdir: /builds/slave/test/build/tests 08:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:19 INFO - trying https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.test_packages.json 08:45:19 INFO - Downloading https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:45:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:45:20 INFO - Downloaded 986 bytes. 08:45:20 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:45:20 INFO - Using the following test package requirements: 08:45:20 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:45:20 INFO - u'common': [u'target.common.tests.zip'], 08:45:20 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:45:20 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:45:20 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:45:20 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:45:20 INFO - u'mozbase': [u'target.common.tests.zip'], 08:45:20 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:45:20 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:45:20 INFO - u'web-platform': [u'target.common.tests.zip', 08:45:20 INFO - u'target.web-platform.tests.zip'], 08:45:20 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:45:20 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:45:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.common.tests.zip 08:45:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:45:20 INFO - Fetch https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.common.tests.zip into memory 08:45:22 INFO - Content-Length response header: 63525236 08:45:22 INFO - Bytes received: 63525236 08:45:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:45:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.common.tests.zip 08:45:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:45:33 INFO - Fetch https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.common.tests.zip into memory 08:45:34 INFO - Content-Length response header: 63525236 08:45:34 INFO - Bytes received: 63525236 08:45:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.talos.tests.zip 08:45:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:45:48 INFO - Fetch https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.talos.tests.zip into memory 08:45:48 INFO - Content-Length response header: 14411888 08:45:48 INFO - Bytes received: 14411888 08:45:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:49 INFO - trying https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.tar.bz2 08:45:49 INFO - Downloading https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:45:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:45:50 INFO - Downloaded 54155521 bytes. 08:45:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.tar.bz2 08:45:50 INFO - mkdir: /builds/slave/test/properties 08:45:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:45:50 INFO - Writing to file /builds/slave/test/properties/build_url 08:45:50 INFO - Contents: 08:45:50 INFO - build_url:https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.tar.bz2 08:45:50 INFO - Running post-action listener: _resource_record_post_action 08:45:50 INFO - Running post-action listener: set_extra_try_arguments 08:45:50 INFO - [mozharness: 2017-05-26 15:45:50.233035Z] Finished download-and-extract step (success) 08:45:50 INFO - [mozharness: 2017-05-26 15:45:50.233134Z] Running populate-webroot step. 08:45:50 INFO - Running pre-action listener: _resource_record_pre_action 08:45:50 INFO - Running main action method: populate_webroot 08:45:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:45:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:45:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:45:50 INFO - '--tppagecycles', 08:45:50 INFO - '7'], 08:45:50 INFO - 'tests': ['tsvgm']}}, 08:45:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:45:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:45:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:45:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:45:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:45:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:45:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:45:50 INFO - 'talos_options': ['--disable-e10s'], 08:45:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:45:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:45:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:45:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:45:50 INFO - 'talos_options': ['--disable-e10s'], 08:45:50 INFO - 'tests': ['damp', 'tps']}, 08:45:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:45:50 INFO - 'tests': ['damp', 'tps']}, 08:45:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:45:50 INFO - 'tests': ['dromaeo_dom']}, 08:45:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:45:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:45:50 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:45:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:45:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:45:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:45:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:45:50 INFO - 'tests': ['a11yr', 08:45:50 INFO - 'ts_paint', 08:45:50 INFO - 'tpaint', 08:45:50 INFO - 'sessionrestore', 08:45:50 INFO - 'sessionrestore_no_auto_restore', 08:45:50 INFO - 'tabpaint']}, 08:45:50 INFO - 'other-e10s': {'tests': ['a11yr', 08:45:50 INFO - 'ts_paint', 08:45:50 INFO - 'tpaint', 08:45:50 INFO - 'sessionrestore', 08:45:50 INFO - 'sessionrestore_no_auto_restore', 08:45:50 INFO - 'tabpaint']}, 08:45:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:45:50 INFO - 'ts_paint', 08:45:50 INFO - 'tpaint', 08:45:50 INFO - 'sessionrestore', 08:45:50 INFO - 'sessionrestore_no_auto_restore', 08:45:50 INFO - 'tabpaint']}, 08:45:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:45:50 INFO - 'ts_paint', 08:45:50 INFO - 'tpaint', 08:45:50 INFO - 'sessionrestore', 08:45:50 INFO - 'sessionrestore_no_auto_restore', 08:45:50 INFO - 'tabpaint']}, 08:45:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:45:50 INFO - 'tests': ['a11yr', 08:45:50 INFO - 'ts_paint', 08:45:50 INFO - 'tpaint', 08:45:50 INFO - 'sessionrestore', 08:45:50 INFO - 'sessionrestore_no_auto_restore', 08:45:50 INFO - 'tabpaint']}, 08:45:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:45:50 INFO - 'tests': ['a11yr', 08:45:50 INFO - 'ts_paint', 08:45:50 INFO - 'tpaint', 08:45:50 INFO - 'sessionrestore', 08:45:50 INFO - 'sessionrestore_no_auto_restore', 08:45:50 INFO - 'tabpaint']}, 08:45:50 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:45:50 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:45:50 INFO - 'bloom_basic_ref']}, 08:45:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:45:50 INFO - 'tests': ['tsvgx', 08:45:50 INFO - 'tsvgr_opacity', 08:45:50 INFO - 'tart', 08:45:50 INFO - 'tscrollx', 08:45:50 INFO - 'cart', 08:45:50 INFO - 'tsvg_static']}, 08:45:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:45:50 INFO - 'tsvgr_opacity', 08:45:50 INFO - 'tart', 08:45:50 INFO - 'tscrollx', 08:45:50 INFO - 'cart', 08:45:50 INFO - 'tsvg_static']}, 08:45:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:45:50 INFO - 'talos_options': ['--disable-e10s'], 08:45:50 INFO - 'tests': ['tp5o']}, 08:45:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:45:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:45:50 INFO - 'talos_options': ['--disable-e10s', 08:45:50 INFO - '--xperf_path', 08:45:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:45:50 INFO - 'tests': ['tp5n']}, 08:45:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:45:50 INFO - 'talos_options': ['--xperf_path', 08:45:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:45:50 INFO - 'tests': ['tp5n']}}, 08:45:50 INFO - 'talos.zip': {'path': '', 08:45:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:45:50 INFO - Running post-action listener: _resource_record_post_action 08:45:50 INFO - [mozharness: 2017-05-26 15:45:50.247056Z] Finished populate-webroot step (success) 08:45:50 INFO - [mozharness: 2017-05-26 15:45:50.247145Z] Running create-virtualenv step. 08:45:50 INFO - Running pre-action listener: _resource_record_pre_action 08:45:50 INFO - Running main action method: create_virtualenv 08:45:50 INFO - Creating virtualenv /builds/slave/test/build/venv 08:45:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:45:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:45:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:45:50 INFO - Using real prefix '/usr' 08:45:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:45:50 INFO - Installing distribute.............................................................................................................................................................................................done. 08:45:52 INFO - Installing pip.................done. 08:45:52 INFO - Return code: 0 08:45:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:45:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:45:53 INFO - Reading from file tmpfile_stdout 08:45:53 INFO - Output received: 08:45:53 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:45:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:45: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')]} 08:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45: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')]} 08:45:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:53 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 0x21241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24975c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24947a0>, '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 0x24956a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2495b30>, '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': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:45:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:45:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:45:53 INFO - Using env: {'DISPLAY': ':0', 08:45:53 INFO - 'HOME': '/home/cltbld', 08:45:53 INFO - 'LANG': 'en_US.UTF-8', 08:45:53 INFO - 'LANGUAGE': 'en_US:en', 08:45:53 INFO - 'LOGNAME': 'cltbld', 08:45:53 INFO - 'MAIL': '/var/mail/cltbld', 08:45:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:53 INFO - 'MOZ_NO_REMOTE': '1', 08:45:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:53 INFO - 'NO_EM_RESTART': '1', 08:45:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:53 INFO - 'PWD': '/builds/slave/test', 08:45:53 INFO - 'SHELL': '/bin/bash', 08:45:53 INFO - 'SHLVL': '1', 08:45:53 INFO - 'TERM': 'linux', 08:45:53 INFO - 'TMOUT': '86400', 08:45:53 INFO - 'USER': 'cltbld', 08:45:53 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 08:45:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:53 INFO - '_': '/tools/buildbot/bin/python'} 08:45:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:45:53 INFO - Cleaning up... 08:45:53 INFO - Return code: 0 08:45:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:45: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')]} 08:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45: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')]} 08:45:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:53 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 0x21241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24975c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24947a0>, '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 0x24956a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2495b30>, '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': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:45:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:45:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:45:53 INFO - Using env: {'DISPLAY': ':0', 08:45:53 INFO - 'HOME': '/home/cltbld', 08:45:53 INFO - 'LANG': 'en_US.UTF-8', 08:45:53 INFO - 'LANGUAGE': 'en_US:en', 08:45:53 INFO - 'LOGNAME': 'cltbld', 08:45:53 INFO - 'MAIL': '/var/mail/cltbld', 08:45:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:53 INFO - 'MOZ_NO_REMOTE': '1', 08:45:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:53 INFO - 'NO_EM_RESTART': '1', 08:45:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:53 INFO - 'PWD': '/builds/slave/test', 08:45:53 INFO - 'SHELL': '/bin/bash', 08:45:53 INFO - 'SHLVL': '1', 08:45:53 INFO - 'TERM': 'linux', 08:45:53 INFO - 'TMOUT': '86400', 08:45:53 INFO - 'USER': 'cltbld', 08:45:53 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 08:45:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:53 INFO - '_': '/tools/buildbot/bin/python'} 08:45:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:53 INFO - Downloading/unpacking psutil>=3.1.1 08:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:45:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:45:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:45:54 INFO - Installing collected packages: psutil 08:45:54 INFO - Running setup.py install for psutil 08:45:54 INFO - building 'psutil._psutil_linux' extension 08:45:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:45:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:45:56 INFO - building 'psutil._psutil_posix' extension 08:45:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:45:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:45:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:45:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:45:56 INFO - Successfully installed psutil 08:45:56 INFO - Cleaning up... 08:45:56 INFO - Return code: 0 08:45:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24975c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24947a0>, '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 0x24956a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2495b30>, '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': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:45:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:45:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:45:56 INFO - Using env: {'DISPLAY': ':0', 08:45:56 INFO - 'HOME': '/home/cltbld', 08:45:56 INFO - 'LANG': 'en_US.UTF-8', 08:45:56 INFO - 'LANGUAGE': 'en_US:en', 08:45:56 INFO - 'LOGNAME': 'cltbld', 08:45:56 INFO - 'MAIL': '/var/mail/cltbld', 08:45:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:56 INFO - 'MOZ_NO_REMOTE': '1', 08:45:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:56 INFO - 'NO_EM_RESTART': '1', 08:45:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:56 INFO - 'PWD': '/builds/slave/test', 08:45:56 INFO - 'SHELL': '/bin/bash', 08:45:56 INFO - 'SHLVL': '1', 08:45:56 INFO - 'TERM': 'linux', 08:45:56 INFO - 'TMOUT': '86400', 08:45:56 INFO - 'USER': 'cltbld', 08:45:56 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 08:45:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:56 INFO - '_': '/tools/buildbot/bin/python'} 08:45:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:45:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:45:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:45:57 INFO - Installing collected packages: mozsystemmonitor 08:45:57 INFO - Running setup.py install for mozsystemmonitor 08:45:57 INFO - Successfully installed mozsystemmonitor 08:45:57 INFO - Cleaning up... 08:45:57 INFO - Return code: 0 08:45:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:45: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')]} 08:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45: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')]} 08:45:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45: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', '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 0x21241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24975c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24947a0>, '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 0x24956a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2495b30>, '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': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:45: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:45: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 jsonschema==2.5.1 08:45:57 INFO - Using env: {'DISPLAY': ':0', 08:45:57 INFO - 'HOME': '/home/cltbld', 08:45:57 INFO - 'LANG': 'en_US.UTF-8', 08:45:57 INFO - 'LANGUAGE': 'en_US:en', 08:45:57 INFO - 'LOGNAME': 'cltbld', 08:45:57 INFO - 'MAIL': '/var/mail/cltbld', 08:45:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:57 INFO - 'MOZ_NO_REMOTE': '1', 08:45:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:57 INFO - 'NO_EM_RESTART': '1', 08:45:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:57 INFO - 'PWD': '/builds/slave/test', 08:45:57 INFO - 'SHELL': '/bin/bash', 08:45:57 INFO - 'SHLVL': '1', 08:45:57 INFO - 'TERM': 'linux', 08:45:57 INFO - 'TMOUT': '86400', 08:45:57 INFO - 'USER': 'cltbld', 08:45:57 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 08:45:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:57 INFO - '_': '/tools/buildbot/bin/python'} 08:45:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:58 INFO - Downloading/unpacking jsonschema==2.5.1 08:45: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 08:45: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 08:45: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 08:45: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 08:45:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:45:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:59 INFO - Downloading functools32-3.2.3-2.tar.gz 08:45:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:45:59 INFO - warning: no files found matching '*.txt' 08:45:59 INFO - no previously-included directories found matching 'build' 08:45:59 INFO - no previously-included directories found matching 'dist' 08:45:59 INFO - no previously-included directories found matching '.git*' 08:45:59 INFO - Installing collected packages: jsonschema, functools32 08:45:59 INFO - Running setup.py install for functools32 08:45:59 INFO - warning: no files found matching '*.txt' 08:45:59 INFO - no previously-included directories found matching 'build' 08:45:59 INFO - no previously-included directories found matching 'dist' 08:45:59 INFO - no previously-included directories found matching '.git*' 08:45:59 INFO - Successfully installed jsonschema functools32 08:45:59 INFO - Cleaning up... 08:45:59 INFO - Return code: 0 08:45:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:59 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 0x21241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24975c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24947a0>, '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 0x24956a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2495b30>, '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': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:45:59 INFO - Using env: {'DISPLAY': ':0', 08:45:59 INFO - 'HOME': '/home/cltbld', 08:45:59 INFO - 'LANG': 'en_US.UTF-8', 08:45:59 INFO - 'LANGUAGE': 'en_US:en', 08:45:59 INFO - 'LOGNAME': 'cltbld', 08:45:59 INFO - 'MAIL': '/var/mail/cltbld', 08:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:59 INFO - 'MOZ_NO_REMOTE': '1', 08:45:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:59 INFO - 'NO_EM_RESTART': '1', 08:45:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:59 INFO - 'PWD': '/builds/slave/test', 08:45:59 INFO - 'SHELL': '/bin/bash', 08:45:59 INFO - 'SHLVL': '1', 08:45:59 INFO - 'TERM': 'linux', 08:45:59 INFO - 'TMOUT': '86400', 08:45:59 INFO - 'USER': 'cltbld', 08:45:59 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 08:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:59 INFO - '_': '/tools/buildbot/bin/python'} 08:45:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:45:59 INFO - Cleaning up... 08:45:59 INFO - Return code: 0 08:45:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:59 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 0x21241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24975c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24947a0>, '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 0x24956a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2495b30>, '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': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:45:59 INFO - Using env: {'DISPLAY': ':0', 08:45:59 INFO - 'HOME': '/home/cltbld', 08:45:59 INFO - 'LANG': 'en_US.UTF-8', 08:45:59 INFO - 'LANGUAGE': 'en_US:en', 08:45:59 INFO - 'LOGNAME': 'cltbld', 08:45:59 INFO - 'MAIL': '/var/mail/cltbld', 08:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:59 INFO - 'MOZ_NO_REMOTE': '1', 08:45:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:59 INFO - 'NO_EM_RESTART': '1', 08:45:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:59 INFO - 'PWD': '/builds/slave/test', 08:45:59 INFO - 'SHELL': '/bin/bash', 08:45:59 INFO - 'SHLVL': '1', 08:45:59 INFO - 'TERM': 'linux', 08:45:59 INFO - 'TMOUT': '86400', 08:45:59 INFO - 'USER': 'cltbld', 08:45:59 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 08:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:59 INFO - '_': '/tools/buildbot/bin/python'} 08:46:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:00 INFO - Downloading/unpacking blobuploader==1.2.4 08:46: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 08:46: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 08:46: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 08:46: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 08:46:01 INFO - Downloading blobuploader-1.2.4.tar.gz 08:46:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:46:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:46: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 08:46: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 08:46: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 08:46: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 08:46:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:46:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:46: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 08:46: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 08:46: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 08:46: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 08:46:01 INFO - Downloading docopt-0.6.1.tar.gz 08:46:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:46:01 INFO - Installing collected packages: blobuploader, requests, docopt 08:46:01 INFO - Running setup.py install for blobuploader 08:46:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:46:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:46:01 INFO - Running setup.py install for requests 08:46:02 INFO - Running setup.py install for docopt 08:46:02 INFO - Successfully installed blobuploader requests docopt 08:46:02 INFO - Cleaning up... 08:46:02 INFO - Return code: 0 08:46:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:02 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 0x21241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24975c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24947a0>, '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 0x24956a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2495b30>, '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': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:46:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:46:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:46:02 INFO - Using env: {'DISPLAY': ':0', 08:46:02 INFO - 'HOME': '/home/cltbld', 08:46:02 INFO - 'LANG': 'en_US.UTF-8', 08:46:02 INFO - 'LANGUAGE': 'en_US:en', 08:46:02 INFO - 'LOGNAME': 'cltbld', 08:46:02 INFO - 'MAIL': '/var/mail/cltbld', 08:46:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:02 INFO - 'MOZ_NO_REMOTE': '1', 08:46:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:02 INFO - 'NO_EM_RESTART': '1', 08:46:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:02 INFO - 'PWD': '/builds/slave/test', 08:46:02 INFO - 'SHELL': '/bin/bash', 08:46:02 INFO - 'SHLVL': '1', 08:46:02 INFO - 'TERM': 'linux', 08:46:02 INFO - 'TMOUT': '86400', 08:46:02 INFO - 'USER': 'cltbld', 08:46:02 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 08:46:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:02 INFO - '_': '/tools/buildbot/bin/python'} 08:46:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:02 INFO - Running setup.py (path:/tmp/pip-vQpMsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:02 INFO - Running setup.py (path:/tmp/pip-U1HCUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:02 INFO - Running setup.py (path:/tmp/pip-yafxRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:02 INFO - Running setup.py (path:/tmp/pip-038Vwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:02 INFO - Running setup.py (path:/tmp/pip-13tRtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:02 INFO - Running setup.py (path:/tmp/pip-5b0_te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:02 INFO - Running setup.py (path:/tmp/pip-MBQozq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:03 INFO - Running setup.py (path:/tmp/pip-vYzFn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:03 INFO - Running setup.py (path:/tmp/pip-MgGLMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:03 INFO - Running setup.py (path:/tmp/pip-qCGEm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:03 INFO - Running setup.py (path:/tmp/pip-Fud5I9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:03 INFO - Running setup.py (path:/tmp/pip-GMCeD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:03 INFO - Running setup.py (path:/tmp/pip-Lda6wg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:03 INFO - Running setup.py (path:/tmp/pip-MdwV4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:03 INFO - Running setup.py (path:/tmp/pip-CH4qbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:03 INFO - Running setup.py (path:/tmp/pip-LulTBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:03 INFO - Running setup.py (path:/tmp/pip-dH3y7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:46:03 INFO - Running setup.py install for manifestparser 08:46:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:46:03 INFO - Running setup.py install for mozcrash 08:46:03 INFO - Running setup.py install for mozdebug 08:46:04 INFO - Running setup.py install for mozdevice 08:46:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:46:04 INFO - Running setup.py install for mozfile 08:46:04 INFO - Running setup.py install for mozhttpd 08:46:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:46:04 INFO - Running setup.py install for mozinfo 08:46:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:46:04 INFO - Running setup.py install for mozInstall 08:46:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:46:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:46:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:46:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:46:04 INFO - Running setup.py install for mozleak 08:46:04 INFO - Running setup.py install for mozlog 08:46:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:46:04 INFO - Running setup.py install for moznetwork 08:46:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:46:04 INFO - Running setup.py install for mozprocess 08:46:04 INFO - Running setup.py install for mozprofile 08:46:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:46:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:46:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:46:05 INFO - Running setup.py install for mozrunner 08:46:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:46:05 INFO - Running setup.py install for mozscreenshot 08:46:05 INFO - Running setup.py install for moztest 08:46:05 INFO - Running setup.py install for mozversion 08:46:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:46:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:46:05 INFO - Cleaning up... 08:46:05 INFO - Return code: 0 08:46:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:05 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 0x21241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24975c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24947a0>, '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 0x24956a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2495b30>, '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': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:46:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:46:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:46:05 INFO - Using env: {'DISPLAY': ':0', 08:46:05 INFO - 'HOME': '/home/cltbld', 08:46:05 INFO - 'LANG': 'en_US.UTF-8', 08:46:05 INFO - 'LANGUAGE': 'en_US:en', 08:46:05 INFO - 'LOGNAME': 'cltbld', 08:46:05 INFO - 'MAIL': '/var/mail/cltbld', 08:46:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:05 INFO - 'MOZ_NO_REMOTE': '1', 08:46:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:05 INFO - 'NO_EM_RESTART': '1', 08:46:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:05 INFO - 'PWD': '/builds/slave/test', 08:46:05 INFO - 'SHELL': '/bin/bash', 08:46:05 INFO - 'SHLVL': '1', 08:46:05 INFO - 'TERM': 'linux', 08:46:05 INFO - 'TMOUT': '86400', 08:46:05 INFO - 'USER': 'cltbld', 08:46:05 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 08:46:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:05 INFO - '_': '/tools/buildbot/bin/python'} 08:46:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:05 INFO - Running setup.py (path:/tmp/pip-Qe73gV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:05 INFO - Running setup.py (path:/tmp/pip-jazBgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:05 INFO - Running setup.py (path:/tmp/pip-aXV1Oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:46:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:05 INFO - Running setup.py (path:/tmp/pip-sn82dw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:06 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)) 08:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:06 INFO - Running setup.py (path:/tmp/pip-EdLK53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:06 INFO - Running setup.py (path:/tmp/pip-iuw_jm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:06 INFO - Running setup.py (path:/tmp/pip-lQ2pXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:06 INFO - Running setup.py (path:/tmp/pip-QqKsfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:06 INFO - Running setup.py (path:/tmp/pip-2Cm3BG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:06 INFO - Running setup.py (path:/tmp/pip-UKmLub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:06 INFO - Running setup.py (path:/tmp/pip-vWFESA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:06 INFO - Running setup.py (path:/tmp/pip-8njZJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:06 INFO - Running setup.py (path:/tmp/pip-fob1TQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:06 INFO - Running setup.py (path:/tmp/pip-rMG9hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:06 INFO - Running setup.py (path:/tmp/pip-R3W7_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:06 INFO - Running setup.py (path:/tmp/pip-DDrmri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:46:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:06 INFO - Running setup.py (path:/tmp/pip-DjA6Gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:46:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:46:06 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)) 08:46:06 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)) 08:46:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:08 INFO - Downloading blessings-1.6.tar.gz 08:46:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:46:08 INFO - Installing collected packages: blessings 08:46:08 INFO - Running setup.py install for blessings 08:46:08 INFO - Successfully installed blessings 08:46:08 INFO - Cleaning up... 08:46:08 INFO - Return code: 0 08:46:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:46:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:08 INFO - Reading from file tmpfile_stdout 08:46:08 INFO - Current package versions: 08:46:08 INFO - argparse == 1.2.1 08:46:08 INFO - blessings == 1.6 08:46:08 INFO - blobuploader == 1.2.4 08:46:08 INFO - docopt == 0.6.1 08:46:08 INFO - functools32 == 3.2.3-2 08:46:08 INFO - jsonschema == 2.5.1 08:46:08 INFO - manifestparser == 1.1 08:46:08 INFO - mozInstall == 1.12 08:46:08 INFO - mozcrash == 1.0 08:46:08 INFO - mozdebug == 0.1 08:46:08 INFO - mozdevice == 0.51 08:46:08 INFO - mozfile == 1.2 08:46:08 INFO - mozhttpd == 0.7 08:46:08 INFO - mozinfo == 0.9 08:46:08 INFO - mozleak == 0.1 08:46:08 INFO - mozlog == 3.4 08:46:08 INFO - moznetwork == 0.27 08:46:08 INFO - mozprocess == 0.25 08:46:08 INFO - mozprofile == 0.28 08:46:08 INFO - mozrunner == 6.13 08:46:08 INFO - mozscreenshot == 0.1 08:46:08 INFO - mozsystemmonitor == 0.3 08:46:08 INFO - moztest == 0.8 08:46:08 INFO - mozversion == 1.4 08:46:08 INFO - psutil == 3.1.1 08:46:08 INFO - requests == 1.2.3 08:46:08 INFO - wsgiref == 0.1.2 08:46:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:08 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 0x21241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24975c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24947a0>, '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 0x24956a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2495b30>, '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': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:46:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:46:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:46:08 INFO - Using env: {'DISPLAY': ':0', 08:46:08 INFO - 'HOME': '/home/cltbld', 08:46:08 INFO - 'LANG': 'en_US.UTF-8', 08:46:08 INFO - 'LANGUAGE': 'en_US:en', 08:46:08 INFO - 'LOGNAME': 'cltbld', 08:46:08 INFO - 'MAIL': '/var/mail/cltbld', 08:46:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:08 INFO - 'MOZ_NO_REMOTE': '1', 08:46:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:08 INFO - 'NO_EM_RESTART': '1', 08:46:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:08 INFO - 'PWD': '/builds/slave/test', 08:46:08 INFO - 'SHELL': '/bin/bash', 08:46:08 INFO - 'SHLVL': '1', 08:46:08 INFO - 'TERM': 'linux', 08:46:08 INFO - 'TMOUT': '86400', 08:46:08 INFO - 'USER': 'cltbld', 08:46:08 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 08:46:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:08 INFO - '_': '/tools/buildbot/bin/python'} 08:46:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:46:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:46:08 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:09 INFO - Downloading marionette_driver-1.4.0.tar.gz 08:46:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 08:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:46:09 INFO - Installing collected packages: marionette-driver 08:46:09 INFO - Running setup.py install for marionette-driver 08:46:10 INFO - Successfully installed marionette-driver 08:46:10 INFO - Cleaning up... 08:46:10 INFO - Return code: 0 08:46:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21241f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24975c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24947a0>, '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 0x24956a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2495b30>, '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': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:46:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:46:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:46:10 INFO - Using env: {'DISPLAY': ':0', 08:46:10 INFO - 'HOME': '/home/cltbld', 08:46:10 INFO - 'LANG': 'en_US.UTF-8', 08:46:10 INFO - 'LANGUAGE': 'en_US:en', 08:46:10 INFO - 'LOGNAME': 'cltbld', 08:46:10 INFO - 'MAIL': '/var/mail/cltbld', 08:46:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:10 INFO - 'MOZ_NO_REMOTE': '1', 08:46:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:10 INFO - 'NO_EM_RESTART': '1', 08:46:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:10 INFO - 'PWD': '/builds/slave/test', 08:46:10 INFO - 'SHELL': '/bin/bash', 08:46:10 INFO - 'SHLVL': '1', 08:46:10 INFO - 'TERM': 'linux', 08:46:10 INFO - 'TMOUT': '86400', 08:46:10 INFO - 'USER': 'cltbld', 08:46:10 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 08:46:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:10 INFO - '_': '/tools/buildbot/bin/python'} 08:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:46:10 INFO - Cleaning up... 08:46:10 INFO - Return code: 0 08:46:10 INFO - Running post-action listener: _resource_record_post_action 08:46:10 INFO - Running post-action listener: _start_resource_monitoring 08:46:10 INFO - Starting resource monitoring. 08:46:10 INFO - [mozharness: 2017-05-26 15:46:10.327363Z] Finished create-virtualenv step (success) 08:46:10 INFO - [mozharness: 2017-05-26 15:46:10.328297Z] Running install step. 08:46:10 INFO - Running pre-action listener: _resource_record_pre_action 08:46:10 INFO - Running main action method: install 08:46:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:10 INFO - Reading from file tmpfile_stdout 08:46:10 INFO - Detecting whether we're running mozinstall >=1.0... 08:46:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:46:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:46:10 INFO - Reading from file tmpfile_stdout 08:46:10 INFO - Output received: 08:46:10 INFO - Usage: mozinstall [options] installer 08:46:10 INFO - Options: 08:46:10 INFO - -h, --help show this help message and exit 08:46:10 INFO - -d DEST, --destination=DEST 08:46:10 INFO - Directory to install application into. [default: 08:46:10 INFO - "/builds/slave/test"] 08:46:10 INFO - --app=APP Application being installed. [default: firefox] 08:46:10 INFO - mkdir: /builds/slave/test/build/application 08:46:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:46:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:46:26 INFO - Reading from file tmpfile_stdout 08:46:26 INFO - Output received: 08:46:26 INFO - /builds/slave/test/build/application/firefox/firefox 08:46:26 INFO - Running post-action listener: _resource_record_post_action 08:46:26 INFO - [mozharness: 2017-05-26 15:46:26.299155Z] Finished install step (success) 08:46:26 INFO - [mozharness: 2017-05-26 15:46:26.299427Z] Running run-tests step. 08:46:26 INFO - Running pre-action listener: _resource_record_pre_action 08:46:26 INFO - Running main action method: run_tests 08:46:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:46:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:46:26 INFO - Python 2.7.3 08:46:26 INFO - Return code: 0 08:46:26 INFO - grabbing minidump binary from tooltool 08:46:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:26 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 0x24947a0>, '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 0x24956a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2495b30>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:46:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:46:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:46:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:46:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:46:26 INFO - Return code: 0 08:46:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:46:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:46:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:46:26 INFO - ENV: RUST_BACKTRACE is now 1 08:46:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:46:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:46:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 08:46:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 08:46:26 INFO - Using env: {'DISPLAY': ':0', 08:46:26 INFO - 'HOME': '/home/cltbld', 08:46:26 INFO - 'LANG': 'en_US.UTF-8', 08:46:26 INFO - 'LANGUAGE': 'en_US:en', 08:46:26 INFO - 'LOGNAME': 'cltbld', 08:46:26 INFO - 'MAIL': '/var/mail/cltbld', 08:46:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:46:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:26 INFO - 'MOZ_NO_REMOTE': '1', 08:46:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:46:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:46:26 INFO - 'NO_EM_RESTART': '1', 08:46:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:26 INFO - 'PWD': '/builds/slave/test', 08:46:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:46:26 INFO - 'RUST_BACKTRACE': '1', 08:46:26 INFO - 'SHELL': '/bin/bash', 08:46:26 INFO - 'SHLVL': '1', 08:46:26 INFO - 'TERM': 'linux', 08:46:26 INFO - 'TMOUT': '86400', 08:46:26 INFO - 'USER': 'cltbld', 08:46:26 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183', 08:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:26 INFO - '_': '/tools/buildbot/bin/python'} 08:46:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 08:46:26 INFO - mozversion application_buildid: 20170526151657 08:46:26 INFO - mozversion application_changeset: 43b0fdd5b94a28a6b8f4da567b9e6af46a6b5d8e 08:46:26 INFO - mozversion application_display_name: Nightly 08:46:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:46:26 INFO - mozversion application_name: Firefox 08:46:26 INFO - mozversion application_remotingname: firefox 08:46:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:46:26 INFO - mozversion application_vendor: Mozilla 08:46:26 INFO - mozversion application_version: 55.0a1 08:46:26 INFO - mozversion platform_buildid: 20170526151657 08:46:26 INFO - mozversion platform_changeset: 43b0fdd5b94a28a6b8f4da567b9e6af46a6b5d8e 08:46:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:46:26 INFO - mozversion platform_version: 55.0a1 08:46:26 INFO - using testdate: 1495813586 08:46:26 INFO - actual date: 1495813586 08:46:26 INFO - starting webserver on 'localhost:37590' 08:46:26 INFO - SUITE-START | Running 2 tests 08:46:26 INFO - TEST-START | dromaeo_css 08:46:26 INFO - operating with platform_type : linux_ 08:46:26 INFO - Initialising browser for dromaeo_css test... 08:46:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37590/getInfo.html -profile /tmp/tmprmnYYO/profile 08:46:26 INFO - TEST-INFO | started process 3318 (/builds/slave/test/build/application/firefox/firefox http://localhost:37590/getInfo.html) 08:47:04 INFO - TEST-INFO | 3318: killed by SIGTERM 08:47:04 INFO - Browser initialized. 08:47:04 INFO - Running cycle 1/1 for dromaeo_css test... 08:47:04 INFO - TEST-INFO | started process 3517 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprmnYYO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:47:16 INFO - PID 3517 | RSS: Main: 167821312 08:47:16 INFO - PID 3517 | 08:50:20 INFO - PID 3517 | Cycle 1(1): loaded http://localhost:37590/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37590/tests/dromaeo/cssquery-ext.html) 08:50:22 INFO - PID 3517 | RSS: Main: 168411136 08:50:22 INFO - PID 3517 | 08:53:24 INFO - PID 3517 | Cycle 1(1): loaded http://localhost:37590/tests/dromaeo/cssquery-ext.html (next: http://localhost:37590/tests/dromaeo/cssquery-jquery.html) 08:53:24 INFO - PID 3517 | RSS: Main: 172711936 08:53:24 INFO - PID 3517 | 08:56:26 INFO - PID 3517 | Cycle 1(1): loaded http://localhost:37590/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37590/tests/dromaeo/cssquery-mootools.html) 08:56:27 INFO - PID 3517 | RSS: Main: 170983424 08:56:27 INFO - PID 3517 | 08:59:28 INFO - PID 3517 | Cycle 1(1): loaded http://localhost:37590/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37590/tests/dromaeo/cssquery-prototype.html) 08:59:29 INFO - PID 3517 | RSS: Main: 174129152 08:59:29 INFO - PID 3517 | 09:02:32 INFO - PID 3517 | Cycle 1(1): loaded http://localhost:37590/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37590/tests/dromaeo/cssquery-yui.html) 09:02:32 INFO - PID 3517 | RSS: Main: 173674496 09:02:32 INFO - PID 3517 | 09:05:34 INFO - PID 3517 | Cycle 1(1): loaded http://localhost:37590/tests/dromaeo/cssquery-yui.html (next: http://localhost:37590/tests/dromaeo/cssquery-dojo.html) 09:05:34 INFO - PID 3517 | RSS: Main: 170975232 09:05:34 INFO - PID 3517 | 09:05:34 INFO - PID 3517 | __start_tp_report 09:05:34 INFO - PID 3517 | _x_x_mozilla_page_load 09:05:34 INFO - PID 3517 | _x_x_mozilla_page_load_details 09:05:34 INFO - PID 3517 | |i|pagename|runs| 09:05:34 INFO - PID 3517 | |0;dojo.html;2690.7578317951384;2729.4268203677225;2745.5607102863532;2760.544510155823;2770.8060435769494;5215.243789650498;5299.5230429261355;5307.203919412085;5312.442193569673;5364.597655175863;5247.1866860636665;5267.894642107156;5290.629655924077;5341.145416733323;5392.0294347017525;4477.440319960005;4686.995524604749;4720.787564559603;4769.475357710649;4825.831095911643;4787.904241915159;4799.2801079837955;4814.759262036901;4832.130216561018;4863.319135321058;4665.066986602693;4686.554777296152;4738.431388233434;4745.477997420281;4755.072760811643;4728.834490792806;4739.526047395268;4741.336212930192;4784.091022705675;4791.6166706663735;4677.579017888373;4687.343771871941;4697.88255293617;4711.104890070876;4752.5247475252545;5196.142636464979;5288.576913846883;5293.153095504701;5330.600204984642;5346.572274218053;5650.802221922254;5725.599208055438;5733.913991290134;5750.281214848144;5771.989901767174;5761.942380576183;5766.481016708516;5772.624779389326;5779.566532510079;5793.275840519935;5375.301210842605;5378.19327100938;5383.246345511631;5384.6769193848495;5387.0841956867425;5795.391483894215;5832.50423713989;5865.794697185578;5902.645841249453;5908.793192238251;5609.354924183771;5616.185653080281;5643.1535269709875;5645.294338207724;5648.406917273709;5628.97185514078;5689.573282003867;5694.174344720075;5698.515626171822;5716.857078573069;5594.328680558359;5677;5694.288213973254;5699.971500142556;5701.2018317435595;5619.606627536115;5687.857803554862;5689.402612725687;5695.772169113272;5708.086706127;4766.6901651392545;4775.641826863;4782.234842425265;4783.880402989884;4824.782884770194;4098.7130900836655;4101.077257617072;4107.322291821875;4117.711760176819;4141.627253547195;4752.120857641278;4783.067301876101;4846.127697014571;4863.708177509361;4865.5864251538305;5753.942460575425;5755.798547050833;5756.856078598069;5766.394528574523;5818.4763371296785;4821.349117869043;4834.830780922651;4838.927416088762;4845.830395936126;4881.51184881509;4955.925661115014;4982.676126051795;5059.51934566223;5076.44159142494;5097.311862898536;5340.412554618911;5365.409804921377;5366.034113859387;5375.220593014069;5387.86530336729;5690.8008219713765;5745.3105627324985;5749.511291540099;5771.538276937751;5779.682117483579;5140.357455318085;5323.733813309396;5333;5333.253344531846;5373.382061063028;5662.405447427961;5683.60214784961;5702.230198923258;5709.68596727184;5720.942790572041;5671.432856714296;5775.826725198251;5777.277840269966;5777.508911742549;5779.104238842986;5548.639338442988;5566.387697353369;5589.329280486368;5592.356878958972;5632.098864181711;5567.526760225264;5583.944160558342;5586.190002449704;5593.524550413261;5640.492355688008;5500.917486237629;5501.889962200814;5519.724013799374;5519.8896022080135;5526.171074338881;5551.111822108443;5567.832965011056;5635.6618602883955;5644.379118297066;5696.971515142398;5487.451254874641;5499.9175012375645;5507.449255074621;5518.613697041169;5541.9168712470155 09:05:34 INFO - PID 3517 | |1;ext.html;659.4262991197659;712.8075419636698;731.4989232375822;1175.8824117588238;1219.5731493977103;35281;35954.46068308974;36071.81964090179;36189.0952726182;36292.81853590735;34174.14564635879;34376.48435273467;34428;34473.482897756556;34546.65453345466;6453.032045193223;6682.365175308338;6814.693338799766;6815.863682726343;6819.659017049153;6771.762988295411;6872.553284036521;6875.140607424072;6885.48358873084;6886.827829304282;6855.143107111611;6910.378065974061;6918.619475928847;6919.273476284993;6929.896051559205;6861.27956564561;6892.448604111659;6894.034835123087;6897.793066208022;6902.723891044352;6763.560368576076;6769.323067693241;6774.356436138559;6817.863642727118;6832.897506537406;32901.3419731604;33085;33101.503477447855;33211.33577328464;33232.833835830905;3060.6327240731034;3100.4884194107944;3101.0231776990177;3102.0073576455543;3106.130283520595;24975.5004899901;25283.241502754947;25319.873400632878;25328.620070698955;25331.493370132677;3029.2578318312153;3045.5279431688123;3072.4776787946103;3095.5511450839726;3115.43922094023;151638;151892;152438;153855;156295;39680.801595992416;39906;39950.20099597992;40039.39940900889;40048.19903601912;41762.582374176476;41984.851136164296;41991.7900410496;44328.77835610866;44767.32849007267;13191.934040329737;13271;13306.401211945486;13389.397477113553;13406.932965335305;10624.893751062546;10637.946810266054;10671.253012289066;10694.091002264739;10698.679039628823;12889.484420623257;12915.354232288348;12923.418446169944;12932.224066556037;13198.868011319955;5460.262864513319;5483.793565415602;5491.780328786884;5494.066008778517;5511.5039646432015;841.5497708725802;892.2103938014903;893.4326702543931;905.0768216419216;938.1369140390857;21000.789992099883;21431.785682142978;21838;21851.78148218529;22081.44796380071;973.0755782007121;991.4745185051934;992.2012779712401;999.6601155607128;1017.5065093429681;11067.72330691739;11437.085033197323;11445.885541144482;11465.140114491447;11547.364894930692;185012;185397;185643;185837;187282.1446069833;41881;42011.7899410501;42194.15611687749;42303.57696423058;42379.7881010593;1200.543793358518;1253.642711827125;1255.2468518888593;1256.3969294738577;1256.9497220111093;19906.303279385153;20520;21779.673304900123;24549.877250613634;24700.50598988046;40446.1910761789;41085.1782964333;41342.75971720853;41562.1687566253;41589.58410415857;167018;168460;168476;168584;169591;31115.688843112184;31398;31431;31560.36879262448;31858.840705796323;36680.81659591792;37431.43852842264;37714.245715084995;37768.62231377761;38041.61958380492;28700.712992869805;28733.28166795847;29120.854395727885;29307.267318317212;29422.264443389085;27670;27949;28017;29057.41885162327;29462.022626945945;31469;31486.37027259488;31497.52753708742;31742.841285794348;31801;27018.459630807665;27519.862400687867;28479.715202848536;28685.856570717013;28837.423251534434;21799.673004905257;22817;22843.428914277276;22860.428489287235;23034.539309213385 09:05:34 INFO - PID 3517 | |2;jquery.html;404.4176386004152;412.4329047559606;415.2504728964219;416.1095256151835;429.8409588452273;17330.61355091593;17407.216675249612;17825.7326140108;17949.19228634711;17969.55076123096;18520;18574.442766716988;18662.57386539748;18742.906285468587;18859.717104243446;5383.192521121834;5400.108982017962;5582.944170558303;5583.748731307101;5594.832155035356;5337.306150200468;5494.5054945054935;5498.395176530578;5503.284573005503;5509.256250406184;5413.891722165555;5457;5457.945420545803;5473.261109750192;5478.726063696819;5205.401378841425;5271.15661494161;5278.287431196778;5308.654937429054;5309.548688361475;4728.030753695484;4737.289406589005;4737.360456338385;4738.218193997986;4762.047590481883;21200.681989770164;21241.25655602062;21405.250816221356;21417.357479275648;21460.785392146034;1544.0967034284956;1554.098622798774;1555.1135852564043;1561.242797243336;1565.1313520995843;13495.527656532073;13718.862811371859;14530.854691453056;15004.849951500455;15007.474738384104;2060.7733149353558;2073.8029887160847;2114.1332053857845;2121.660534314518;2129.4144110369616;83931;84011.5799421005;84303;84825.57587212024;84996.15003850005;10551.419671918118;10865.619703310345;10907.400092994962;10950.233483656228;11108.16688748347;15238.161901095324;15241.923790380977;15247.923760381127;15252.237388130548;15252.923735381251;7534.962325188339;7839.25527074927;7857.489263197874;7896.170902055316;8017.318527925075;7286.271372862778;7369.22623124575;7388.889166662504;7518.135414427411;7576.4317676173405;6799.660016999131;6865.794026179223;6870.862582748317;6930.653467326714;6942.583444993215;1969.2123150739533;1970.5369238229005;1991.9203231870852;1994.5312851479891;2005.4084045206407;835.9832803343899;850.5152063323847;857.5926434943477;863.1023735315201;884.8982367027745;16498.505044848673;16604.319417427352;16888.324467021426;17245.482635520955;17480.21339039721;852.4927668037508;865.8874346334936;886.2201262888617;891.140049851896;891.2647066170306;8811.779705507413;9355.90644093564;9492.85760713576;9549.379290346105;9608.807823843485;368840;369812;372221;372788;375124;71914.9212761809;75366.24633753701;76640.61679691565;76674.61662691651;77002.2299776995;1049.41757324684;1057.5293994172673;1088.7387027113436;1089.2484185911696;1094.2231015978584;16860.66278674444;17199.656006880043;17298.22158002918;17800.732989004915;17860.91069544644;40554.39168412417;41361.1727765449;41554.58445415507;41613.79193104015;42816;51581;51843.222351664;52183.21725174201;54079.188812168635;54221.45778542322;8206.425550211428;8233.423660343718;8260.545669988209;8346.833063338821;8417.326613870753;12056.939715301367;12257.938710306391;12423.31671758062;12423.37883105823;12662.683432914251;10623.89376106229;10683.57265709363;10851.945740271249;10952.288101273392;11026.393548354597;9430.245580353418;9436.245100392089;9499.287553433382;9586.904130958601;9611.279154063333;10679.85314455702;10866.348019118877;10924.344539327665;10949.781004379709;11101.333919964827;8089.635966381645;8227.547484888388;8327.541985190872;8348.582570871553;8712.259457945893;10784.137269018303;10860.294080884718;10958.452077396258;11092.667219983414;11268.211225214156 09:05:34 INFO - PID 3517 | |3;mootools.html;1253.3858409379404;1291.1478424240004;1291.59314815833;1293.3274697157485;1298.6104168749375;9671.70984870454;9949.502524873746;10161.542730577114;10166.135878450332;10464.267501274913;10149.797004059914;10351.068403843652;10371.325863818865;10380;10417.374957502536;2070.730804995352;2106.2522804404443;2106.631339515586;2113.218109299554;2115.23851413491;2004.7393838801008;2049.333966460899;2050.179928028793;2052.097077285997;2052.6100040992237;2008.156574238816;2021.7978202179738;2039.388183544938;2039.7144399784042;2048.498117961102;1973.9013049347548;1977.0411350494999;1977.2881762565464;1986.5629561496448;1991.8904460254753;1675.0703359635397;1680.546252511827;1954.2183126749414;2034.0846619021352;2044.570640165567;10002;10084.899151008469;10092.747681307952;10141.290109692325;10146.898531014669;5387.1111266640955;5595.272614560122;5596.832095037155;5599.048161812501;5612.354579223401;3088.552159936819;3190.6968837960358;3246.6104067511815;3254.8372581371073;3275.050235431722;1050.2018465965843;1070.1866581398115;1075.8924107589257;1087.896649818266;1092.9726756831064;14231.573052808433;14317.069390489585;14449.060811047248;14462.421503139862;14478.348474318682;4510.639148868083;4581.702189357681;4731.195696731564;4759.928601070987;4772.49888761682;10776.29954052984;10812.94593527043;10821.67534973952;10859.78280434387;10881;5205.687658740488;5356.517913387814;5385.246065550826;5389.649672771257;5392.137258038695;4866.537678920497;4997.925031124536;5017.172166592544;5017.397912250553;5018.172001619765;3568.964310356915;3622.909427264287;3635.381985062546;3692.9815350923072;3748;1053.4364115198484;1054.9789004219872;1055.5250137438184;1081.3836113415277;1085.3054045410943;703.869784089945;733.1898252431093;737.181728281607;740.7444431671065;746.3618606091713;5547.25112109868;5573.69344686038;5595.4124816894455;5617.466340697627;5709.115087161496;526.7182057599166;551.6524589508584;551.7765305051461;555.419586532075;555.7471350535496;3348.2968576599187;3380.6139482812423;3478.495618135407;3481.7388695847912;3876.8836934891433;12524.248545087325;12717.491300348067;12926.547570834975;13054.804177937338;13096.803547946789;12740.681482962815;13005.284709341076;13006.154599950973;13019.088663793444;13025.892799111993;7187.856242875113;7209.747658831916;7220.819479513054;7254.637268136677;7337.669804858794;5511.559075273888;5728.971355143364;5790.9710451449155;5950.672713000827;5984.670843103497;11403.429828508375;11677.708057298636;11694.2983420995;11886.762264754829;11886.82169767437;11949.64151075469;12146.08904332161;12148.392580371123;12167.296578479198;12334.81497777552;7694.961180240596;7739.613019348897;7811.726589569338;7813.492123011986;7820.257075577811;9369;9394.906050939677;9417.717468475958;9457.952710236405;9502.38234514782;7910.485818421784;7913.960430198043;7981.281684648409;8065.314448271728;8270.131636178116;7796.064472263365;7858.882116768139;7862.331701805182;7931.087924888481;7954.204579542;6734.831629209309;6984.196817366068;7106.964465177641;7107.857842843217;7117;5226.817061402832;5242.842714718565;5274.709890955881;5347.224652425454;5465.781368745205;5234.63357564982;5372.2747429098135;5405.972970135124;5411.702356370437;5472.069748142746 09:05:34 INFO - PID 3517 | |4;prototype.html;360.2956220588749;379.4005471355257;383.40189304684696;384.4675124951944;386.02721142720344;5494.560435165187;5646.350669672984;5653.660780353177;5684.943150568493;5693;5412.377576578695;5481.75332110055;5501.229827824108;5517.420670829565;5521.834344969648;4213.47331583552;4214.13610209907;4219.1139860629155;4235.301175306071;4236.618704316611;4136.110736191718;4186.58134186582;4227;4237.4491316129015;4240.915181696364;4172.123853990651;4176.749395036308;4183.560726123744;4186.162767446508;4196.454460920076;4147.294959856831;4148.23257697327;4160.625543701051;4163.50037995441;4165.604267594574;3567.126054116758;3647.7993710346186;3666.17511060012;3678.2275722098125;3691.7046636269033;5441.129419292894;5444.50999410051;5464.9453505464835;5465.622454232422;5474.178873169017;4923.4091908970795;4936.531029552187;4946.777395017233;4958.479359667255;4977.178765503688;5739.82780516581;5813.82558523245;5837.387074357174;5909;5918.141869428909;4407.757573333465;4429.269170586849;4430.977845110754;4434.512203657595;4445.310976798602;6000.789972350947;6187.81436556904;6220.720067596969;6269.780557680505;6314.494840412802;4406.49325327585;4432.667549933768;4439.667024973075;4440.622547083534;4440.822367105344;4493.101379724003;4508.368828364031;4515;4518.615917647036;4534.093181363674;4981.551660350586;5080.491950804964;5083.186690129635;5084.161113416245;5086.21163719624;5935.851603709855;5936.22829032223;5942.316633587193;5960.552958528127;5964.7315870785915;1603.0702192728422;1608.9782987802596;1610.846969537892;1612.8387161283779;1615.7980252468442;1107.5791199344199;1172.9061675065975;1188.7265928836416;1191.4936152135308;1197.1201167142144;5075.137226671474;5078.542931136141;5086.72023038729;5114.974425127851;5119.46245644202;6005.3694362091355;6106;6112.633242005494;6157.815265542041;6165.075238714139;5004.124278251365;5024.773885175176;5042.974785126051;5057.494250574913;5058.140116180185;3230.82230477326;3256.8571328667476;3263.396271689745;3268.477043673001;3275.2958114005605;5481.616286859881;5628.634138780966;5639.971800140973;5651.858703532444;5667.263255776723;6264.498840092781;6293.1504246927;6295.905561416583;6298.496120310364;6319.557630965788;5457.426970168075;5651.208830763697;5652.293463317085;5669.659820410789;5673.858153546112;6002.549808764273;6071.787487437919;6078.088286757022;6115.480184184395;6167.995401494442;5821.534277257894;5984.102384642339;6006.09908513709;6030.48740857032;6036.607620504653;5592.692401917933;5630.971845140748;5635.464630860061;5647.5199608032135;5659.801906933238;4198.6221240088535;4220.324748040299;4244.575542445731;4248.405223268683;4262.190183865056;5988.37122102173;6078.878422431615;6127.14220009208;6141.048137538689;6160.784372546939;5946.554008449297;5950.702464876652;5952.880942381042;5956.255468066492;5965.135055417027;5821.476067153976;5934.287885453773;5956.821295361146;5963.850903727442;5968;5797.767859374087;5845.1232315153065;5854.151148083589;5857.502112320501;5874.441928016831;5931.822045338647;5947.910781338197;5971.611845230046;5983.700814959322;6012.8797424052145;5638.210650509014;5702.486776190163;5763.423657634203;5771.662476696944;5794.217780599565 09:05:34 INFO - PID 3517 | |5;yui.html;737.0860133434543;753.8266198774285;779.1546172402942;782.4483738964029;796.7211475983405;13980.790288145674;14707.632309192275;14714.411423543059;14956.7008659827;15413.92293038536;16836.158192090377;16977.91511042443;17057.658846823055;17073;17089.230984605692;893.714011516315;893.9150780675858;899.3659470073615;899.8380291547519;902.8013836955892;883.3639779358883;905.6830109461702;909.56340956341;913.8446464101086;919.1865199298635;902.7246689759621;949.7768024514234;953.0755167487526;953.7281874665722;956.7560272130597;907.3739120007207;939.7415710679584;946.5220063867703;947.6588428165854;950.3585080070959;671.1107782188619;685.5715178013742;694.350782018809;695.6069820551423;700.0514303119251;14898.553043408716;15051.698966020727;15088.471903483434;15155.848441515664;15226.847731522654;15592.454264100703;16065.759013614805;16132.838671613252;16251.593710157222;16274.26765795542;12591.181573197713;12990.090693651448;13154.144980576231;13190.868091319157;13235.867641323559;3071.094027261941;3075.8308293043874;3080.7843450958444;3082.5067989121635;3088.366884788613;88818;89380.5530972341;89878;91372.62941055889;92136.53931730299;10419.635312764018;10609.893901061045;10631.574737010587;10700.090492308085;10712;23213.651795223086;23323.53352932966;23381.53236935286;23386.415339616644;23391.883040585028;6126.08108783686;6199.070139479024;6212.409821066992;6262.436380725757;6271.874562508816;2873.5115030444877;2923.8099523530905;2929.5605659151297;2935.8532073396245;2952.158634789118;3615.29501747162;3675.889723308305;3679.5032670589662;3683.7605555638797;3685;661.6823924516162;661.778304268066;665.0423390317927;669.3975422120034;670.4301343857682;571.4342800627433;575.7610591604438;579.2932622200908;625.1435533319291;632.7563887903191;9596.080068380152;10396.48017599117;10662.626808061836;10742;11329.150313726339;598.2133494454865;599.6192417814719;609.1715267236555;612.650789050246;630.334996578614;4269.466316710411;4447.555244475527;4464.330350447394;4476.216662084187;4542.954570454253;77991.61004194943;78243;78533.60733196298;83494;83802.16197838065;73771;74892.62553687197;74998.25001750022;76323.61838190774;77316;59002.40997589969;59735;62179.378206217356;63182;65123.34876651173;9720.562574684014;10942.069924056226;11012.17408694335;11042.944785276022;11299.322040677585;27239.182824514504;27371.589426159022;27831.16506504808;28850.27874303075;28907.132786016453;51371.48628513816;52689.73655131853;53083.469165307855;53336.73331633317;53356.46643533515;13303.135296205715;13365.198088114745;13439.99200060019;13470.124441911245;13650.726985460036;16513.587160321087;16542.66914661724;16695;16792.664146716754;16971.745423818407;14290.857091429369;14675.48635797742;14727.337269822669;14907;15018.474353398016;14820.925895370454;14907.478238261609;15016.099034057554;15124.848751512343;15405.537833865003;14799.704005919606;14878.181700006604;14918.179500127597;15032.24838758036;15301.846981530041;10395.220358472994;10426.635067772895;10691.946540267249;10754.300970436896;11232.83150752723;11590.94204528972;11638.6508404748;11901.642950711492;12031.819522706992;12056.75886482248 09:05:34 INFO - PID 3517 | __end_tp_report 09:05:34 INFO - PID 3517 | __start_cc_report 09:05:34 INFO - PID 3517 | _x_x_mozilla_cycle_collect,337 09:05:34 INFO - PID 3517 | __end_cc_report 09:05:34 INFO - PID 3517 | __startTimestamp1495814734817__endTimestamp 09:05:34 INFO - PID 3517 | 09:05:34 INFO - PID 3517 | ------- Summary: start ------- 09:05:34 INFO - PID 3517 | Number of tests: 6 09:05:34 INFO - PID 3517 | 09:05:34 INFO - PID 3517 | [#0] dojo.html Cycles:165 Average:5236.73 Median:5387.08 stddev:621.35 (11.5%) stddev-sans-first:590.29 09:05:34 INFO - PID 3517 | Values: 2690.8 2729.4 2745.6 2760.5 2770.8 5215.2 5299.5 5307.2 5312.4 5364.6 5247.2 5267.9 5290.6 5341.1 5392.0 4477.4 4687.0 4720.8 4769.5 4825.8 4787.9 4799.3 4814.8 4832.1 4863.3 4665.1 4686.6 4738.4 4745.5 4755.1 4728.8 4739.5 4741.3 4784.1 4791.6 4677.6 4687.3 4697.9 4711.1 4752.5 5196.1 5288.6 5293.2 5330.6 5346.6 5650.8 5725.6 5733.9 5750.3 5772.0 5761.9 5766.5 5772.6 5779.6 5793.3 5375.3 5378.2 5383.2 5384.7 5387.1 5795.4 5832.5 5865.8 5902.6 5908.8 5609.4 5616.2 5643.2 5645.3 5648.4 5629.0 5689.6 5694.2 5698.5 5716.9 5594.3 5677.0 5694.3 5700.0 5701.2 5619.6 5687.9 5689.4 5695.8 5708.1 4766.7 4775.6 4782.2 4783.9 4824.8 4098.7 4101.1 4107.3 4117.7 4141.6 4752.1 4783.1 4846.1 4863.7 4865.6 5753.9 5755.8 5756.9 5766.4 5818.5 4821.3 4834.8 4838.9 4845.8 4881.5 4955.9 4982.7 5059.5 5076.4 5097.3 5340.4 5365.4 5366.0 5375.2 5387.9 5690.8 5745.3 5749.5 5771.5 5779.7 5140.4 5323.7 5333.0 5333.3 5373.4 5662.4 5683.6 5702.2 5709.7 5720.9 5671.4 5775.8 5777.3 5777.5 5779.1 5548.6 5566.4 5589.3 5592.4 5632.1 5567.5 5583.9 5586.2 5593.5 5640.5 5500.9 5501.9 5519.7 5519.9 5526.2 5551.1 5567.8 5635.7 5644.4 5697.0 5487.5 5499.9 5507.4 5518.6 5541.9 09:05:34 INFO - PID 3517 | 09:05:34 INFO - PID 3517 | [#1] ext.html Cycles:180 Average:32039.97 Median:31450.00 stddev:43823.84 (139.3%) stddev-sans-first:43883.43 09:05:34 INFO - PID 3517 | Values: 659.4 712.8 731.5 1175.9 1219.6 35281.0 35954.5 36071.8 36189.1 36292.8 34174.1 34376.5 34428.0 34473.5 34546.7 6453.0 6682.4 6814.7 6815.9 6819.7 6771.8 6872.6 6875.1 6885.5 6886.8 6855.1 6910.4 6918.6 6919.3 6929.9 6861.3 6892.4 6894.0 6897.8 6902.7 6763.6 6769.3 6774.4 6817.9 6832.9 32901.3 33085.0 33101.5 33211.3 33232.8 3060.6 3100.5 3101.0 3102.0 3106.1 24975.5 25283.2 25319.9 25328.6 25331.5 3029.3 3045.5 3072.5 3095.6 3115.4 151638.0 151892.0 152438.0 153855.0 156295.0 39680.8 39906.0 39950.2 40039.4 40048.2 41762.6 41984.9 41991.8 44328.8 44767.3 13191.9 13271.0 13306.4 13389.4 13406.9 10624.9 10637.9 10671.3 10694.1 10698.7 12889.5 12915.4 12923.4 12932.2 13198.9 5460.3 5483.8 5491.8 5494.1 5511.5 841.5 892.2 893.4 905.1 938.1 21000.8 21431.8 21838.0 21851.8 22081.4 973.1 991.5 992.2 999.7 1017.5 11067.7 11437.1 11445.9 11465.1 11547.4 185012.0 185397.0 185643.0 185837.0 187282.1 41881.0 42011.8 42194.2 42303.6 42379.8 1200.5 1253.6 1255.2 1256.4 1256.9 19906.3 20520.0 21779.7 24549.9 24700.5 40446.2 41085.2 41342.8 41562.2 41589.6 167018.0 168460.0 168476.0 168584.0 169591.0 31115.7 31398.0 31431.0 31560.4 31858.8 36680.8 37431.4 37714.2 37768.6 38041.6 28700.7 28733.3 29120.9 29307.3 29422.3 27670.0 27949.0 28017.0 29057.4 29462.0 31469.0 31486.4 31497.5 31742.8 31801.0 27018.5 27519.9 28479.7 28685.9 28837.4 21799.7 22817.0 22843.4 22860.4 23034.5 09:05:34 INFO - PID 3517 | 09:05:34 INFO - PID 3517 | [#2] jquery.html Cycles:180 Average:24906.79 Median:20985.35 stddev:61778.65 (294.4%) stddev-sans-first:61924.56 09:05:34 INFO - PID 3517 | Values: 404.4 412.4 415.3 416.1 429.8 17330.6 17407.2 17825.7 17949.2 17969.6 18520.0 18574.4 18662.6 18742.9 18859.7 5383.2 5400.1 5582.9 5583.7 5594.8 5337.3 5494.5 5498.4 5503.3 5509.3 5413.9 5457.0 5457.9 5473.3 5478.7 5205.4 5271.2 5278.3 5308.7 5309.5 4728.0 4737.3 4737.4 4738.2 4762.0 21200.7 21241.3 21405.3 21417.4 21460.8 1544.1 1554.1 1555.1 1561.2 1565.1 13495.5 13718.9 14530.9 15004.8 15007.5 2060.8 2073.8 2114.1 2121.7 2129.4 83931.0 84011.6 84303.0 84825.6 84996.2 10551.4 10865.6 10907.4 10950.2 11108.2 15238.2 15241.9 15247.9 15252.2 15252.9 7535.0 7839.3 7857.5 7896.2 8017.3 7286.3 7369.2 7388.9 7518.1 7576.4 6799.7 6865.8 6870.9 6930.7 6942.6 1969.2 1970.5 1991.9 1994.5 2005.4 836.0 850.5 857.6 863.1 884.9 16498.5 16604.3 16888.3 17245.5 17480.2 852.5 865.9 886.2 891.1 891.3 8811.8 9355.9 9492.9 9549.4 9608.8 368840.0 369812.0 372221.0 372788.0 375124.0 71914.9 75366.2 76640.6 76674.6 77002.2 1049.4 1057.5 1088.7 1089.2 1094.2 16860.7 17199.7 17298.2 17800.7 17860.9 40554.4 41361.2 41554.6 41613.8 42816.0 51581.0 51843.2 52183.2 54079.2 54221.5 8206.4 8233.4 8260.5 8346.8 8417.3 12056.9 12257.9 12423.3 12423.4 12662.7 10623.9 10683.6 10851.9 10952.3 11026.4 9430.2 9436.2 9499.3 9586.9 9611.3 10679.9 10866.3 10924.3 10949.8 11101.3 8089.6 8227.5 8327.5 8348.6 8712.3 10784.1 10860.3 10958.5 11092.7 11268.2 09:05:34 INFO - PID 3517 | 09:05:34 INFO - PID 3517 | [#3] mootools.html Cycles:180 Average:6126.33 Median:3480.12 stddev:3985.95 (114.5%) stddev-sans-first:3980.31 09:05:34 INFO - PID 3517 | Values: 1253.4 1291.1 1291.6 1293.3 1298.6 9671.7 9949.5 10161.5 10166.1 10464.3 10149.8 10351.1 10371.3 10380.0 10417.4 2070.7 2106.3 2106.6 2113.2 2115.2 2004.7 2049.3 2050.2 2052.1 2052.6 2008.2 2021.8 2039.4 2039.7 2048.5 1973.9 1977.0 1977.3 1986.6 1991.9 1675.1 1680.5 1954.2 2034.1 2044.6 10002.0 10084.9 10092.7 10141.3 10146.9 5387.1 5595.3 5596.8 5599.0 5612.4 3088.6 3190.7 3246.6 3254.8 3275.1 1050.2 1070.2 1075.9 1087.9 1093.0 14231.6 14317.1 14449.1 14462.4 14478.3 4510.6 4581.7 4731.2 4759.9 4772.5 10776.3 10812.9 10821.7 10859.8 10881.0 5205.7 5356.5 5385.2 5389.6 5392.1 4866.5 4997.9 5017.2 5017.4 5018.2 3569.0 3622.9 3635.4 3693.0 3748.0 1053.4 1055.0 1055.5 1081.4 1085.3 703.9 733.2 737.2 740.7 746.4 5547.3 5573.7 5595.4 5617.5 5709.1 526.7 551.7 551.8 555.4 555.7 3348.3 3380.6 3478.5 3481.7 3876.9 12524.2 12717.5 12926.5 13054.8 13096.8 12740.7 13005.3 13006.2 13019.1 13025.9 7187.9 7209.7 7220.8 7254.6 7337.7 5511.6 5729.0 5791.0 5950.7 5984.7 11403.4 11677.7 11694.3 11886.8 11886.8 11949.6 12146.1 12148.4 12167.3 12334.8 7695.0 7739.6 7811.7 7813.5 7820.3 9369.0 9394.9 9417.7 9458.0 9502.4 7910.5 7914.0 7981.3 8065.3 8270.1 7796.1 7858.9 7862.3 7931.1 7954.2 6734.8 6984.2 7107.0 7107.9 7117.0 5226.8 5242.8 5274.7 5347.2 5465.8 5234.6 5372.3 5406.0 5411.7 5472.1 09:05:34 INFO - PID 3517 | 09:05:34 INFO - PID 3517 | [#4] prototype.html Cycles:180 Average:4889.72 Median:5469.90 stddev:1411.86 (25.8%) stddev-sans-first:1374.28 09:05:34 INFO - PID 3517 | Values: 360.3 379.4 383.4 384.5 386.0 5494.6 5646.4 5653.7 5684.9 5693.0 5412.4 5481.8 5501.2 5517.4 5521.8 4213.5 4214.1 4219.1 4235.3 4236.6 4136.1 4186.6 4227.0 4237.4 4240.9 4172.1 4176.7 4183.6 4186.2 4196.5 4147.3 4148.2 4160.6 4163.5 4165.6 3567.1 3647.8 3666.2 3678.2 3691.7 5441.1 5444.5 5464.9 5465.6 5474.2 4923.4 4936.5 4946.8 4958.5 4977.2 5739.8 5813.8 5837.4 5909.0 5918.1 4407.8 4429.3 4431.0 4434.5 4445.3 6000.8 6187.8 6220.7 6269.8 6314.5 4406.5 4432.7 4439.7 4440.6 4440.8 4493.1 4508.4 4515.0 4518.6 4534.1 4981.6 5080.5 5083.2 5084.2 5086.2 5935.9 5936.2 5942.3 5960.6 5964.7 1603.1 1609.0 1610.8 1612.8 1615.8 1107.6 1172.9 1188.7 1191.5 1197.1 5075.1 5078.5 5086.7 5115.0 5119.5 6005.4 6106.0 6112.6 6157.8 6165.1 5004.1 5024.8 5043.0 5057.5 5058.1 3230.8 3256.9 3263.4 3268.5 3275.3 5481.6 5628.6 5640.0 5651.9 5667.3 6264.5 6293.2 6295.9 6298.5 6319.6 5457.4 5651.2 5652.3 5669.7 5673.9 6002.5 6071.8 6078.1 6115.5 6168.0 5821.5 5984.1 6006.1 6030.5 6036.6 5592.7 5631.0 5635.5 5647.5 5659.8 4198.6 4220.3 4244.6 4248.4 4262.2 5988.4 6078.9 6127.1 6141.0 6160.8 5946.6 5950.7 5952.9 5956.3 5965.1 5821.5 5934.3 5956.8 5963.9 5968.0 5797.8 5845.1 5854.2 5857.5 5874.4 5931.8 5947.9 5971.6 5983.7 6012.9 5638.2 5702.5 5763.4 5771.7 5794.2 09:05:34 INFO - PID 3517 | 09:05:34 INFO - PID 3517 | [#5] yui.html Cycles:180 Average:17913.53 Median:3081.65 stddev:23460.44 (761.3%) stddev-sans-first:23490.79 09:05:34 INFO - PID 3517 | Values: 737.1 753.8 779.2 782.4 796.7 13980.8 14707.6 14714.4 14956.7 15413.9 16836.2 16977.9 17057.7 17073.0 17089.2 893.7 893.9 899.4 899.8 902.8 883.4 905.7 909.6 913.8 919.2 902.7 949.8 953.1 953.7 956.8 907.4 939.7 946.5 947.7 950.4 671.1 685.6 694.4 695.6 700.1 14898.6 15051.7 15088.5 15155.8 15226.8 15592.5 16065.8 16132.8 16251.6 16274.3 12591.2 12990.1 13154.1 13190.9 13235.9 3071.1 3075.8 3080.8 3082.5 3088.4 88818.0 89380.6 89878.0 91372.6 92136.5 10419.6 10609.9 10631.6 10700.1 10712.0 23213.7 23323.5 23381.5 23386.4 23391.9 6126.1 6199.1 6212.4 6262.4 6271.9 2873.5 2923.8 2929.6 2935.9 2952.2 3615.3 3675.9 3679.5 3683.8 3685.0 661.7 661.8 665.0 669.4 670.4 571.4 575.8 579.3 625.1 632.8 9596.1 10396.5 10662.6 10742.0 11329.2 598.2 599.6 609.2 612.7 630.3 4269.5 4447.6 4464.3 4476.2 4543.0 77991.6 78243.0 78533.6 83494.0 83802.2 73771.0 74892.6 74998.3 76323.6 77316.0 59002.4 59735.0 62179.4 63182.0 65123.3 9720.6 10942.1 11012.2 11042.9 11299.3 27239.2 27371.6 27831.2 28850.3 28907.1 51371.5 52689.7 53083.5 53336.7 53356.5 13303.1 13365.2 13440.0 13470.1 13650.7 16513.6 16542.7 16695.0 16792.7 16971.7 14290.9 14675.5 14727.3 14907.0 15018.5 14820.9 14907.5 15016.1 15124.8 15405.5 14799.7 14878.2 14918.2 15032.2 15301.8 10395.2 10426.6 10691.9 10754.3 11232.8 11590.9 11638.7 11901.6 12031.8 12056.8 09:05:34 INFO - PID 3517 | -------- Summary: end -------- 09:05:34 INFO - PID 3517 | 09:05:35 INFO - TEST-INFO | 3517: exit 0 09:05:35 INFO - TEST-OK | dromaeo_css | took 1148804ms 09:05:35 INFO - TEST-START | kraken 09:05:35 INFO - operating with platform_type : linux_ 09:05:35 INFO - Initialising browser for kraken test... 09:05:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37590/getInfo.html -profile /tmp/tmp6USayr/profile 09:05:35 INFO - TEST-INFO | started process 3919 (/builds/slave/test/build/application/firefox/firefox http://localhost:37590/getInfo.html) 09:06:10 INFO - TEST-INFO | 3919: killed by SIGTERM 09:06:10 INFO - Browser initialized. 09:06:10 INFO - Running cycle 1/1 for kraken test... 09:06:10 INFO - TEST-INFO | started process 4116 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6USayr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:06:22 INFO - PID 4116 | RSS: Main: 168910848 09:06:22 INFO - PID 4116 | 09:06:25 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37590/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37590/tests/kraken/driver.html?testName=audio-beat-detection) 09:06:26 INFO - PID 4116 | RSS: Main: 174546944 09:06:26 INFO - PID 4116 | 09:06:29 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37590/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37590/tests/kraken/driver.html?testName=audio-dft) 09:06:29 INFO - PID 4116 | RSS: Main: 173600768 09:06:29 INFO - PID 4116 | 09:06:32 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37590/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37590/tests/kraken/driver.html?testName=audio-fft) 09:06:33 INFO - PID 4116 | RSS: Main: 171216896 09:06:33 INFO - PID 4116 | 09:06:35 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37590/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37590/tests/kraken/driver.html?testName=audio-oscillator) 09:06:36 INFO - PID 4116 | RSS: Main: 171782144 09:06:36 INFO - PID 4116 | 09:06:38 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37590/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37590/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:06:38 INFO - PID 4116 | RSS: Main: 172220416 09:06:38 INFO - PID 4116 | 09:06:41 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37590/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37590/tests/kraken/driver.html?testName=imaging-darkroom) 09:06:42 INFO - PID 4116 | RSS: Main: 173121536 09:06:42 INFO - PID 4116 | 09:06:46 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37590/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37590/tests/kraken/driver.html?testName=imaging-desaturate) 09:06:46 INFO - PID 4116 | RSS: Main: 172183552 09:06:46 INFO - PID 4116 | 09:06:49 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37590/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37590/tests/kraken/driver.html?testName=json-parse-financial) 09:06:50 INFO - PID 4116 | RSS: Main: 172670976 09:06:50 INFO - PID 4116 | 09:06:51 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37590/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37590/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:06:52 INFO - PID 4116 | RSS: Main: 172851200 09:06:52 INFO - PID 4116 | 09:06:54 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37590/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37590/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:06:54 INFO - PID 4116 | RSS: Main: 172711936 09:06:54 INFO - PID 4116 | 09:06:56 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37590/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37590/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:06:57 INFO - PID 4116 | RSS: Main: 172572672 09:06:57 INFO - PID 4116 | 09:07:00 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37590/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37590/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:07:01 INFO - PID 4116 | RSS: Main: 172949504 09:07:01 INFO - PID 4116 | 09:07:04 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37590/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37590/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:07:04 INFO - PID 4116 | RSS: Main: 173207552 09:07:04 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | Cycle 1(1): loaded http://localhost:37590/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37590/tests/kraken/driver.html?testName=ai-astar) 09:07:06 INFO - PID 4116 | RSS: Main: 174690304 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | __start_tp_report 09:07:06 INFO - PID 4116 | _x_x_mozilla_page_load 09:07:06 INFO - PID 4116 | _x_x_mozilla_page_load_details 09:07:06 INFO - PID 4116 | |i|pagename|runs| 09:07:06 INFO - PID 4116 | |0;ai-astar;102;110;111;112;111;113;112;113;111;113 09:07:06 INFO - PID 4116 | |1;audio-beat-detection;150;132;121;179;122;125;123;124;145;129 09:07:06 INFO - PID 4116 | |2;audio-dft;163;162;165;167;168;170;166;165;165;162 09:07:06 INFO - PID 4116 | |3;audio-fft;73;79;78;104;75;74;74;77;93;73 09:07:06 INFO - PID 4116 | |4;audio-oscillator;84;83;114;83;83;85;98;84;83;106 09:07:06 INFO - PID 4116 | |5;imaging-gaussian-blur;114;115;113;116;114;114;113;114;115;114 09:07:06 INFO - PID 4116 | |6;imaging-darkroom;150;150;151;151;150;150;150;151;151;151 09:07:06 INFO - PID 4116 | |7;imaging-desaturate;95;95;94;94;94;94;95;94;94;94 09:07:06 INFO - PID 4116 | |8;json-parse-financial;55;55;56;56;57;56;67;59;55;57 09:07:06 INFO - PID 4116 | |9;json-stringify-tinderbox;57;58;58;58;57;58;57;57;57;57 09:07:06 INFO - PID 4116 | |10;stanford-crypto-aes;87;87;88;89;85;95;87;88;123;86 09:07:06 INFO - PID 4116 | |11;stanford-crypto-ccm;144;136;135;135;133;134;133;140;134;132 09:07:06 INFO - PID 4116 | |12;stanford-crypto-pbkdf2;190;200;195;191;196;192;201;204;194;195 09:07:06 INFO - PID 4116 | |13;stanford-crypto-sha256-iterative;75;73;76;72;74;77;75;75;74;73 09:07:06 INFO - PID 4116 | __end_tp_report 09:07:06 INFO - PID 4116 | __start_cc_report 09:07:06 INFO - PID 4116 | _x_x_mozilla_cycle_collect,908 09:07:06 INFO - PID 4116 | __end_cc_report 09:07:06 INFO - PID 4116 | __startTimestamp1495814826971__endTimestamp 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | ------- Summary: start ------- 09:07:06 INFO - PID 4116 | Number of tests: 14 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.00 stddev:3.26 (2.9%) stddev-sans-first:1.09 09:07:06 INFO - PID 4116 | Values: 102.0 110.0 111.0 112.0 111.0 113.0 112.0 113.0 111.0 113.0 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | [#1] audio-beat-detection Cycles:10 Average:135.00 Median:130.50 stddev:18.37 (14.1%) stddev-sans-first:18.66 09:07:06 INFO - PID 4116 | Values: 150.0 132.0 121.0 179.0 122.0 125.0 123.0 124.0 145.0 129.0 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | [#2] audio-dft Cycles:10 Average:165.30 Median:165.50 stddev:2.58 (1.6%) stddev-sans-first:2.60 09:07:06 INFO - PID 4116 | Values: 163.0 162.0 165.0 167.0 168.0 170.0 166.0 165.0 165.0 162.0 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | [#3] audio-fft Cycles:10 Average:80.00 Median:76.00 stddev:10.30 (13.5%) stddev-sans-first:10.60 09:07:06 INFO - PID 4116 | Values: 73.0 79.0 78.0 104.0 75.0 74.0 74.0 77.0 93.0 73.0 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:83.50 stddev:11.49 (13.8%) stddev-sans-first:11.96 09:07:06 INFO - PID 4116 | Values: 84.0 83.0 114.0 83.0 83.0 85.0 98.0 84.0 83.0 106.0 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 09:07:06 INFO - PID 4116 | Values: 114.0 115.0 113.0 116.0 114.0 114.0 113.0 114.0 115.0 114.0 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | [#6] imaging-darkroom Cycles:10 Average:150.50 Median:151.00 stddev:0.53 (0.3%) stddev-sans-first:0.53 09:07:06 INFO - PID 4116 | Values: 150.0 150.0 151.0 151.0 150.0 150.0 150.0 151.0 151.0 151.0 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 09:07:06 INFO - PID 4116 | Values: 95.0 95.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:56.50 stddev:3.62 (6.4%) stddev-sans-first:3.75 09:07:06 INFO - PID 4116 | Values: 55.0 55.0 56.0 56.0 57.0 56.0 67.0 59.0 55.0 57.0 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | [#9] json-stringify-tinderbox Cycles:10 Average:57.40 Median:57.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 09:07:06 INFO - PID 4116 | Values: 57.0 58.0 58.0 58.0 57.0 58.0 57.0 57.0 57.0 57.0 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:87.50 stddev:11.39 (13.0%) stddev-sans-first:11.97 09:07:06 INFO - PID 4116 | Values: 87.0 87.0 88.0 89.0 85.0 95.0 87.0 88.0 123.0 86.0 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | [#11] stanford-crypto-ccm Cycles:10 Average:135.60 Median:135.00 stddev:3.69 (2.7%) stddev-sans-first:2.35 09:07:06 INFO - PID 4116 | Values: 144.0 136.0 135.0 135.0 133.0 134.0 133.0 140.0 134.0 132.0 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.80 Median:195.50 stddev:4.57 (2.3%) stddev-sans-first:4.33 09:07:06 INFO - PID 4116 | Values: 190.0 200.0 195.0 191.0 196.0 192.0 201.0 204.0 194.0 195.0 09:07:06 INFO - PID 4116 | 09:07:06 INFO - PID 4116 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.40 Median:75.00 stddev:1.51 (2.0%) stddev-sans-first:1.58 09:07:06 INFO - PID 4116 | Values: 75.0 73.0 76.0 72.0 74.0 77.0 75.0 75.0 74.0 73.0 09:07:06 INFO - PID 4116 | -------- Summary: end -------- 09:07:06 INFO - PID 4116 | 09:07:08 INFO - TEST-INFO | 4116: exit 0 09:07:08 INFO - TEST-OK | kraken | took 92493ms 09:07:08 INFO - SUITE-END | took 1241s 09:07:08 INFO - Completed test suite (00:20:41) 09:07:08 INFO - javascript benchmark 09:07:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6819.547178971829, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2690.7578317951384, 2729.4268203677225, 2745.5607102863532, 2760.544510155823, 2770.8060435769494, 5215.243789650498, 5299.5230429261355, 5307.203919412085, 5312.442193569673, 5364.597655175863, 5247.1866860636665, 5267.894642107156, 5290.629655924077, 5341.145416733323, 5392.0294347017525, 4477.440319960005, 4686.995524604749, 4720.787564559603, 4769.475357710649, 4825.831095911643, 4787.904241915159, 4799.2801079837955, 4814.759262036901, 4832.130216561018, 4863.319135321058, 4665.066986602693, 4686.554777296152, 4738.431388233434, 4745.477997420281, 4755.072760811643, 4728.834490792806, 4739.526047395268, 4741.336212930192, 4784.091022705675, 4791.6166706663735, 4677.579017888373, 4687.343771871941, 4697.88255293617, 4711.104890070876, 4752.5247475252545, 5196.142636464979, 5288.576913846883, 5293.153095504701, 5330.600204984642, 5346.572274218053, 5650.802221922254, 5725.599208055438, 5733.913991290134, 5750.281214848144, 5771.989901767174, 5761.942380576183, 5766.481016708516, 5772.624779389326, 5779.566532510079, 5793.275840519935, 5375.301210842605, 5378.19327100938, 5383.246345511631, 5384.6769193848495, 5387.0841956867425, 5795.391483894215, 5832.50423713989, 5865.794697185578, 5902.645841249453, 5908.793192238251, 5609.354924183771, 5616.185653080281, 5643.1535269709875, 5645.294338207724, 5648.406917273709, 5628.97185514078, 5689.573282003867, 5694.174344720075, 5698.515626171822, 5716.857078573069, 5594.328680558359, 5677.0, 5694.288213973254, 5699.971500142556, 5701.2018317435595, 5619.606627536115, 5687.857803554862, 5689.402612725687, 5695.772169113272, 5708.086706127, 4766.6901651392545, 4775.641826863, 4782.234842425265, 4783.880402989884, 4824.782884770194, 4098.7130900836655, 4101.077257617072, 4107.322291821875, 4117.711760176819, 4141.627253547195, 4752.120857641278, 4783.067301876101, 4846.127697014571, 4863.708177509361, 4865.5864251538305, 5753.942460575425, 5755.798547050833, 5756.856078598069, 5766.394528574523, 5818.4763371296785, 4821.349117869043, 4834.830780922651, 4838.927416088762, 4845.830395936126, 4881.51184881509, 4955.925661115014, 4982.676126051795, 5059.51934566223, 5076.44159142494, 5097.311862898536, 5340.412554618911, 5365.409804921377, 5366.034113859387, 5375.220593014069, 5387.86530336729, 5690.8008219713765, 5745.3105627324985, 5749.511291540099, 5771.538276937751, 5779.682117483579, 5140.357455318085, 5323.733813309396, 5333.0, 5333.253344531846, 5373.382061063028, 5662.405447427961, 5683.60214784961, 5702.230198923258, 5709.68596727184, 5720.942790572041, 5671.432856714296, 5775.826725198251, 5777.277840269966, 5777.508911742549, 5779.104238842986, 5548.639338442988, 5566.387697353369, 5589.329280486368, 5592.356878958972, 5632.098864181711, 5567.526760225264, 5583.944160558342, 5586.190002449704, 5593.524550413261, 5640.492355688008, 5500.917486237629, 5501.889962200814, 5519.724013799374, 5519.8896022080135, 5526.171074338881, 5551.111822108443, 5567.832965011056, 5635.6618602883955, 5644.379118297066, 5696.971515142398, 5487.451254874641, 5499.9175012375645, 5507.449255074621, 5518.613697041169, 5541.9168712470155], "value": 5190.931223232035, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.4262991197659, 712.8075419636698, 731.4989232375822, 1175.8824117588238, 1219.5731493977103, 35281.0, 35954.46068308974, 36071.81964090179, 36189.0952726182, 36292.81853590735, 34174.14564635879, 34376.48435273467, 34428.0, 34473.482897756556, 34546.65453345466, 6453.032045193223, 6682.365175308338, 6814.693338799766, 6815.863682726343, 6819.659017049153, 6771.762988295411, 6872.553284036521, 6875.140607424072, 6885.48358873084, 6886.827829304282, 6855.143107111611, 6910.378065974061, 6918.619475928847, 6919.273476284993, 6929.896051559205, 6861.27956564561, 6892.448604111659, 6894.034835123087, 6897.793066208022, 6902.723891044352, 6763.560368576076, 6769.323067693241, 6774.356436138559, 6817.863642727118, 6832.897506537406, 32901.3419731604, 33085.0, 33101.503477447855, 33211.33577328464, 33232.833835830905, 3060.6327240731034, 3100.4884194107944, 3101.0231776990177, 3102.0073576455543, 3106.130283520595, 24975.5004899901, 25283.241502754947, 25319.873400632878, 25328.620070698955, 25331.493370132677, 3029.2578318312153, 3045.5279431688123, 3072.4776787946103, 3095.5511450839726, 3115.43922094023, 151638.0, 151892.0, 152438.0, 153855.0, 156295.0, 39680.801595992416, 39906.0, 39950.20099597992, 40039.39940900889, 40048.19903601912, 41762.582374176476, 41984.851136164296, 41991.7900410496, 44328.77835610866, 44767.32849007267, 13191.934040329737, 13271.0, 13306.401211945486, 13389.397477113553, 13406.932965335305, 10624.893751062546, 10637.946810266054, 10671.253012289066, 10694.091002264739, 10698.679039628823, 12889.484420623257, 12915.354232288348, 12923.418446169944, 12932.224066556037, 13198.868011319955, 5460.262864513319, 5483.793565415602, 5491.780328786884, 5494.066008778517, 5511.5039646432015, 841.5497708725802, 892.2103938014903, 893.4326702543931, 905.0768216419216, 938.1369140390857, 21000.789992099883, 21431.785682142978, 21838.0, 21851.78148218529, 22081.44796380071, 973.0755782007121, 991.4745185051934, 992.2012779712401, 999.6601155607128, 1017.5065093429681, 11067.72330691739, 11437.085033197323, 11445.885541144482, 11465.140114491447, 11547.364894930692, 185012.0, 185397.0, 185643.0, 185837.0, 187282.1446069833, 41881.0, 42011.7899410501, 42194.15611687749, 42303.57696423058, 42379.7881010593, 1200.543793358518, 1253.642711827125, 1255.2468518888593, 1256.3969294738577, 1256.9497220111093, 19906.303279385153, 20520.0, 21779.673304900123, 24549.877250613634, 24700.50598988046, 40446.1910761789, 41085.1782964333, 41342.75971720853, 41562.1687566253, 41589.58410415857, 167018.0, 168460.0, 168476.0, 168584.0, 169591.0, 31115.688843112184, 31398.0, 31431.0, 31560.36879262448, 31858.840705796323, 36680.81659591792, 37431.43852842264, 37714.245715084995, 37768.62231377761, 38041.61958380492, 28700.712992869805, 28733.28166795847, 29120.854395727885, 29307.267318317212, 29422.264443389085, 27670.0, 27949.0, 28017.0, 29057.41885162327, 29462.022626945945, 31469.0, 31486.37027259488, 31497.52753708742, 31742.841285794348, 31801.0, 27018.459630807665, 27519.862400687867, 28479.715202848536, 28685.856570717013, 28837.423251534434, 21799.673004905257, 22817.0, 22843.428914277276, 22860.428489287235, 23034.539309213385], "value": 15018.310599905797, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.4176386004152, 412.4329047559606, 415.2504728964219, 416.1095256151835, 429.8409588452273, 17330.61355091593, 17407.216675249612, 17825.7326140108, 17949.19228634711, 17969.55076123096, 18520.0, 18574.442766716988, 18662.57386539748, 18742.906285468587, 18859.717104243446, 5383.192521121834, 5400.108982017962, 5582.944170558303, 5583.748731307101, 5594.832155035356, 5337.306150200468, 5494.5054945054935, 5498.395176530578, 5503.284573005503, 5509.256250406184, 5413.891722165555, 5457.0, 5457.945420545803, 5473.261109750192, 5478.726063696819, 5205.401378841425, 5271.15661494161, 5278.287431196778, 5308.654937429054, 5309.548688361475, 4728.030753695484, 4737.289406589005, 4737.360456338385, 4738.218193997986, 4762.047590481883, 21200.681989770164, 21241.25655602062, 21405.250816221356, 21417.357479275648, 21460.785392146034, 1544.0967034284956, 1554.098622798774, 1555.1135852564043, 1561.242797243336, 1565.1313520995843, 13495.527656532073, 13718.862811371859, 14530.854691453056, 15004.849951500455, 15007.474738384104, 2060.7733149353558, 2073.8029887160847, 2114.1332053857845, 2121.660534314518, 2129.4144110369616, 83931.0, 84011.5799421005, 84303.0, 84825.57587212024, 84996.15003850005, 10551.419671918118, 10865.619703310345, 10907.400092994962, 10950.233483656228, 11108.16688748347, 15238.161901095324, 15241.923790380977, 15247.923760381127, 15252.237388130548, 15252.923735381251, 7534.962325188339, 7839.25527074927, 7857.489263197874, 7896.170902055316, 8017.318527925075, 7286.271372862778, 7369.22623124575, 7388.889166662504, 7518.135414427411, 7576.4317676173405, 6799.660016999131, 6865.794026179223, 6870.862582748317, 6930.653467326714, 6942.583444993215, 1969.2123150739533, 1970.5369238229005, 1991.9203231870852, 1994.5312851479891, 2005.4084045206407, 835.9832803343899, 850.5152063323847, 857.5926434943477, 863.1023735315201, 884.8982367027745, 16498.505044848673, 16604.319417427352, 16888.324467021426, 17245.482635520955, 17480.21339039721, 852.4927668037508, 865.8874346334936, 886.2201262888617, 891.140049851896, 891.2647066170306, 8811.779705507413, 9355.90644093564, 9492.85760713576, 9549.379290346105, 9608.807823843485, 368840.0, 369812.0, 372221.0, 372788.0, 375124.0, 71914.9212761809, 75366.24633753701, 76640.61679691565, 76674.61662691651, 77002.2299776995, 1049.41757324684, 1057.5293994172673, 1088.7387027113436, 1089.2484185911696, 1094.2231015978584, 16860.66278674444, 17199.656006880043, 17298.22158002918, 17800.732989004915, 17860.91069544644, 40554.39168412417, 41361.1727765449, 41554.58445415507, 41613.79193104015, 42816.0, 51581.0, 51843.222351664, 52183.21725174201, 54079.188812168635, 54221.45778542322, 8206.425550211428, 8233.423660343718, 8260.545669988209, 8346.833063338821, 8417.326613870753, 12056.939715301367, 12257.938710306391, 12423.31671758062, 12423.37883105823, 12662.683432914251, 10623.89376106229, 10683.57265709363, 10851.945740271249, 10952.288101273392, 11026.393548354597, 9430.245580353418, 9436.245100392089, 9499.287553433382, 9586.904130958601, 9611.279154063333, 10679.85314455702, 10866.348019118877, 10924.344539327665, 10949.781004379709, 11101.333919964827, 8089.635966381645, 8227.547484888388, 8327.541985190872, 8348.582570871553, 8712.259457945893, 10784.137269018303, 10860.294080884718, 10958.452077396258, 11092.667219983414, 11268.211225214156], "value": 8779.026486389492, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1253.3858409379404, 1291.1478424240004, 1291.59314815833, 1293.3274697157485, 1298.6104168749375, 9671.70984870454, 9949.502524873746, 10161.542730577114, 10166.135878450332, 10464.267501274913, 10149.797004059914, 10351.068403843652, 10371.325863818865, 10380.0, 10417.374957502536, 2070.730804995352, 2106.2522804404443, 2106.631339515586, 2113.218109299554, 2115.23851413491, 2004.7393838801008, 2049.333966460899, 2050.179928028793, 2052.097077285997, 2052.6100040992237, 2008.156574238816, 2021.7978202179738, 2039.388183544938, 2039.7144399784042, 2048.498117961102, 1973.9013049347548, 1977.0411350494999, 1977.2881762565464, 1986.5629561496448, 1991.8904460254753, 1675.0703359635397, 1680.546252511827, 1954.2183126749414, 2034.0846619021352, 2044.570640165567, 10002.0, 10084.899151008469, 10092.747681307952, 10141.290109692325, 10146.898531014669, 5387.1111266640955, 5595.272614560122, 5596.832095037155, 5599.048161812501, 5612.354579223401, 3088.552159936819, 3190.6968837960358, 3246.6104067511815, 3254.8372581371073, 3275.050235431722, 1050.2018465965843, 1070.1866581398115, 1075.8924107589257, 1087.896649818266, 1092.9726756831064, 14231.573052808433, 14317.069390489585, 14449.060811047248, 14462.421503139862, 14478.348474318682, 4510.639148868083, 4581.702189357681, 4731.195696731564, 4759.928601070987, 4772.49888761682, 10776.29954052984, 10812.94593527043, 10821.67534973952, 10859.78280434387, 10881.0, 5205.687658740488, 5356.517913387814, 5385.246065550826, 5389.649672771257, 5392.137258038695, 4866.537678920497, 4997.925031124536, 5017.172166592544, 5017.397912250553, 5018.172001619765, 3568.964310356915, 3622.909427264287, 3635.381985062546, 3692.9815350923072, 3748.0, 1053.4364115198484, 1054.9789004219872, 1055.5250137438184, 1081.3836113415277, 1085.3054045410943, 703.869784089945, 733.1898252431093, 737.181728281607, 740.7444431671065, 746.3618606091713, 5547.25112109868, 5573.69344686038, 5595.4124816894455, 5617.466340697627, 5709.115087161496, 526.7182057599166, 551.6524589508584, 551.7765305051461, 555.419586532075, 555.7471350535496, 3348.2968576599187, 3380.6139482812423, 3478.495618135407, 3481.7388695847912, 3876.8836934891433, 12524.248545087325, 12717.491300348067, 12926.547570834975, 13054.804177937338, 13096.803547946789, 12740.681482962815, 13005.284709341076, 13006.154599950973, 13019.088663793444, 13025.892799111993, 7187.856242875113, 7209.747658831916, 7220.819479513054, 7254.637268136677, 7337.669804858794, 5511.559075273888, 5728.971355143364, 5790.9710451449155, 5950.672713000827, 5984.670843103497, 11403.429828508375, 11677.708057298636, 11694.2983420995, 11886.762264754829, 11886.82169767437, 11949.64151075469, 12146.08904332161, 12148.392580371123, 12167.296578479198, 12334.81497777552, 7694.961180240596, 7739.613019348897, 7811.726589569338, 7813.492123011986, 7820.257075577811, 9369.0, 9394.906050939677, 9417.717468475958, 9457.952710236405, 9502.38234514782, 7910.485818421784, 7913.960430198043, 7981.281684648409, 8065.314448271728, 8270.131636178116, 7796.064472263365, 7858.882116768139, 7862.331701805182, 7931.087924888481, 7954.204579542, 6734.831629209309, 6984.196817366068, 7106.964465177641, 7107.857842843217, 7117.0, 5226.817061402832, 5242.842714718565, 5274.709890955881, 5347.224652425454, 5465.781368745205, 5234.63357564982, 5372.2747429098135, 5405.972970135124, 5411.702356370437, 5472.069748142746], "value": 4544.481229238408, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.2956220588749, 379.4005471355257, 383.40189304684696, 384.4675124951944, 386.02721142720344, 5494.560435165187, 5646.350669672984, 5653.660780353177, 5684.943150568493, 5693.0, 5412.377576578695, 5481.75332110055, 5501.229827824108, 5517.420670829565, 5521.834344969648, 4213.47331583552, 4214.13610209907, 4219.1139860629155, 4235.301175306071, 4236.618704316611, 4136.110736191718, 4186.58134186582, 4227.0, 4237.4491316129015, 4240.915181696364, 4172.123853990651, 4176.749395036308, 4183.560726123744, 4186.162767446508, 4196.454460920076, 4147.294959856831, 4148.23257697327, 4160.625543701051, 4163.50037995441, 4165.604267594574, 3567.126054116758, 3647.7993710346186, 3666.17511060012, 3678.2275722098125, 3691.7046636269033, 5441.129419292894, 5444.50999410051, 5464.9453505464835, 5465.622454232422, 5474.178873169017, 4923.4091908970795, 4936.531029552187, 4946.777395017233, 4958.479359667255, 4977.178765503688, 5739.82780516581, 5813.82558523245, 5837.387074357174, 5909.0, 5918.141869428909, 4407.757573333465, 4429.269170586849, 4430.977845110754, 4434.512203657595, 4445.310976798602, 6000.789972350947, 6187.81436556904, 6220.720067596969, 6269.780557680505, 6314.494840412802, 4406.49325327585, 4432.667549933768, 4439.667024973075, 4440.622547083534, 4440.822367105344, 4493.101379724003, 4508.368828364031, 4515.0, 4518.615917647036, 4534.093181363674, 4981.551660350586, 5080.491950804964, 5083.186690129635, 5084.161113416245, 5086.21163719624, 5935.851603709855, 5936.22829032223, 5942.316633587193, 5960.552958528127, 5964.7315870785915, 1603.0702192728422, 1608.9782987802596, 1610.846969537892, 1612.8387161283779, 1615.7980252468442, 1107.5791199344199, 1172.9061675065975, 1188.7265928836416, 1191.4936152135308, 1197.1201167142144, 5075.137226671474, 5078.542931136141, 5086.72023038729, 5114.974425127851, 5119.46245644202, 6005.3694362091355, 6106.0, 6112.633242005494, 6157.815265542041, 6165.075238714139, 5004.124278251365, 5024.773885175176, 5042.974785126051, 5057.494250574913, 5058.140116180185, 3230.82230477326, 3256.8571328667476, 3263.396271689745, 3268.477043673001, 3275.2958114005605, 5481.616286859881, 5628.634138780966, 5639.971800140973, 5651.858703532444, 5667.263255776723, 6264.498840092781, 6293.1504246927, 6295.905561416583, 6298.496120310364, 6319.557630965788, 5457.426970168075, 5651.208830763697, 5652.293463317085, 5669.659820410789, 5673.858153546112, 6002.549808764273, 6071.787487437919, 6078.088286757022, 6115.480184184395, 6167.995401494442, 5821.534277257894, 5984.102384642339, 6006.09908513709, 6030.48740857032, 6036.607620504653, 5592.692401917933, 5630.971845140748, 5635.464630860061, 5647.5199608032135, 5659.801906933238, 4198.6221240088535, 4220.324748040299, 4244.575542445731, 4248.405223268683, 4262.190183865056, 5988.37122102173, 6078.878422431615, 6127.14220009208, 6141.048137538689, 6160.784372546939, 5946.554008449297, 5950.702464876652, 5952.880942381042, 5956.255468066492, 5965.135055417027, 5821.476067153976, 5934.287885453773, 5956.821295361146, 5963.850903727442, 5968.0, 5797.767859374087, 5845.1232315153065, 5854.151148083589, 5857.502112320501, 5874.441928016831, 5931.822045338647, 5947.910781338197, 5971.611845230046, 5983.700814959322, 6012.8797424052145, 5638.210650509014, 5702.486776190163, 5763.423657634203, 5771.662476696944, 5794.217780599565], "value": 4464.9417366254875, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.0860133434543, 753.8266198774285, 779.1546172402942, 782.4483738964029, 796.7211475983405, 13980.790288145674, 14707.632309192275, 14714.411423543059, 14956.7008659827, 15413.92293038536, 16836.158192090377, 16977.91511042443, 17057.658846823055, 17073.0, 17089.230984605692, 893.714011516315, 893.9150780675858, 899.3659470073615, 899.8380291547519, 902.8013836955892, 883.3639779358883, 905.6830109461702, 909.56340956341, 913.8446464101086, 919.1865199298635, 902.7246689759621, 949.7768024514234, 953.0755167487526, 953.7281874665722, 956.7560272130597, 907.3739120007207, 939.7415710679584, 946.5220063867703, 947.6588428165854, 950.3585080070959, 671.1107782188619, 685.5715178013742, 694.350782018809, 695.6069820551423, 700.0514303119251, 14898.553043408716, 15051.698966020727, 15088.471903483434, 15155.848441515664, 15226.847731522654, 15592.454264100703, 16065.759013614805, 16132.838671613252, 16251.593710157222, 16274.26765795542, 12591.181573197713, 12990.090693651448, 13154.144980576231, 13190.868091319157, 13235.867641323559, 3071.094027261941, 3075.8308293043874, 3080.7843450958444, 3082.5067989121635, 3088.366884788613, 88818.0, 89380.5530972341, 89878.0, 91372.62941055889, 92136.53931730299, 10419.635312764018, 10609.893901061045, 10631.574737010587, 10700.090492308085, 10712.0, 23213.651795223086, 23323.53352932966, 23381.53236935286, 23386.415339616644, 23391.883040585028, 6126.08108783686, 6199.070139479024, 6212.409821066992, 6262.436380725757, 6271.874562508816, 2873.5115030444877, 2923.8099523530905, 2929.5605659151297, 2935.8532073396245, 2952.158634789118, 3615.29501747162, 3675.889723308305, 3679.5032670589662, 3683.7605555638797, 3685.0, 661.6823924516162, 661.778304268066, 665.0423390317927, 669.3975422120034, 670.4301343857682, 571.4342800627433, 575.7610591604438, 579.2932622200908, 625.1435533319291, 632.7563887903191, 9596.080068380152, 10396.48017599117, 10662.626808061836, 10742.0, 11329.150313726339, 598.2133494454865, 599.6192417814719, 609.1715267236555, 612.650789050246, 630.334996578614, 4269.466316710411, 4447.555244475527, 4464.330350447394, 4476.216662084187, 4542.954570454253, 77991.61004194943, 78243.0, 78533.60733196298, 83494.0, 83802.16197838065, 73771.0, 74892.62553687197, 74998.25001750022, 76323.61838190774, 77316.0, 59002.40997589969, 59735.0, 62179.378206217356, 63182.0, 65123.34876651173, 9720.562574684014, 10942.069924056226, 11012.17408694335, 11042.944785276022, 11299.322040677585, 27239.182824514504, 27371.589426159022, 27831.16506504808, 28850.27874303075, 28907.132786016453, 51371.48628513816, 52689.73655131853, 53083.469165307855, 53336.73331633317, 53356.46643533515, 13303.135296205715, 13365.198088114745, 13439.99200060019, 13470.124441911245, 13650.726985460036, 16513.587160321087, 16542.66914661724, 16695.0, 16792.664146716754, 16971.745423818407, 14290.857091429369, 14675.48635797742, 14727.337269822669, 14907.0, 15018.474353398016, 14820.925895370454, 14907.478238261609, 15016.099034057554, 15124.848751512343, 15405.537833865003, 14799.704005919606, 14878.181700006604, 14918.179500127597, 15032.24838758036, 15301.846981530041, 10395.220358472994, 10426.635067772895, 10691.946540267249, 10754.300970436896, 11232.83150752723, 11590.94204528972, 11638.6508404748, 11901.642950711492, 12031.819522706992, 12056.75886482248], "value": 7242.486035503438, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 110.0, 111.0, 112.0, 111.0, 113.0, 112.0, 113.0, 111.0, 113.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 132.0, 121.0, 179.0, 122.0, 125.0, 123.0, 124.0, 145.0, 129.0], "value": 135.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 165.0, 167.0, 168.0, 170.0, 166.0, 165.0, 165.0, 162.0], "value": 165.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 79.0, 78.0, 104.0, 75.0, 74.0, 74.0, 77.0, 93.0, 73.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 114.0, 83.0, 83.0, 85.0, 98.0, 84.0, 83.0, 106.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 113.0, 116.0, 114.0, 114.0, 113.0, 114.0, 115.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 151.0, 151.0, 150.0, 150.0, 150.0, 151.0, 151.0, 151.0], "value": 150.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 56.0, 56.0, 57.0, 56.0, 67.0, 59.0, 55.0, 57.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 58.0, 57.0, 58.0, 57.0, 57.0, 57.0, 57.0], "value": 57.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 88.0, 89.0, 85.0, 95.0, 87.0, 88.0, 123.0, 86.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 136.0, 135.0, 135.0, 133.0, 134.0, 133.0, 140.0, 134.0, 132.0], "value": 135.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 200.0, 195.0, 191.0, 196.0, 192.0, 201.0, 204.0, 194.0, 195.0], "value": 195.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 76.0, 72.0, 74.0, 77.0, 75.0, 75.0, 74.0, 73.0], "value": 74.4, "unit": "score"}]}]} 09:07:08 INFO - Return code: 0 09:07:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:07:08 INFO - # TBPL SUCCESS # 09:07:08 INFO - Running post-action listener: _resource_record_post_action 09:07:08 INFO - [mozharness: 2017-05-26 16:07:08.254808Z] Finished run-tests step (success) 09:07:08 INFO - Running post-run listener: _resource_record_post_run 09:07:08 INFO - Total resource usage - Wall time: 1257s; CPU: 13.0%; Read bytes: 8708096; Write bytes: 415150080; Read time: 3712; Write time: 457032 09:07:08 INFO - TinderboxPrint: CPU usage
12.7% 09:07:08 INFO - TinderboxPrint: I/O read bytes / time
8,708,096 / 3,712 09:07:08 INFO - TinderboxPrint: I/O write bytes / time
415,150,080 / 457,032 09:07:08 INFO - TinderboxPrint: CPU idle
8,760.5 (87.3%) 09:07:08 INFO - TinderboxPrint: CPU user
1,213.1 (12.1%) 09:07:08 INFO - TinderboxPrint: Swap in / out
0 / 0 09:07:08 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 112775168; Read time: 116; Write time: 129944 09:07:08 INFO - run-tests - Wall time: 1242s; CPU: 13.0%; Read bytes: 8704000; Write bytes: 302374912; Read time: 3596; Write time: 327088 09:07:08 INFO - Running post-run listener: _upload_blobber_files 09:07:08 INFO - Blob upload gear active. 09:07:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:07:08 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 09:07:08 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:07:08 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:07:09 INFO - (blobuploader) - INFO - Open directory for files ... 09:07:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:07:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:07:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:10 INFO - (blobuploader) - INFO - Done attempting. 09:07:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:07:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:07:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:11 INFO - (blobuploader) - INFO - Done attempting. 09:07:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:07:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:07:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:18 INFO - (blobuploader) - INFO - Done attempting. 09:07:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:07:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:07:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:19 INFO - (blobuploader) - INFO - Done attempting. 09:07:19 INFO - (blobuploader) - INFO - Iteration through files over. 09:07:19 INFO - Return code: 0 09:07:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:07:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:07:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f81f52de2f85efcfeae2d77f27e2e7195940c30fb93bda1464cbb538057b1272266268f5ff38c33b391c2a703150a9cc1871ab303db50937ab8984faaf6c502e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c83a077e8a8c0cc93f6563d71a2c3aed76eb31b6d28eb735a681b92b517a8aba79948fbe05a9c734bdc923e1c6b1af5876d25ad7ed7aeb4cf5645a0015fe0c97", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6538409771cab651a63fa41894362e32747c7dc7dea7a70191257c3e7b794831784406430306e8a5e3a89710b823f1deacad6e1e77da35f80099e6f9ba83e5e1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/234769eb427d3b8006513cf748f2bd4b397edf207d2c7bd1da7be86ccba224111a9c3ccc419600a0cf6589908fda0a64df7daf58cad77db33a5b8c0714e49af6"} 09:07:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:07:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:07:19 INFO - Contents: 09:07:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f81f52de2f85efcfeae2d77f27e2e7195940c30fb93bda1464cbb538057b1272266268f5ff38c33b391c2a703150a9cc1871ab303db50937ab8984faaf6c502e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c83a077e8a8c0cc93f6563d71a2c3aed76eb31b6d28eb735a681b92b517a8aba79948fbe05a9c734bdc923e1c6b1af5876d25ad7ed7aeb4cf5645a0015fe0c97", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6538409771cab651a63fa41894362e32747c7dc7dea7a70191257c3e7b794831784406430306e8a5e3a89710b823f1deacad6e1e77da35f80099e6f9ba83e5e1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/234769eb427d3b8006513cf748f2bd4b397edf207d2c7bd1da7be86ccba224111a9c3ccc419600a0cf6589908fda0a64df7daf58cad77db33a5b8c0714e49af6"} 09:07:19 INFO - Running post-run listener: copy_logs_to_upload_dir 09:07:19 INFO - Copying logs to upload dir... 09:07:19 INFO - mkdir: /builds/slave/test/build/upload/logs 09:07:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1322.222332 ========= master_lag: -0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 2 secs) (at 2017-05-26 09:07:19.227646) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 09:07:19.235279) ========= 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=5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f81f52de2f85efcfeae2d77f27e2e7195940c30fb93bda1464cbb538057b1272266268f5ff38c33b391c2a703150a9cc1871ab303db50937ab8984faaf6c502e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c83a077e8a8c0cc93f6563d71a2c3aed76eb31b6d28eb735a681b92b517a8aba79948fbe05a9c734bdc923e1c6b1af5876d25ad7ed7aeb4cf5645a0015fe0c97", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6538409771cab651a63fa41894362e32747c7dc7dea7a70191257c3e7b794831784406430306e8a5e3a89710b823f1deacad6e1e77da35f80099e6f9ba83e5e1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/234769eb427d3b8006513cf748f2bd4b397edf207d2c7bd1da7be86ccba224111a9c3ccc419600a0cf6589908fda0a64df7daf58cad77db33a5b8c0714e49af6"} build_url:https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011222 build_url: 'https://queue.taskcluster.net/v1/task/SbFTaHztR-yIz2-oJJAJmg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f81f52de2f85efcfeae2d77f27e2e7195940c30fb93bda1464cbb538057b1272266268f5ff38c33b391c2a703150a9cc1871ab303db50937ab8984faaf6c502e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c83a077e8a8c0cc93f6563d71a2c3aed76eb31b6d28eb735a681b92b517a8aba79948fbe05a9c734bdc923e1c6b1af5876d25ad7ed7aeb4cf5645a0015fe0c97", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6538409771cab651a63fa41894362e32747c7dc7dea7a70191257c3e7b794831784406430306e8a5e3a89710b823f1deacad6e1e77da35f80099e6f9ba83e5e1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/234769eb427d3b8006513cf748f2bd4b397edf207d2c7bd1da7be86ccba224111a9c3ccc419600a0cf6589908fda0a64df7daf58cad77db33a5b8c0714e49af6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 09:07:19.262271) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 09:07:19.262604) ========= 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=5cf47e208febbcd81da23b2e0000026f-1495813500.889937-554002183 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004417 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 09:07:19.342558) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 09:07:19.345927) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 09:07:19.346231) ========= ========= Total master_lag: 0.14 =========