builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-037 starttime: 1495675172.26 results: success (0) revision: d10f5ccd882b965fcad39914f7c3c930d1301a41 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 18:19:32.264984) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 18:19:32.265452) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 18:19:32.265749) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 18:19:32.280594) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 18:19:32.280965) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-24 18:19:32-- 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% 99.6M=0s 2017-05-24 18:19:32 (99.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134022 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 18:19:32.428903) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 18:19:32.429304) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.025791 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 18:19:32.479096) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 18:19:32.479426) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d10f5ccd882b965fcad39914f7c3c930d1301a41 --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 d10f5ccd882b965fcad39914f7c3c930d1301a41 --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=56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-24 18:19:32,491 truncating revision to first 12 chars 2017-05-24 18:19:32,491 Setting DEBUG logging. 2017-05-24 18:19:32,491 attempt 1/10 2017-05-24 18:19:32,491 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d10f5ccd882b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 18:19:33,077 unpacking tar archive at: mozilla-central-d10f5ccd882b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.911336 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 18:19:33.405994) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 18:19:33.406319) ========= script_repo_revision: d10f5ccd882b965fcad39914f7c3c930d1301a41 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 18:19:33.409711) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 18:19:33.409982) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 18:19:33.418727) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 55 secs) (at 2017-05-24 18:19:33.419059) ========= /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=56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:19:33 INFO - MultiFileLogger online at 20170524 18:19:33 in /builds/slave/test 18:19:33 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 18:19:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:19:33 INFO - {'append_to_log': False, 18:19:33 INFO - 'base_work_dir': '/builds/slave/test', 18:19:33 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:19:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:19:33 INFO - 'branch': 'Firefox-Non-PGO', 18:19:33 INFO - 'buildbot_json_path': 'buildprops.json', 18:19:33 INFO - 'config_files': ('talos/linux_config.py',), 18:19:33 INFO - 'default_actions': ('clobber', 18:19:33 INFO - 'read-buildbot-config', 18:19:33 INFO - 'download-and-extract', 18:19:33 INFO - 'populate-webroot', 18:19:33 INFO - 'create-virtualenv', 18:19:33 INFO - 'install', 18:19:33 INFO - 'run-tests'), 18:19:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:19:33 INFO - 'download_minidump_stackwalk': True, 18:19:33 INFO - 'download_symbols': 'ondemand', 18:19:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:19:33 INFO - 'tooltool.py': '/tools/tooltool.py', 18:19:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:19:33 INFO - '/tools/misc-python/virtualenv.py')}, 18:19:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:19:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:19:33 INFO - 'gecko_profile': False, 18:19:33 INFO - 'gecko_profile_interval': 0, 18:19:33 INFO - 'installer_path': 'installer.exe', 18:19:33 INFO - 'log_level': 'info', 18:19:33 INFO - 'log_name': 'talos', 18:19:33 INFO - 'log_to_console': True, 18:19:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:19:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:19:33 INFO - 'opt_config_files': (), 18:19:33 INFO - 'pip_index': False, 18:19:33 INFO - 'suite': 'dromaeojs-e10s', 18:19:33 INFO - 'system_bits': '32', 18:19:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:19:33 INFO - 'title': 'talos-linux64-ix-037', 18:19:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:19:33 INFO - 'use_talos_json': True, 18:19:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:19:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:19:33 INFO - 'work_dir': 'build'} 18:19:33 INFO - [mozharness: 2017-05-25 01:19:33.526386Z] Running clobber step. 18:19:33 INFO - Running pre-action listener: _resource_record_pre_action 18:19:33 INFO - Running main action method: clobber 18:19:33 INFO - rmtree: /builds/slave/test/build 18:19:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:19:35 INFO - Running post-action listener: _resource_record_post_action 18:19:35 INFO - [mozharness: 2017-05-25 01:19:35.068611Z] Finished clobber step (success) 18:19:35 INFO - [mozharness: 2017-05-25 01:19:35.068710Z] Running read-buildbot-config step. 18:19:35 INFO - Running pre-action listener: _resource_record_pre_action 18:19:35 INFO - Running main action method: read_buildbot_config 18:19:35 INFO - Using buildbot properties: 18:19:35 INFO - { 18:19:35 INFO - "project": "", 18:19:35 INFO - "product": "firefox", 18:19:35 INFO - "who": "ryanvm@gmail.com", 18:19:35 INFO - "installer_path": "public/build/target.tar.bz2", 18:19:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:19:35 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:19:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:19:35 INFO - "stage_platform": "linux64", 18:19:35 INFO - "basedir": "/builds/slave/test", 18:19:35 INFO - "buildnumber": 850, 18:19:35 INFO - "platform": "ubuntu64_hw", 18:19:35 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:19:35 INFO - "slavebuilddir": "test", 18:19:35 INFO - "taskId": "EsTPJsVfRsykoLln9m4gSQ", 18:19:35 INFO - "branch": "mozilla-central", 18:19:35 INFO - "script_repo_revision": "production", 18:19:35 INFO - "revision": "d10f5ccd882b965fcad39914f7c3c930d1301a41", 18:19:35 INFO - "slavename": "talos-linux64-ix-037", 18:19:35 INFO - "repo_path": "mozilla-central" 18:19:35 INFO - } 18:19:35 INFO - Finding installer, test and symbols from parent task. 18:19:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EsTPJsVfRsykoLln9m4gSQ'}, attempt #1 18:19:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EsTPJsVfRsykoLln9m4gSQ'}, attempt #1 18:19:35 INFO - Task dependencies: O6MD1N43Rp-bCmGfoqftNw 18:19:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw'}, attempt #1 18:19:36 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.tar.bz2 18:19:36 INFO - Set test_url: https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/test_packages.json 18:19:36 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.crashreporter-symbols.zip 18:19:36 INFO - Running post-action listener: _resource_record_post_action 18:19:36 INFO - [mozharness: 2017-05-25 01:19:36.417859Z] Finished read-buildbot-config step (success) 18:19:36 INFO - [mozharness: 2017-05-25 01:19:36.418081Z] Running download-and-extract step. 18:19:36 INFO - Running pre-action listener: _resource_record_pre_action 18:19:36 INFO - Running main action method: download_and_extract 18:19:36 INFO - mkdir: /builds/slave/test/build/tests 18:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:19:36 INFO - trying https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.test_packages.json 18:19:36 INFO - Downloading https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:19:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:19:36 INFO - Downloaded 989 bytes. 18:19:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:19:36 INFO - Using the following test package requirements: 18:19:36 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:19:36 INFO - u'common': [u'target.common.tests.zip'], 18:19:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:19:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:19:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:19:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:19:36 INFO - u'mozbase': [u'target.common.tests.zip'], 18:19:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:19:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:19:36 INFO - u'web-platform': [u'target.common.tests.zip', 18:19:36 INFO - u'target.web-platform.tests.tar.gz'], 18:19:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:19:36 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:19:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.common.tests.zip 18:19:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:19:36 INFO - Fetch https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.common.tests.zip into memory 18:19:38 INFO - Content-Length response header: 63523829 18:19:38 INFO - Bytes received: 63523829 18:19:49 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:19:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.common.tests.zip 18:19:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:19:49 INFO - Fetch https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.common.tests.zip into memory 18:19:50 INFO - Content-Length response header: 63523829 18:19:50 INFO - Bytes received: 63523829 18:20:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.talos.tests.zip 18:20:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:20:02 INFO - Fetch https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.talos.tests.zip into memory 18:20:02 INFO - Content-Length response header: 14411859 18:20:02 INFO - Bytes received: 14411859 18:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:03 INFO - trying https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.tar.bz2 18:20:03 INFO - Downloading https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:20:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:20:04 INFO - Downloaded 54012175 bytes. 18:20:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.tar.bz2 18:20:04 INFO - mkdir: /builds/slave/test/properties 18:20:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:20:04 INFO - Writing to file /builds/slave/test/properties/build_url 18:20:04 INFO - Contents: 18:20:04 INFO - build_url:https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.tar.bz2 18:20:04 INFO - Running post-action listener: _resource_record_post_action 18:20:04 INFO - Running post-action listener: set_extra_try_arguments 18:20:04 INFO - [mozharness: 2017-05-25 01:20:04.309111Z] Finished download-and-extract step (success) 18:20:04 INFO - [mozharness: 2017-05-25 01:20:04.309209Z] Running populate-webroot step. 18:20:04 INFO - Running pre-action listener: _resource_record_pre_action 18:20:04 INFO - Running main action method: populate_webroot 18:20:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:20:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:20:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:20:04 INFO - '--tppagecycles', 18:20:04 INFO - '7'], 18:20:04 INFO - 'tests': ['tsvgm']}}, 18:20:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:20:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:20:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:20:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:20:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:20:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:20:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:20:04 INFO - 'talos_options': ['--disable-e10s'], 18:20:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:20:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:20:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:20:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:20:04 INFO - 'talos_options': ['--disable-e10s'], 18:20:04 INFO - 'tests': ['damp', 'tps']}, 18:20:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:20:04 INFO - 'tests': ['damp', 'tps']}, 18:20:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:20:04 INFO - 'tests': ['dromaeo_dom']}, 18:20:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:20:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:20:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:20:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:20:04 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:20:04 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:20:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:20:04 INFO - 'tests': ['a11yr', 18:20:04 INFO - 'ts_paint', 18:20:04 INFO - 'tpaint', 18:20:04 INFO - 'sessionrestore', 18:20:04 INFO - 'sessionrestore_no_auto_restore', 18:20:04 INFO - 'tabpaint']}, 18:20:04 INFO - 'other-e10s': {'tests': ['a11yr', 18:20:04 INFO - 'ts_paint', 18:20:04 INFO - 'tpaint', 18:20:04 INFO - 'sessionrestore', 18:20:04 INFO - 'sessionrestore_no_auto_restore', 18:20:04 INFO - 'tabpaint']}, 18:20:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:20:04 INFO - 'ts_paint', 18:20:04 INFO - 'tpaint', 18:20:04 INFO - 'sessionrestore', 18:20:04 INFO - 'sessionrestore_no_auto_restore', 18:20:04 INFO - 'tabpaint']}, 18:20:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:20:04 INFO - 'ts_paint', 18:20:04 INFO - 'tpaint', 18:20:04 INFO - 'sessionrestore', 18:20:04 INFO - 'sessionrestore_no_auto_restore', 18:20:04 INFO - 'tabpaint']}, 18:20:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:20:04 INFO - 'tests': ['a11yr', 18:20:04 INFO - 'ts_paint', 18:20:04 INFO - 'tpaint', 18:20:04 INFO - 'sessionrestore', 18:20:04 INFO - 'sessionrestore_no_auto_restore', 18:20:04 INFO - 'tabpaint']}, 18:20:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:20:04 INFO - 'tests': ['a11yr', 18:20:04 INFO - 'ts_paint', 18:20:04 INFO - 'tpaint', 18:20:04 INFO - 'sessionrestore', 18:20:04 INFO - 'sessionrestore_no_auto_restore', 18:20:04 INFO - 'tabpaint']}, 18:20:04 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:20:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:20:04 INFO - 'bloom_basic_ref']}, 18:20:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:20:04 INFO - 'tests': ['tsvgx', 18:20:04 INFO - 'tsvgr_opacity', 18:20:04 INFO - 'tart', 18:20:04 INFO - 'tscrollx', 18:20:04 INFO - 'cart', 18:20:04 INFO - 'tsvg_static']}, 18:20:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:20:04 INFO - 'tsvgr_opacity', 18:20:04 INFO - 'tart', 18:20:04 INFO - 'tscrollx', 18:20:04 INFO - 'cart', 18:20:04 INFO - 'tsvg_static']}, 18:20:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:20:04 INFO - 'talos_options': ['--disable-e10s'], 18:20:04 INFO - 'tests': ['tp5o']}, 18:20:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:20:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:20:04 INFO - 'talos_options': ['--disable-e10s', 18:20:04 INFO - '--xperf_path', 18:20:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:20:04 INFO - 'tests': ['tp5n']}, 18:20:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:20:04 INFO - 'talos_options': ['--xperf_path', 18:20:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:20:04 INFO - 'tests': ['tp5n']}}, 18:20:04 INFO - 'talos.zip': {'path': '', 18:20:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:20:04 INFO - Running post-action listener: _resource_record_post_action 18:20:04 INFO - [mozharness: 2017-05-25 01:20:04.323257Z] Finished populate-webroot step (success) 18:20:04 INFO - [mozharness: 2017-05-25 01:20:04.323347Z] Running create-virtualenv step. 18:20:04 INFO - Running pre-action listener: _resource_record_pre_action 18:20:04 INFO - Running main action method: create_virtualenv 18:20:04 INFO - Creating virtualenv /builds/slave/test/build/venv 18:20:04 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 18:20:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:20:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:20:04 INFO - Using real prefix '/usr' 18:20:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:20:04 INFO - Installing distribute.............................................................................................................................................................................................done. 18:20:06 INFO - Installing pip.................done. 18:20:06 INFO - Return code: 0 18:20:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:20:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:20:07 INFO - Reading from file tmpfile_stdout 18:20:07 INFO - Output received: 18:20:07 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) 18:20:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:07 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 0x24f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26bda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28635c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28607a0>, '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 0x28616a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2861b30>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', '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 18:20:07 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 18:20:07 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 18:20:07 INFO - Using env: {'DISPLAY': ':0', 18:20:07 INFO - 'HOME': '/home/cltbld', 18:20:07 INFO - 'LANG': 'en_US.UTF-8', 18:20:07 INFO - 'LANGUAGE': 'en_US:en', 18:20:07 INFO - 'LOGNAME': 'cltbld', 18:20:07 INFO - 'MAIL': '/var/mail/cltbld', 18:20:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:07 INFO - 'MOZ_NO_REMOTE': '1', 18:20:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:07 INFO - 'NO_EM_RESTART': '1', 18:20:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:07 INFO - 'PWD': '/builds/slave/test', 18:20:07 INFO - 'SHELL': '/bin/bash', 18:20:07 INFO - 'SHLVL': '1', 18:20:07 INFO - 'TERM': 'linux', 18:20:07 INFO - 'TMOUT': '86400', 18:20:07 INFO - 'USER': 'cltbld', 18:20:07 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', 18:20:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:07 INFO - '_': '/tools/buildbot/bin/python'} 18:20:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:07 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 18:20:07 INFO - Cleaning up... 18:20:07 INFO - Return code: 0 18:20:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:07 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 0x24f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26bda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28635c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28607a0>, '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 0x28616a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2861b30>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', '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 18:20:07 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 18:20:07 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 18:20:07 INFO - Using env: {'DISPLAY': ':0', 18:20:07 INFO - 'HOME': '/home/cltbld', 18:20:07 INFO - 'LANG': 'en_US.UTF-8', 18:20:07 INFO - 'LANGUAGE': 'en_US:en', 18:20:07 INFO - 'LOGNAME': 'cltbld', 18:20:07 INFO - 'MAIL': '/var/mail/cltbld', 18:20:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:07 INFO - 'MOZ_NO_REMOTE': '1', 18:20:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:07 INFO - 'NO_EM_RESTART': '1', 18:20:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:07 INFO - 'PWD': '/builds/slave/test', 18:20:07 INFO - 'SHELL': '/bin/bash', 18:20:07 INFO - 'SHLVL': '1', 18:20:07 INFO - 'TERM': 'linux', 18:20:07 INFO - 'TMOUT': '86400', 18:20:07 INFO - 'USER': 'cltbld', 18:20:07 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', 18:20:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:07 INFO - '_': '/tools/buildbot/bin/python'} 18:20:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:07 INFO - Downloading/unpacking psutil>=3.1.1 18:20:07 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 18:20:07 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 18:20:07 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 18:20:07 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 18:20:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:20:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:08 INFO - Installing collected packages: psutil 18:20:08 INFO - Running setup.py install for psutil 18:20:08 INFO - building 'psutil._psutil_linux' extension 18:20:08 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 18:20:08 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 18:20:08 INFO - building 'psutil._psutil_posix' extension 18:20:08 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 18:20:09 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 18:20:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:09 INFO - Successfully installed psutil 18:20:09 INFO - Cleaning up... 18:20:09 INFO - Return code: 0 18:20:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:09 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 0x24f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26bda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28635c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28607a0>, '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 0x28616a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2861b30>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', '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 18:20:09 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 18:20:09 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 18:20:09 INFO - Using env: {'DISPLAY': ':0', 18:20:09 INFO - 'HOME': '/home/cltbld', 18:20:09 INFO - 'LANG': 'en_US.UTF-8', 18:20:09 INFO - 'LANGUAGE': 'en_US:en', 18:20:09 INFO - 'LOGNAME': 'cltbld', 18:20:09 INFO - 'MAIL': '/var/mail/cltbld', 18:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:09 INFO - 'MOZ_NO_REMOTE': '1', 18:20:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:09 INFO - 'NO_EM_RESTART': '1', 18:20:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:09 INFO - 'PWD': '/builds/slave/test', 18:20:09 INFO - 'SHELL': '/bin/bash', 18:20:09 INFO - 'SHLVL': '1', 18:20:09 INFO - 'TERM': 'linux', 18:20:09 INFO - 'TMOUT': '86400', 18:20:09 INFO - 'USER': 'cltbld', 18:20:09 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', 18:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:09 INFO - '_': '/tools/buildbot/bin/python'} 18:20:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:20:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:20:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:20:10 INFO - Installing collected packages: mozsystemmonitor 18:20:10 INFO - Running setup.py install for mozsystemmonitor 18:20:10 INFO - Successfully installed mozsystemmonitor 18:20:10 INFO - Cleaning up... 18:20:10 INFO - Return code: 0 18:20:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:20: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')]} 18:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20: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')]} 18:20:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26bda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28635c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28607a0>, '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 0x28616a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2861b30>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', '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 18:20: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==2.5.1'] in /builds/slave/test/build 18:20: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==2.5.1 18:20:10 INFO - Using env: {'DISPLAY': ':0', 18:20:10 INFO - 'HOME': '/home/cltbld', 18:20:10 INFO - 'LANG': 'en_US.UTF-8', 18:20:10 INFO - 'LANGUAGE': 'en_US:en', 18:20:10 INFO - 'LOGNAME': 'cltbld', 18:20:10 INFO - 'MAIL': '/var/mail/cltbld', 18:20:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:10 INFO - 'MOZ_NO_REMOTE': '1', 18:20:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:10 INFO - 'NO_EM_RESTART': '1', 18:20:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:10 INFO - 'PWD': '/builds/slave/test', 18:20:10 INFO - 'SHELL': '/bin/bash', 18:20:10 INFO - 'SHLVL': '1', 18:20:10 INFO - 'TERM': 'linux', 18:20:10 INFO - 'TMOUT': '86400', 18:20:10 INFO - 'USER': 'cltbld', 18:20:10 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', 18:20:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:10 INFO - '_': '/tools/buildbot/bin/python'} 18:20:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:10 INFO - Downloading/unpacking jsonschema==2.5.1 18:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:20:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:12 INFO - Downloading functools32-3.2.3-2.tar.gz 18:20:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:20:12 INFO - warning: no files found matching '*.txt' 18:20:12 INFO - no previously-included directories found matching 'build' 18:20:12 INFO - no previously-included directories found matching 'dist' 18:20:12 INFO - no previously-included directories found matching '.git*' 18:20:12 INFO - Installing collected packages: jsonschema, functools32 18:20:12 INFO - Running setup.py install for functools32 18:20:12 INFO - warning: no files found matching '*.txt' 18:20:12 INFO - no previously-included directories found matching 'build' 18:20:12 INFO - no previously-included directories found matching 'dist' 18:20:12 INFO - no previously-included directories found matching '.git*' 18:20:12 INFO - Successfully installed jsonschema functools32 18:20:12 INFO - Cleaning up... 18:20:12 INFO - Return code: 0 18:20:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:20:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26bda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28635c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28607a0>, '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 0x28616a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2861b30>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', '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 18:20:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:20:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:20:12 INFO - Using env: {'DISPLAY': ':0', 18:20:12 INFO - 'HOME': '/home/cltbld', 18:20:12 INFO - 'LANG': 'en_US.UTF-8', 18:20:12 INFO - 'LANGUAGE': 'en_US:en', 18:20:12 INFO - 'LOGNAME': 'cltbld', 18:20:12 INFO - 'MAIL': '/var/mail/cltbld', 18:20:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:12 INFO - 'MOZ_NO_REMOTE': '1', 18:20:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:12 INFO - 'NO_EM_RESTART': '1', 18:20:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:12 INFO - 'PWD': '/builds/slave/test', 18:20:12 INFO - 'SHELL': '/bin/bash', 18:20:12 INFO - 'SHLVL': '1', 18:20:12 INFO - 'TERM': 'linux', 18:20:12 INFO - 'TMOUT': '86400', 18:20:12 INFO - 'USER': 'cltbld', 18:20:12 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', 18:20:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:12 INFO - '_': '/tools/buildbot/bin/python'} 18:20:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:20:12 INFO - Cleaning up... 18:20:12 INFO - Return code: 0 18:20:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:20:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26bda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28635c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28607a0>, '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 0x28616a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2861b30>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', '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 18:20:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:20:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:20:12 INFO - Using env: {'DISPLAY': ':0', 18:20:12 INFO - 'HOME': '/home/cltbld', 18:20:12 INFO - 'LANG': 'en_US.UTF-8', 18:20:12 INFO - 'LANGUAGE': 'en_US:en', 18:20:12 INFO - 'LOGNAME': 'cltbld', 18:20:12 INFO - 'MAIL': '/var/mail/cltbld', 18:20:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:12 INFO - 'MOZ_NO_REMOTE': '1', 18:20:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:12 INFO - 'NO_EM_RESTART': '1', 18:20:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:12 INFO - 'PWD': '/builds/slave/test', 18:20:12 INFO - 'SHELL': '/bin/bash', 18:20:12 INFO - 'SHLVL': '1', 18:20:12 INFO - 'TERM': 'linux', 18:20:12 INFO - 'TMOUT': '86400', 18:20:12 INFO - 'USER': 'cltbld', 18:20:12 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', 18:20:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:12 INFO - '_': '/tools/buildbot/bin/python'} 18:20:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:12 INFO - Downloading/unpacking blobuploader==1.2.4 18:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:13 INFO - Downloading blobuploader-1.2.4.tar.gz 18:20:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:20:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:20:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:14 INFO - Downloading docopt-0.6.1.tar.gz 18:20:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:20:14 INFO - Installing collected packages: blobuploader, requests, docopt 18:20:14 INFO - Running setup.py install for blobuploader 18:20:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:20:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:20:14 INFO - Running setup.py install for requests 18:20:14 INFO - Running setup.py install for docopt 18:20:15 INFO - Successfully installed blobuploader requests docopt 18:20:15 INFO - Cleaning up... 18:20:15 INFO - Return code: 0 18:20:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:15 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 0x24f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26bda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28635c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28607a0>, '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 0x28616a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2861b30>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', '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 18:20:15 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 18:20:15 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 18:20:15 INFO - Using env: {'DISPLAY': ':0', 18:20:15 INFO - 'HOME': '/home/cltbld', 18:20:15 INFO - 'LANG': 'en_US.UTF-8', 18:20:15 INFO - 'LANGUAGE': 'en_US:en', 18:20:15 INFO - 'LOGNAME': 'cltbld', 18:20:15 INFO - 'MAIL': '/var/mail/cltbld', 18:20:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:15 INFO - 'MOZ_NO_REMOTE': '1', 18:20:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:15 INFO - 'NO_EM_RESTART': '1', 18:20:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:15 INFO - 'PWD': '/builds/slave/test', 18:20:15 INFO - 'SHELL': '/bin/bash', 18:20:15 INFO - 'SHLVL': '1', 18:20:15 INFO - 'TERM': 'linux', 18:20:15 INFO - 'TMOUT': '86400', 18:20:15 INFO - 'USER': 'cltbld', 18:20:15 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', 18:20:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:15 INFO - '_': '/tools/buildbot/bin/python'} 18:20:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:20:15 INFO - Running setup.py (path:/tmp/pip-FxjsL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:20:15 INFO - Running setup.py (path:/tmp/pip-mgcXo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:20:15 INFO - Running setup.py (path:/tmp/pip-TZqCsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:20:15 INFO - Running setup.py (path:/tmp/pip-M9fUNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:20:15 INFO - Running setup.py (path:/tmp/pip-K7bEHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:20:15 INFO - Running setup.py (path:/tmp/pip-4KiyKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:20:15 INFO - Running setup.py (path:/tmp/pip-ms8M_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:20:15 INFO - Running setup.py (path:/tmp/pip-AVNDAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:20:15 INFO - Running setup.py (path:/tmp/pip-6JV0Uz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:20:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:20:15 INFO - Running setup.py (path:/tmp/pip-j0UjDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:20:16 INFO - Running setup.py (path:/tmp/pip-gjwG0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:20:16 INFO - Running setup.py (path:/tmp/pip-d8hLKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:20:16 INFO - Running setup.py (path:/tmp/pip-0Bmi5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:20:16 INFO - Running setup.py (path:/tmp/pip-J3l4fW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:16 INFO - Running setup.py (path:/tmp/pip-1vukOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:20:16 INFO - Running setup.py (path:/tmp/pip-4WPdhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:20:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:20:16 INFO - Running setup.py (path:/tmp/pip-WPXmDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:20:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:20:16 INFO - Running setup.py install for manifestparser 18:20:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:20:16 INFO - Running setup.py install for mozcrash 18:20:16 INFO - Running setup.py install for mozdebug 18:20:16 INFO - Running setup.py install for mozdevice 18:20:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:20:16 INFO - Running setup.py install for mozfile 18:20:17 INFO - Running setup.py install for mozhttpd 18:20:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:20:17 INFO - Running setup.py install for mozinfo 18:20:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:20:17 INFO - Running setup.py install for mozInstall 18:20:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:20:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:20:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:20:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:20:17 INFO - Running setup.py install for mozleak 18:20:17 INFO - Running setup.py install for mozlog 18:20:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:20:17 INFO - Running setup.py install for moznetwork 18:20:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:20:17 INFO - Running setup.py install for mozprocess 18:20:17 INFO - Running setup.py install for mozprofile 18:20:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:20:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:20:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:20:17 INFO - Running setup.py install for mozrunner 18:20:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:20:18 INFO - Running setup.py install for mozscreenshot 18:20:18 INFO - Running setup.py install for moztest 18:20:18 INFO - Running setup.py install for mozversion 18:20:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:20:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:20:18 INFO - Cleaning up... 18:20:18 INFO - Return code: 0 18:20:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:18 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')]} 18:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:18 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')]} 18:20:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:18 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 0x24f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26bda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28635c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28607a0>, '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 0x28616a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2861b30>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', '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 18:20:18 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 18:20:18 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 18:20:18 INFO - Using env: {'DISPLAY': ':0', 18:20:18 INFO - 'HOME': '/home/cltbld', 18:20:18 INFO - 'LANG': 'en_US.UTF-8', 18:20:18 INFO - 'LANGUAGE': 'en_US:en', 18:20:18 INFO - 'LOGNAME': 'cltbld', 18:20:18 INFO - 'MAIL': '/var/mail/cltbld', 18:20:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:18 INFO - 'MOZ_NO_REMOTE': '1', 18:20:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:18 INFO - 'NO_EM_RESTART': '1', 18:20:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:18 INFO - 'PWD': '/builds/slave/test', 18:20:18 INFO - 'SHELL': '/bin/bash', 18:20:18 INFO - 'SHLVL': '1', 18:20:18 INFO - 'TERM': 'linux', 18:20:18 INFO - 'TMOUT': '86400', 18:20:18 INFO - 'USER': 'cltbld', 18:20:18 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', 18:20:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:18 INFO - '_': '/tools/buildbot/bin/python'} 18:20:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:20:18 INFO - Running setup.py (path:/tmp/pip-0vZHsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:20:18 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)) 18:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:20:18 INFO - Running setup.py (path:/tmp/pip-XkxVEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:20:18 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)) 18:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:20:18 INFO - Running setup.py (path:/tmp/pip-gVtj6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:20:18 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)) 18:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:20:18 INFO - Running setup.py (path:/tmp/pip-n19pWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:20:18 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)) 18:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:20:18 INFO - Running setup.py (path:/tmp/pip-cTc0kY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:20:18 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)) 18:20:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:20:18 INFO - Running setup.py (path:/tmp/pip-dtabCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:20:19 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)) 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:20:19 INFO - Running setup.py (path:/tmp/pip-p0e4G4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:20:19 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)) 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:20:19 INFO - Running setup.py (path:/tmp/pip-NNcHXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:20:19 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)) 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:20:19 INFO - Running setup.py (path:/tmp/pip-lQZnFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:20:19 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)) 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:20:19 INFO - Running setup.py (path:/tmp/pip-cJof6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:20:19 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)) 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:20:19 INFO - Running setup.py (path:/tmp/pip-x5InbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:20:19 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)) 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:20:19 INFO - Running setup.py (path:/tmp/pip-d_5ZfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:20:19 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)) 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:20:19 INFO - Running setup.py (path:/tmp/pip-ZUUzcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:20:19 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)) 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:20:19 INFO - Running setup.py (path:/tmp/pip-ytLFoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:20:19 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)) 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:19 INFO - Running setup.py (path:/tmp/pip-kAp6em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:19 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)) 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:20:19 INFO - Running setup.py (path:/tmp/pip-i01rNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:20:19 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)) 18:20:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:20:19 INFO - Running setup.py (path:/tmp/pip-EUyK8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:20:19 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)) 18:20:19 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)) 18:20:19 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)) 18:20:19 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)) 18:20:19 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)) 18:20:19 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)) 18:20:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:20 INFO - Downloading blessings-1.6.tar.gz 18:20:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:20:20 INFO - Installing collected packages: blessings 18:20:20 INFO - Running setup.py install for blessings 18:20:21 INFO - Successfully installed blessings 18:20:21 INFO - Cleaning up... 18:20:21 INFO - Return code: 0 18:20:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:20:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:20:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:20:21 INFO - Reading from file tmpfile_stdout 18:20:21 INFO - Current package versions: 18:20:21 INFO - argparse == 1.2.1 18:20:21 INFO - blessings == 1.6 18:20:21 INFO - blobuploader == 1.2.4 18:20:21 INFO - docopt == 0.6.1 18:20:21 INFO - functools32 == 3.2.3-2 18:20:21 INFO - jsonschema == 2.5.1 18:20:21 INFO - manifestparser == 1.1 18:20:21 INFO - mozInstall == 1.12 18:20:21 INFO - mozcrash == 1.0 18:20:21 INFO - mozdebug == 0.1 18:20:21 INFO - mozdevice == 0.51 18:20:21 INFO - mozfile == 1.2 18:20:21 INFO - mozhttpd == 0.7 18:20:21 INFO - mozinfo == 0.9 18:20:21 INFO - mozleak == 0.1 18:20:21 INFO - mozlog == 3.4 18:20:21 INFO - moznetwork == 0.27 18:20:21 INFO - mozprocess == 0.25 18:20:21 INFO - mozprofile == 0.28 18:20:21 INFO - mozrunner == 6.13 18:20:21 INFO - mozscreenshot == 0.1 18:20:21 INFO - mozsystemmonitor == 0.3 18:20:21 INFO - moztest == 0.8 18:20:21 INFO - mozversion == 1.4 18:20:21 INFO - psutil == 3.1.1 18:20:21 INFO - requests == 1.2.3 18:20:21 INFO - wsgiref == 0.1.2 18:20:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:21 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')]} 18:20:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:21 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')]} 18:20:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:21 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 0x24f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26bda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28635c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28607a0>, '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 0x28616a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2861b30>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', '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 18:20:21 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 18:20:21 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 18:20:21 INFO - Using env: {'DISPLAY': ':0', 18:20:21 INFO - 'HOME': '/home/cltbld', 18:20:21 INFO - 'LANG': 'en_US.UTF-8', 18:20:21 INFO - 'LANGUAGE': 'en_US:en', 18:20:21 INFO - 'LOGNAME': 'cltbld', 18:20:21 INFO - 'MAIL': '/var/mail/cltbld', 18:20:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:21 INFO - 'MOZ_NO_REMOTE': '1', 18:20:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:21 INFO - 'NO_EM_RESTART': '1', 18:20:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:21 INFO - 'PWD': '/builds/slave/test', 18:20:21 INFO - 'SHELL': '/bin/bash', 18:20:21 INFO - 'SHLVL': '1', 18:20:21 INFO - 'TERM': 'linux', 18:20:21 INFO - 'TMOUT': '86400', 18:20:21 INFO - 'USER': 'cltbld', 18:20:21 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', 18:20:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:21 INFO - '_': '/tools/buildbot/bin/python'} 18:20:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:21 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)) 18:20:21 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)) 18:20:21 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)) 18:20:21 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)) 18:20:21 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)) 18:20:21 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)) 18:20:21 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)) 18:20:21 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)) 18:20:21 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)) 18:20:21 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:20:21 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 18:20:21 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 18:20:21 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 18:20:21 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 18:20:22 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:20:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:20:22 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)) 18:20:22 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)) 18:20:22 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)) 18:20:22 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)) 18:20:22 INFO - Installing collected packages: marionette-driver 18:20:22 INFO - Running setup.py install for marionette-driver 18:20:22 INFO - Successfully installed marionette-driver 18:20:22 INFO - Cleaning up... 18:20:22 INFO - Return code: 0 18:20:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:20:22 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')]} 18:20:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:22 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')]} 18:20:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:22 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 0x24f01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26bda80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28635c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28607a0>, '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 0x28616a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2861b30>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', '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 18:20:22 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 18:20:22 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 18:20:22 INFO - Using env: {'DISPLAY': ':0', 18:20:22 INFO - 'HOME': '/home/cltbld', 18:20:22 INFO - 'LANG': 'en_US.UTF-8', 18:20:22 INFO - 'LANGUAGE': 'en_US:en', 18:20:22 INFO - 'LOGNAME': 'cltbld', 18:20:22 INFO - 'MAIL': '/var/mail/cltbld', 18:20:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:22 INFO - 'MOZ_NO_REMOTE': '1', 18:20:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:22 INFO - 'NO_EM_RESTART': '1', 18:20:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:22 INFO - 'PWD': '/builds/slave/test', 18:20:22 INFO - 'SHELL': '/bin/bash', 18:20:22 INFO - 'SHLVL': '1', 18:20:22 INFO - 'TERM': 'linux', 18:20:22 INFO - 'TMOUT': '86400', 18:20:22 INFO - 'USER': 'cltbld', 18:20:22 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', 18:20:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:22 INFO - '_': '/tools/buildbot/bin/python'} 18:20:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:20:23 INFO - Cleaning up... 18:20:23 INFO - Return code: 0 18:20:23 INFO - Running post-action listener: _resource_record_post_action 18:20:23 INFO - Running post-action listener: _start_resource_monitoring 18:20:23 INFO - Starting resource monitoring. 18:20:23 INFO - [mozharness: 2017-05-25 01:20:23.153410Z] Finished create-virtualenv step (success) 18:20:23 INFO - [mozharness: 2017-05-25 01:20:23.154059Z] Running install step. 18:20:23 INFO - Running pre-action listener: _resource_record_pre_action 18:20:23 INFO - Running main action method: install 18:20:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:20:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:20:23 INFO - Reading from file tmpfile_stdout 18:20:23 INFO - Detecting whether we're running mozinstall >=1.0... 18:20:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:20:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:20:23 INFO - Reading from file tmpfile_stdout 18:20:23 INFO - Output received: 18:20:23 INFO - Usage: mozinstall [options] installer 18:20:23 INFO - Options: 18:20:23 INFO - -h, --help show this help message and exit 18:20:23 INFO - -d DEST, --destination=DEST 18:20:23 INFO - Directory to install application into. [default: 18:20:23 INFO - "/builds/slave/test"] 18:20:23 INFO - --app=APP Application being installed. [default: firefox] 18:20:23 INFO - mkdir: /builds/slave/test/build/application 18:20:23 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'] 18:20:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:20:38 INFO - Reading from file tmpfile_stdout 18:20:38 INFO - Output received: 18:20:38 INFO - /builds/slave/test/build/application/firefox/firefox 18:20:38 INFO - Running post-action listener: _resource_record_post_action 18:20:38 INFO - [mozharness: 2017-05-25 01:20:38.919619Z] Finished install step (success) 18:20:38 INFO - [mozharness: 2017-05-25 01:20:38.919960Z] Running run-tests step. 18:20:38 INFO - Running pre-action listener: _resource_record_pre_action 18:20:38 INFO - Running main action method: run_tests 18:20:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:20:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:20:38 INFO - Python 2.7.3 18:20:38 INFO - Return code: 0 18:20:38 INFO - grabbing minidump binary from tooltool 18:20:38 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')]} 18:20:38 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 0x28607a0>, '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 0x28616a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2861b30>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:20:38 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 18:20:38 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 18:20:38 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 18:20:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:20:38 INFO - Return code: 0 18:20:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:20:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:20:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:20:39 INFO - ENV: RUST_BACKTRACE is now 1 18:20:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:20:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:20:39 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/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 18:20:39 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/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 18:20:39 INFO - Using env: {'DISPLAY': ':0', 18:20:39 INFO - 'HOME': '/home/cltbld', 18:20:39 INFO - 'LANG': 'en_US.UTF-8', 18:20:39 INFO - 'LANGUAGE': 'en_US:en', 18:20:39 INFO - 'LOGNAME': 'cltbld', 18:20:39 INFO - 'MAIL': '/var/mail/cltbld', 18:20:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:20:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:39 INFO - 'MOZ_NO_REMOTE': '1', 18:20:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:20:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:20:39 INFO - 'NO_EM_RESTART': '1', 18:20:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:39 INFO - 'PWD': '/builds/slave/test', 18:20:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:20:39 INFO - 'RUST_BACKTRACE': '1', 18:20:39 INFO - 'SHELL': '/bin/bash', 18:20:39 INFO - 'SHLVL': '1', 18:20:39 INFO - 'TERM': 'linux', 18:20:39 INFO - 'TMOUT': '86400', 18:20:39 INFO - 'USER': 'cltbld', 18:20:39 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828', 18:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:39 INFO - '_': '/tools/buildbot/bin/python'} 18:20:39 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/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 18:20:39 INFO - mozversion application_buildid: 20170525004348 18:20:39 INFO - mozversion application_changeset: d10f5ccd882b965fcad39914f7c3c930d1301a41 18:20:39 INFO - mozversion application_display_name: Nightly 18:20:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:20:39 INFO - mozversion application_name: Firefox 18:20:39 INFO - mozversion application_remotingname: firefox 18:20:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:20:39 INFO - mozversion application_vendor: Mozilla 18:20:39 INFO - mozversion application_version: 55.0a1 18:20:39 INFO - mozversion platform_buildid: 20170525004348 18:20:39 INFO - mozversion platform_changeset: d10f5ccd882b965fcad39914f7c3c930d1301a41 18:20:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:20:39 INFO - mozversion platform_version: 55.0a1 18:20:39 INFO - using testdate: 1495675239 18:20:39 INFO - actual date: 1495675239 18:20:39 INFO - starting webserver on 'localhost:50827' 18:20:39 INFO - SUITE-START | Running 2 tests 18:20:39 INFO - TEST-START | dromaeo_css 18:20:39 INFO - operating with platform_type : linux_ 18:20:39 INFO - Initialising browser for dromaeo_css test... 18:20:39 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50827/getInfo.html -profile /tmp/tmpdmCb03/profile 18:20:39 INFO - TEST-INFO | started process 24815 (/builds/slave/test/build/application/firefox/firefox http://localhost:50827/getInfo.html) 18:21:13 INFO - TEST-INFO | 24815: killed by SIGTERM 18:21:13 INFO - Browser initialized. 18:21:13 INFO - Running cycle 1/1 for dromaeo_css test... 18:21:13 INFO - TEST-INFO | started process 25009 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdmCb03/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:25 INFO - PID 25009 | RSS: Main: 172527616 18:21:25 INFO - PID 25009 | 18:24:30 INFO - PID 25009 | Cycle 1(1): loaded http://localhost:50827/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50827/tests/dromaeo/cssquery-ext.html) 18:24:32 INFO - PID 25009 | RSS: Main: 170491904 18:24:32 INFO - PID 25009 | 18:27:34 INFO - PID 25009 | Cycle 1(1): loaded http://localhost:50827/tests/dromaeo/cssquery-ext.html (next: http://localhost:50827/tests/dromaeo/cssquery-jquery.html) 18:27:34 INFO - PID 25009 | RSS: Main: 175423488 18:27:34 INFO - PID 25009 | 18:30:36 INFO - PID 25009 | Cycle 1(1): loaded http://localhost:50827/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50827/tests/dromaeo/cssquery-mootools.html) 18:30:37 INFO - PID 25009 | RSS: Main: 174252032 18:30:37 INFO - PID 25009 | 18:33:38 INFO - PID 25009 | Cycle 1(1): loaded http://localhost:50827/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50827/tests/dromaeo/cssquery-prototype.html) 18:33:39 INFO - PID 25009 | RSS: Main: 178655232 18:33:39 INFO - PID 25009 | 18:36:41 INFO - PID 25009 | Cycle 1(1): loaded http://localhost:50827/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50827/tests/dromaeo/cssquery-yui.html) 18:36:42 INFO - PID 25009 | RSS: Main: 178532352 18:36:42 INFO - PID 25009 | 18:39:44 INFO - PID 25009 | Cycle 1(1): loaded http://localhost:50827/tests/dromaeo/cssquery-yui.html (next: http://localhost:50827/tests/dromaeo/cssquery-dojo.html) 18:39:44 INFO - PID 25009 | RSS: Main: 174247936 18:39:44 INFO - PID 25009 | 18:39:44 INFO - PID 25009 | __start_tp_report 18:39:44 INFO - PID 25009 | _x_x_mozilla_page_load 18:39:44 INFO - PID 25009 | _x_x_mozilla_page_load_details 18:39:44 INFO - PID 25009 | |i|pagename|runs| 18:39:44 INFO - PID 25009 | |0;dojo.html;2727.4954133485307;2737.1241202815095;2737.7399147081046;2772.1822062491556;2775.5004099262123;5103.974480127594;5223.3209682741235;5234.5027222413855;5267.051930652481;5284.233786101013;5255.605829562779;5262.394824595172;5264.973675131619;5321.308229930113;5338.893222135555;4663.323818046373;4673.6027437667835;4742.264948932903;4770.189067858455;4780.976095119519;4590.2196626573395;4724.929126063112;4739.241721324589;4762.547557981986;4791.640626952975;4592.885177870547;4691.906161876744;4747.145513807513;4756;4782.163121453749;4617.376654151713;4687.554682305175;4694.248920172756;4722.197226471508;4731.077439899221;4619;4630.86107416778;4642.303654451859;4666.626669866402;4684.110019096361;5165.302684137631;5169.9744938625845;5179.766910489037;5189.40321862983;5197.038547868657;5609.186667933168;5660.094384898438;5675.829725108253;5688.345840228385;5743;5617.971910140464;5674.290713660751;5687.118496633028;5725.942740572583;5728.97135514324;5241.344831896013;5246.947530524646;5261.13191323435;5273.815416460406;5323.254744335796;5700.287464066992;5779.739911703983;5781.19063331134;5811.79658711943;5816.127580862904;5567.220589117527;5570.248016517799;5638;5652.463016013472;5658.122990936411;5673.8297851064535;5697.401772813795;5708.743106560215;5709.486146246774;5713.942860571341;5548.167774833807;5652.491275785199;5665.773369065191;5668.149777533321;5668.773249070074;5542.334919809648;5579.46995035471;5612.1581762735095;5618.4662457066515;5627.493525582717;4801.399825021872;4804.159272127364;4805;4812.494688057774;4847.903041939141;4090.406891000788;4091.57038510958;4107.178564287155;4142.06803469217;4154.335306350969;4714.5521175488275;4790.616750659939;4791.904161916742;4809.422869255712;4815.927761083586;5585.888282234414;5675.687837168913;5688;5689.203511508392;5743.4256574342235;4815.566599006036;4815.975920120377;4830.830920917751;4839.903201936012;4858.271259311132;5108.591312694975;5109.56568691658;5110.667806592485;5117.283580298761;5124.5131712487255;5135.204043373134;5185.896282074413;5194.091034069098;5235;5261.079311120462;5568.248286481273;5769;5771.249737534262;5793.826185214451;5840.737166827588;5212.713300768494;5224.843254702365;5226.242194881797;5251.474852514871;5343.5457986071615;5546.528545073714;5640.379558248672;5646.858828529155;5665.3201615806365;5698.088305871041;5552.361478430033;5766.3080430346945;5769;5769.1634712967225;5783.277090363705;5493.176023596333;5526.088195447706;5533.916991245054;5561.165825126263;5569.944300556943;5491.478309560585;5493;5496.258005169411;5496.697681627549;5511;5369.704666243394;5418.918716219181;5421.05131601976;5421.105517589711;5462.754176062166;5498.092814685692;5574.16387541872;5580.720963951705;5582.218489411567;5670.234518339971;5400.324959380077;5404.83785486436;5426.945730542644;5440.319960004999;5447.972760136174 18:39:44 INFO - PID 25009 | |1;ext.html;661.9900701489479;715.3847690985751;736.4623824608036;1062.2829590026736;1064.4677661169414;29598.260043498896;30215.03602265854;30280.697193028063;31963.36073278533;32031.51952720711;34888;35198.82400588;35228.647713522856;35242;35342.64657353426;6717.8992315115065;6834.111565496503;6845.383915447609;6845.623490708009;6966.16406031277;6770.322967703215;6810.795676129699;6850.246472887979;6867.175938887341;6868.828279293008;6820.761273355433;6832.248452670202;6851.623160726158;6858.485613578977;6867.381935625793;6833.350831670983;6858.759943402007;6859.725610975555;6861.039454476378;6874.931250687529;6764.188297404294;6769.458443324522;6771.762988295386;6809.148856392951;6820.079289295931;31992.520112198337;32019.679803201903;32138;32280.35439291201;32426.189345266557;2966.110166949928;3018.864151113183;3021.4863473209607;3022.2746540830262;3025.9394812103633;24082;24211.878940605184;24328.878355608285;24346.513069738685;24498.142565010137;2796.4546913351905;2801.453716525258;2809.0730059080456;2810.339570201001;2828.505011622978;142569.28715356358;143800.28099859433;143828;148067;152974;37993.24013519714;38049.619503804606;38247.80876095658;38282.61717382846;38459;39703.40444893329;39886.401703974465;40018.59981400207;40086.39870401946;43387.56612433835;12421.31682757457;12788.872111278954;12809.487620495262;12855.485780568673;12900.225986440844;10322;10383.896161038445;10411.687649370531;10480.213983951235;10483.790324193626;12394.008479321632;12469.75060498785;12535.498580056696;12652.430640621194;12684.556040538537;5358.437364076716;5397.8110766123;5418.45815418455;5452.127659574449;5462.617616766867;851.1275942159212;883.1874675298741;894.5840184314325;902.0753727429437;917.5824999625155;18628.068596570116;18742.25030998745;19117.808821911603;19858.60282794365;25423.348156133332;948.7343543807744;950.2350607760819;972.6790159247573;974.2790335151936;984.17329443268;11084.334939903632;11188.328700278009;11340.376279304552;11404.543818247177;11565.537378504934;157105.2144739269;159224;159763.20118399334;160302.1984890068;168972;43475.56524434715;44342.11315773666;44499;44630;44678.55321446809;1201.130127361214;1225.4791713521895;1229.9508019679292;1232.1744431231098;1232.6302109367155;24035.27894163178;24065.278041658778;25287.620685689362;25469.23592292234;25673.743262567135;29562.556561651163;33278;33671.3265734679;33672.663273367936;33813.3237335247;154133;155094.22452887663;155731;155949;156177;29603.259918502208;29644.851775740983;29698.554521682625;30092.849535752182;30101.247468813453;34814.12964675904;35491.645083548836;35744.82127589345;35760.28479430449;35991.460128098624;26341.34146646349;26596;26970.32574185661;27422.314442139104;27478.29043079159;26771.19686409411;27338.863305683346;27839.58240626352;27856.721432786224;28031.579526306712;31223.687763122078;31230;31324.216894577818;31327;31355.686443135277;26461.60307595349;26830.86584567143;27263.86368068147;27823.44353112967;28174.71825281803;21100.788992110498;22053.33839984803;22129;22140.889295553417;22368.328950131523 18:39:44 INFO - PID 25009 | |2;jquery.html;408.3262616682472;411.2145801519099;416.98749037528876;421.51736261980034;440.0516886110433;14054;15340.079595224266;16813.747793783088;17486.475405737816;17500;18682.719759203625;18954.24183032677;18976.525586860334;19155.90422047891;19187.61624767504;5313.601479889005;5400.216968539559;5559.666420014792;5586.329640443152;5612.326520817487;5372.758225879826;5537.197106419567;5539.141433077859;5544.889102217953;5549.056660367747;5086.465921078289;5324.946750532484;5330.786768529274;5358.758855851476;5509.944900550983;5224.164133738603;5229.477052294778;5231.24146998683;5231.895362092775;5259.053370393328;4770.212914869043;4790.305405716186;4939.185034469308;4951.653384263103;4975.4527002029745;21386.358409247747;21650;21756.564868702695;21817.781822181732;21842.907854607205;1480.9333579988818;1610.8630766384872;1618.8866779325451;1622.5132460261875;1626.9023858568405;13858.514951976733;14034.508792192326;14044.859551404457;14242.216678082701;14293.714125717532;2000.7799142094505;2003.8096380843797;2051.128270485053;2057.886816225107;2074.1806986240504;87416.12583874143;91600.08399915982;92022;92689.53655231748;93728;10589.84115238272;10884.455777211107;10913.563457461612;11191.32852028881;11261.887381126084;14858.628534286729;15239.923800380928;15341;15370.308336124901;15436;7352.816179595446;7404.740834070782;7440.4791664584045;7525.172231054579;7641.69433222676;6815.114035175495;6828.054004608663;6972.511924165361;7003.509754317149;7025.297470252934;6828.317168283132;6841.4344620767215;6867.828304292432;6872.484563657746;6932.376086152271;2025.9898700506699;2064.6386882295837;2069.5240094778287;2091.6025955068794;2238.160689741347;834.4200780457523;864.6066039951806;868.5613765048736;879.3404946290283;892.790194304338;16535.917320413562;16596.336146554004;16811.579710507336;17353.132343383033;17445.563860903578;839.6473481137815;858.4677499949946;861.5003298087095;874.5758307220992;879.0769691823688;9092.499912504876;9300.581473833694;9494.287928405398;9549.474778887228;9619.374740641835;351939;353167;355279;358727;360961;72475.2752472479;72496;73686.26313736795;75311.62344188245;75392;997.7306127345576;1008.5663164839043;1024.6823484719762;1032.767627283855;1036.7356324137297;13864.653383665489;14191.787123192953;14291;14386.856131438552;14667.48663796762;36126.458103127945;36233.27533449369;36518;37290.254194916495;37775.81112094422;44953.77523112494;45119.77440112778;45590.54409455863;46460.30309545292;47044.29433558431;7905.960470197612;8005.719799806979;8079.272865442138;8119.350451963947;8229.506229626242;11460.140489463156;11738.765224695288;11921.165518413627;11972.940135299268;12026.879731202576;10304.536295866854;10312.329698569569;10349.844752328872;10440.53017614194;10563.894361056291;9381.155695987121;9485.146336829719;9614.134727874525;9669.854952175583;9850.162736167505;10056.748581285234;10265.178785697275;10537.683869483928;10689.412082335235;11100.11199104086;7816.648250828613;8056.879146812686;8060.919390806017;8159.673613055411;8219.794505137419;10371.533281002223;10445.9477702611;10818.837717434088;10892.891071089187;10913.727156821144 18:39:44 INFO - PID 25009 | |3;mootools.html;1286.8777466140716;1301.2517802263694;1332.6934804994858;1334.7263810918762;1338.1168428836966;10122.797544049115;10168.237382196328;10507.894921050787;10629.52167152478;10640.308379955297;10451.268411211218;10542;10659.307145035567;10673;10697.19771017173;2175.119076773909;2222.0112050137695;2227.7326720793526;2233.36349140495;2233.888305584714;2159.7948194921455;2164.7618761936255;2188.070070220158;2195.034184958613;2195.1768086967386;2152.6448136057534;2174.4781252499442;2176.107795803721;2188.6826410170515;2192.9122835086578;2133.8612990155666;2142.046789178816;2154.407537927075;2155.0194661429014;2162.643163877958;2092.7697953225133;2097.98951005244;2182.3998400439846;2205.757366689663;2239.2162743039808;10548.050675439248;10583.735406614896;10636.46817659114;10688.946555267252;10708.25042247043;5397.27136836526;5546.500814926676;5555.916661250085;5578.609497335188;5628.662280263156;2888.6966868478717;2984.910452686423;3019.0942717184757;3025.319303156794;3079.8306093164865;879.7316818370394;885.4642941020654;903.0292435631723;916.6516723644972;935.8596210568401;15582.922085389613;15677.216139192995;15718.21408929549;15740;15761.921190394089;4525.321201819687;4569.611583015481;4623.595730220267;4748.525147485224;4770.356001939763;11178.44107794607;11265.7607663157;11276.1542884284;11315.377654228932;11322.660320190407;5424.105022671294;5515.283013208257;5531.557475401958;5534.833954981357;5548;5054.519820617035;5119.436861945183;5140.717260550631;5155.484451554815;5168.3797944246935;3879.786611736325;3895.3962135868987;3911.1786524830104;3984.940225896614;4028.697847661437;1084.4794498640697;1085.234909388879;1100.6753007862699;1108.7228192951761;1114.7714718482691;684.1072400517273;707.2785758526275;711.2460791560962;716.7527203114917;722.9710811567584;5279.894402111936;5388;5390.598444404404;5422.40353561108;5520.723963801794;530.737285043898;533.2587703092704;536.8550491367387;539.2962184349383;540.1007322807482;3507.5790905091044;3603.531540899666;3619.5656521217625;3672.192117734094;3806.8477260909253;13482.460701572023;14164.220967846868;14205.78691319631;14207.78688319676;14217.289135543182;13654.385552650154;13879.722405551833;14059.156450612996;14264.857351426353;14454.927725361516;7477.850442991218;7584;7589.43079269046;7602.771916842504;7613.08642962848;5327.840164795062;5412.134058550613;5598.1322894951345;5731.054376027909;5755.309362876482;12037.638870833538;12084.154109212272;12135.332556709465;12205.877941220475;12346;12569.622911312676;13058.281794501394;13115.606531804062;13308.134971226838;13321.933390332986;7781.105172904962;7936;8039.758807235792;8045.034595848536;8084.110747817617;8479.745607631781;9038.593263303035;9130.223930965942;9176.541172941194;9208.723738287863;7236.963815181101;7302.671379788028;7314.341709246193;7320.707171713072;7324.230955749569;7607.239276072349;7659.655315510704;7660.961695191489;7780.338671212784;7815.491993020435;6884.4148247399535;7003.089598352181;7003.54476958996;7008.614526201108;7016.438684905093;4719.5752382285755;5163.225516172604;5165.9483405165465;5170.302009228856;5392.056390131633;5404.648697834628;5424.942136283387;5438.483344082306;5485.533729633026;5576.052071147834 18:39:44 INFO - PID 25009 | |4;prototype.html;331.0911547801286;342.0081762887626;352.1091638155466;352.977056491328;353.22819639088607;5376.274202982596;5534.584906132041;5541.362743284523;5551.611387202897;5572.832815015551;5288.603354748399;5367.973160134194;5372.435894231098;5395.865103372417;5409;4003.8598649047144;4005.1589166275116;4006.198760247962;4007.3788562772816;4018.899527511821;3854.169269596947;3935.685145188392;3999.740016898907;4010.939835902464;4026.4161696553983;3931.095847954972;3943.8225279862477;3957.940630890539;3970.3051965905997;3977.2244412339605;3918.902027449308;3930.980345098285;3937.1731936293413;3953.2093581283857;3962.960370396288;3412.3004784019463;3416.974907527732;3420.0765793235937;3430.2967891582166;3435.7766745161484;5218.510793305445;5230.110881150214;5284.339457567804;5314.927178400427;5316.494932981399;4680.812767489297;4697.271922851964;4704.176769065434;4709.340692303081;4713.740744259065;5558.944410555923;5649.180868774046;5663.122216056477;5723.456271654186;5724.847637126578;3808.333541630231;3809.3904975203836;3810.714196435251;3822.9617703822883;3831.616838316146;5828.805094955524;5992.273515550414;6051.515878729735;6093.421124993163;6117.265928088658;3896.79199448172;3919.451276821247;3926.7251292409255;3929.44987701722;3935.252302062599;3956.248312820555;3979.403089536535;3989.1024519483462;3991.900202494961;3996.8800935971967;4539.319102134707;4678.672492925531;4681.81272749093;4682.765861706969;4684.180268453007;5645.887082258414;5715.742791574389;5729;5742.913856292159;5749.482546570745;1351.6418149190472;1356.5387768158873;1359.6261028217123;1360.693843885118;1362.9659258518616;865.6234537976001;898.1229230907435;904.9592768325441;913.712180663089;913.981720365589;4942.912559236962;4943.492234868328;4954.777035033432;4964.776585053682;4978.228374601942;5799.623024503394;5859.2090067841145;5911.9113213301835;5913.172155898178;5926.170336152942;4869.123557759566;4925.21196608541;4952.529509696573;4975.378077740283;4986.551210391082;3132.9034837806585;3144.3554071415306;3145.2451411661036;3162.414953233659;3170.651228364878;5478;5479.123340265538;5480.835574932678;5501.504864562208;5516.531094856902;5907.734151963172;6045.33501314855;6047.183630209865;6084.60450070744;6084.908726369108;5452.836414907559;5472;5483.396826349099;5486.807961721321;5516.14499752539;5711.571632127524;5846.269216347957;5882.735276912464;5890.793822216202;5893.6463812171405;5838.12428135783;5841.649501029951;5847.415258474289;5864.149698293809;5868.684193430698;5302.992431438014;5433.972830135824;5445.455454454523;5450.890982180414;5452.427495113115;3882.4370466281666;3884.18432129256;3886.1839013806284;3886.5336159661024;3892.6107389260847;5852.151438041546;5909.793157239476;5957.4042595740075;5980.312264089685;5981.431763982415;5694.515966142924;5717.313922329181;5729.255196824386;5739.368669446441;5755.769769209185;5709.8287051388525;5716.199732037435;5757.898367095896;5761;5766.538676905921;5562.332520097614;5651.886962260814;5663.253322138842;5666.21514223035;5667.773289068391;5788.681622510802;5792.1482214327;5793.623414478046;5795.478406943395;5818.94181058201;5541.972290138685;5590.329160500766;5629.802956896489;5630.60585758993;5633.985882541182 18:39:44 INFO - PID 25009 | |5;yui.html;670.7048898484669;687.6905392573343;723.5080145501058;724.0334153904537;730.455810421236;13140.145890517122;13982.440702371905;14014.859851401483;15919.92040039801;16466.588335291624;16110.677786444265;16276.918615406907;16317.510474685778;16372.83627163728;16464.83535164648;894.5616647842543;899.1952202778509;899.6131663384742;900.3922352412096;902.8149229407956;822.983540329193;855.0978717453085;857.6355049104137;861.2593169873935;900.8648702694616;904.023654453189;914.3005600715425;916.9358144929857;920.1258804136097;930.8557173638097;904.1049361132465;906.7687739626354;909.9044600316971;913.2785099771173;933.2673851026946;670.9530332876701;672.4183581202274;675.0144788608637;676.347324831535;683.4873844616537;13624;13924.094933829268;13965.371558279901;14007.579772606838;14024.929875350557;14110.647233819134;14310.64223394413;14412.78380824278;14495.275236238145;14569;11262;11291.943540282327;11345.886541134565;11484.36835974021;11592.420378981102;2601.9089331873292;2618.56793629051;2622.1740151852105;2626.0808716948914;2629.526685196664;68739.78232285638;69796.30203697897;70757.2924270761;71158.64420677915;77811;10758.386242063752;11559.30644161353;11584.942075289739;11639.185257031926;11643.941780291214;21520.56958860814;21613.75793630968;21777.67333489967;22129;22710.886445567998;5659.858503537444;5797.072468405035;5807.883842323215;5836.037053886062;5837.6497410155525;2894.493463643854;2936.8237905725723;2939.8824047038306;2952.099609619087;2957.290250339946;3542.3977923753023;3600.765950213228;3717.4795528626014;3792.4311353297226;3830.3871380578976;710.0343532795395;711.6086152616041;714.1608609883366;719.1585844561876;723.0528008309132;561.9940306850691;566.7817890112336;572.7250919558635;577.2121054760222;578.1068249554448;9757.658481953098;9855.901440985499;10029.686915304977;10263.692089237335;10391.109253897697;562.9155626655952;565.6097292867908;566.07164250629;566.498648695907;566.9681180251902;4055.7566546007333;4117.5882411759185;4119.279126152911;4337.6963612546815;4555.612772914273;71505;72289;73674;73948;75625.62187189028;62092.689536552934;63845.565711030824;64640;65669.67165164143;67368.66315668488;57467.42532574704;58686.11970820441;59750;60768.69615651982;61379.38620613826;9873.272177369076;10057.849132262876;10358.430286334324;10425.165986720893;10525.36847789135;26715.599266010635;26756.063537776085;27090.864545677145;27188.728112718618;27301.180964571096;34886.30227395488;35011.29977400489;35374.29251415009;35689.82155089208;35736;13267.402966866708;13315.600531984055;13351.465941362625;13356.198628082346;13500.25748583798;15178;15357;15414.229288535304;15643.374265029272;15831.525054248392;12507.874921251036;13648.590542283748;13655.317234138054;13739.862601373858;13767.311634418038;12520;13080.672983175496;13392.665183370495;13436.39536220892;13551.457941682222;13733;13813.102148360324;14129.293535323399;14135.858641413866;14347.56957291283;9816.509174541387;9828.11546960777;10051.447170405405;10089.679373743933;10685.465726713788;11113.611023614134;11741.236819606698;11813.173077884465;11832.290062596272;11991.400429978292 18:39:44 INFO - PID 25009 | __end_tp_report 18:39:44 INFO - PID 25009 | __start_cc_report 18:39:44 INFO - PID 25009 | _x_x_mozilla_cycle_collect,341 18:39:44 INFO - PID 25009 | __end_cc_report 18:39:44 INFO - PID 25009 | __startTimestamp1495676384574__endTimestamp 18:39:44 INFO - PID 25009 | 18:39:44 INFO - PID 25009 | ------- Summary: start ------- 18:39:44 INFO - PID 25009 | Number of tests: 6 18:39:44 INFO - PID 25009 | 18:39:44 INFO - PID 25009 | [#0] dojo.html Cycles:165 Average:5190.10 Median:5323.25 stddev:604.54 (11.4%) stddev-sans-first:574.70 18:39:44 INFO - PID 25009 | Values: 2727.5 2737.1 2737.7 2772.2 2775.5 5104.0 5223.3 5234.5 5267.1 5284.2 5255.6 5262.4 5265.0 5321.3 5338.9 4663.3 4673.6 4742.3 4770.2 4781.0 4590.2 4724.9 4739.2 4762.5 4791.6 4592.9 4691.9 4747.1 4756.0 4782.2 4617.4 4687.6 4694.2 4722.2 4731.1 4619.0 4630.9 4642.3 4666.6 4684.1 5165.3 5170.0 5179.8 5189.4 5197.0 5609.2 5660.1 5675.8 5688.3 5743.0 5618.0 5674.3 5687.1 5725.9 5729.0 5241.3 5246.9 5261.1 5273.8 5323.3 5700.3 5779.7 5781.2 5811.8 5816.1 5567.2 5570.2 5638.0 5652.5 5658.1 5673.8 5697.4 5708.7 5709.5 5713.9 5548.2 5652.5 5665.8 5668.1 5668.8 5542.3 5579.5 5612.2 5618.5 5627.5 4801.4 4804.2 4805.0 4812.5 4847.9 4090.4 4091.6 4107.2 4142.1 4154.3 4714.6 4790.6 4791.9 4809.4 4815.9 5585.9 5675.7 5688.0 5689.2 5743.4 4815.6 4816.0 4830.8 4839.9 4858.3 5108.6 5109.6 5110.7 5117.3 5124.5 5135.2 5185.9 5194.1 5235.0 5261.1 5568.2 5769.0 5771.2 5793.8 5840.7 5212.7 5224.8 5226.2 5251.5 5343.5 5546.5 5640.4 5646.9 5665.3 5698.1 5552.4 5766.3 5769.0 5769.2 5783.3 5493.2 5526.1 5533.9 5561.2 5569.9 5491.5 5493.0 5496.3 5496.7 5511.0 5369.7 5418.9 5421.1 5421.1 5462.8 5498.1 5574.2 5580.7 5582.2 5670.2 5400.3 5404.8 5426.9 5440.3 5448.0 18:39:44 INFO - PID 25009 | 18:39:44 INFO - PID 25009 | [#1] ext.html Cycles:180 Average:30090.42 Median:3020.18 stddev:39770.35 (1316.8%) stddev-sans-first:39820.52 18:39:44 INFO - PID 25009 | Values: 662.0 715.4 736.5 1062.3 1064.5 29598.3 30215.0 30280.7 31963.4 32031.5 34888.0 35198.8 35228.6 35242.0 35342.6 6717.9 6834.1 6845.4 6845.6 6966.2 6770.3 6810.8 6850.2 6867.2 6868.8 6820.8 6832.2 6851.6 6858.5 6867.4 6833.4 6858.8 6859.7 6861.0 6874.9 6764.2 6769.5 6771.8 6809.1 6820.1 31992.5 32019.7 32138.0 32280.4 32426.2 2966.1 3018.9 3021.5 3022.3 3025.9 24082.0 24211.9 24328.9 24346.5 24498.1 2796.5 2801.5 2809.1 2810.3 2828.5 142569.3 143800.3 143828.0 148067.0 152974.0 37993.2 38049.6 38247.8 38282.6 38459.0 39703.4 39886.4 40018.6 40086.4 43387.6 12421.3 12788.9 12809.5 12855.5 12900.2 10322.0 10383.9 10411.7 10480.2 10483.8 12394.0 12469.8 12535.5 12652.4 12684.6 5358.4 5397.8 5418.5 5452.1 5462.6 851.1 883.2 894.6 902.1 917.6 18628.1 18742.3 19117.8 19858.6 25423.3 948.7 950.2 972.7 974.3 984.2 11084.3 11188.3 11340.4 11404.5 11565.5 157105.2 159224.0 159763.2 160302.2 168972.0 43475.6 44342.1 44499.0 44630.0 44678.6 1201.1 1225.5 1230.0 1232.2 1232.6 24035.3 24065.3 25287.6 25469.2 25673.7 29562.6 33278.0 33671.3 33672.7 33813.3 154133.0 155094.2 155731.0 155949.0 156177.0 29603.3 29644.9 29698.6 30092.8 30101.2 34814.1 35491.6 35744.8 35760.3 35991.5 26341.3 26596.0 26970.3 27422.3 27478.3 26771.2 27338.9 27839.6 27856.7 28031.6 31223.7 31230.0 31324.2 31327.0 31355.7 26461.6 26830.9 27263.9 27823.4 28174.7 21100.8 22053.3 22129.0 22140.9 22368.3 18:39:44 INFO - PID 25009 | 18:39:44 INFO - PID 25009 | [#2] jquery.html Cycles:180 Average:24101.53 Median:414.10 stddev:59411.49 (14347.1%) stddev-sans-first:59551.52 18:39:44 INFO - PID 25009 | Values: 408.3 411.2 417.0 421.5 440.1 14054.0 15340.1 16813.7 17486.5 17500.0 18682.7 18954.2 18976.5 19155.9 19187.6 5313.6 5400.2 5559.7 5586.3 5612.3 5372.8 5537.2 5539.1 5544.9 5549.1 5086.5 5324.9 5330.8 5358.8 5509.9 5224.2 5229.5 5231.2 5231.9 5259.1 4770.2 4790.3 4939.2 4951.7 4975.5 21386.4 21650.0 21756.6 21817.8 21842.9 1480.9 1610.9 1618.9 1622.5 1626.9 13858.5 14034.5 14044.9 14242.2 14293.7 2000.8 2003.8 2051.1 2057.9 2074.2 87416.1 91600.1 92022.0 92689.5 93728.0 10589.8 10884.5 10913.6 11191.3 11261.9 14858.6 15239.9 15341.0 15370.3 15436.0 7352.8 7404.7 7440.5 7525.2 7641.7 6815.1 6828.1 6972.5 7003.5 7025.3 6828.3 6841.4 6867.8 6872.5 6932.4 2026.0 2064.6 2069.5 2091.6 2238.2 834.4 864.6 868.6 879.3 892.8 16535.9 16596.3 16811.6 17353.1 17445.6 839.6 858.5 861.5 874.6 879.1 9092.5 9300.6 9494.3 9549.5 9619.4 351939.0 353167.0 355279.0 358727.0 360961.0 72475.3 72496.0 73686.3 75311.6 75392.0 997.7 1008.6 1024.7 1032.8 1036.7 13864.7 14191.8 14291.0 14386.9 14667.5 36126.5 36233.3 36518.0 37290.3 37775.8 44953.8 45119.8 45590.5 46460.3 47044.3 7906.0 8005.7 8079.3 8119.4 8229.5 11460.1 11738.8 11921.2 11972.9 12026.9 10304.5 10312.3 10349.8 10440.5 10563.9 9381.2 9485.1 9614.1 9669.9 9850.2 10056.7 10265.2 10537.7 10689.4 11100.1 7816.6 8056.9 8060.9 8159.7 8219.8 10371.5 10445.9 10818.8 10892.9 10913.7 18:39:44 INFO - PID 25009 | 18:39:44 INFO - PID 25009 | [#3] mootools.html Cycles:180 Average:6303.79 Median:3887.59 stddev:4249.00 (109.3%) stddev-sans-first:4244.20 18:39:44 INFO - PID 25009 | Values: 1286.9 1301.3 1332.7 1334.7 1338.1 10122.8 10168.2 10507.9 10629.5 10640.3 10451.3 10542.0 10659.3 10673.0 10697.2 2175.1 2222.0 2227.7 2233.4 2233.9 2159.8 2164.8 2188.1 2195.0 2195.2 2152.6 2174.5 2176.1 2188.7 2192.9 2133.9 2142.0 2154.4 2155.0 2162.6 2092.8 2098.0 2182.4 2205.8 2239.2 10548.1 10583.7 10636.5 10688.9 10708.3 5397.3 5546.5 5555.9 5578.6 5628.7 2888.7 2984.9 3019.1 3025.3 3079.8 879.7 885.5 903.0 916.7 935.9 15582.9 15677.2 15718.2 15740.0 15761.9 4525.3 4569.6 4623.6 4748.5 4770.4 11178.4 11265.8 11276.2 11315.4 11322.7 5424.1 5515.3 5531.6 5534.8 5548.0 5054.5 5119.4 5140.7 5155.5 5168.4 3879.8 3895.4 3911.2 3984.9 4028.7 1084.5 1085.2 1100.7 1108.7 1114.8 684.1 707.3 711.2 716.8 723.0 5279.9 5388.0 5390.6 5422.4 5520.7 530.7 533.3 536.9 539.3 540.1 3507.6 3603.5 3619.6 3672.2 3806.8 13482.5 14164.2 14205.8 14207.8 14217.3 13654.4 13879.7 14059.2 14264.9 14454.9 7477.9 7584.0 7589.4 7602.8 7613.1 5327.8 5412.1 5598.1 5731.1 5755.3 12037.6 12084.2 12135.3 12205.9 12346.0 12569.6 13058.3 13115.6 13308.1 13321.9 7781.1 7936.0 8039.8 8045.0 8084.1 8479.7 9038.6 9130.2 9176.5 9208.7 7237.0 7302.7 7314.3 7320.7 7324.2 7607.2 7659.7 7661.0 7780.3 7815.5 6884.4 7003.1 7003.5 7008.6 7016.4 4719.6 5163.2 5165.9 5170.3 5392.1 5404.6 5424.9 5438.5 5485.5 5576.1 18:39:44 INFO - PID 25009 | 18:39:44 INFO - PID 25009 | [#4] prototype.html Cycles:180 Average:4664.32 Median:5402.43 stddev:1425.32 (26.4%) stddev-sans-first:1391.71 18:39:44 INFO - PID 25009 | Values: 331.1 342.0 352.1 353.0 353.2 5376.3 5534.6 5541.4 5551.6 5572.8 5288.6 5368.0 5372.4 5395.9 5409.0 4003.9 4005.2 4006.2 4007.4 4018.9 3854.2 3935.7 3999.7 4010.9 4026.4 3931.1 3943.8 3957.9 3970.3 3977.2 3918.9 3931.0 3937.2 3953.2 3963.0 3412.3 3417.0 3420.1 3430.3 3435.8 5218.5 5230.1 5284.3 5314.9 5316.5 4680.8 4697.3 4704.2 4709.3 4713.7 5558.9 5649.2 5663.1 5723.5 5724.8 3808.3 3809.4 3810.7 3823.0 3831.6 5828.8 5992.3 6051.5 6093.4 6117.3 3896.8 3919.5 3926.7 3929.4 3935.3 3956.2 3979.4 3989.1 3991.9 3996.9 4539.3 4678.7 4681.8 4682.8 4684.2 5645.9 5715.7 5729.0 5742.9 5749.5 1351.6 1356.5 1359.6 1360.7 1363.0 865.6 898.1 905.0 913.7 914.0 4942.9 4943.5 4954.8 4964.8 4978.2 5799.6 5859.2 5911.9 5913.2 5926.2 4869.1 4925.2 4952.5 4975.4 4986.6 3132.9 3144.4 3145.2 3162.4 3170.7 5478.0 5479.1 5480.8 5501.5 5516.5 5907.7 6045.3 6047.2 6084.6 6084.9 5452.8 5472.0 5483.4 5486.8 5516.1 5711.6 5846.3 5882.7 5890.8 5893.6 5838.1 5841.6 5847.4 5864.1 5868.7 5303.0 5434.0 5445.5 5450.9 5452.4 3882.4 3884.2 3886.2 3886.5 3892.6 5852.2 5909.8 5957.4 5980.3 5981.4 5694.5 5717.3 5729.3 5739.4 5755.8 5709.8 5716.2 5757.9 5761.0 5766.5 5562.3 5651.9 5663.3 5666.2 5667.8 5788.7 5792.1 5793.6 5795.5 5818.9 5542.0 5590.3 5629.8 5630.6 5634.0 18:39:44 INFO - PID 25009 | 18:39:44 INFO - PID 25009 | [#5] yui.html Cycles:180 Average:15932.42 Median:34948.80 stddev:19952.58 (57.1%) stddev-sans-first:19975.64 18:39:44 INFO - PID 25009 | Values: 670.7 687.7 723.5 724.0 730.5 13140.1 13982.4 14014.9 15919.9 16466.6 16110.7 16276.9 16317.5 16372.8 16464.8 894.6 899.2 899.6 900.4 902.8 823.0 855.1 857.6 861.3 900.9 904.0 914.3 916.9 920.1 930.9 904.1 906.8 909.9 913.3 933.3 671.0 672.4 675.0 676.3 683.5 13624.0 13924.1 13965.4 14007.6 14024.9 14110.6 14310.6 14412.8 14495.3 14569.0 11262.0 11291.9 11345.9 11484.4 11592.4 2601.9 2618.6 2622.2 2626.1 2629.5 68739.8 69796.3 70757.3 71158.6 77811.0 10758.4 11559.3 11584.9 11639.2 11643.9 21520.6 21613.8 21777.7 22129.0 22710.9 5659.9 5797.1 5807.9 5836.0 5837.6 2894.5 2936.8 2939.9 2952.1 2957.3 3542.4 3600.8 3717.5 3792.4 3830.4 710.0 711.6 714.2 719.2 723.1 562.0 566.8 572.7 577.2 578.1 9757.7 9855.9 10029.7 10263.7 10391.1 562.9 565.6 566.1 566.5 567.0 4055.8 4117.6 4119.3 4337.7 4555.6 71505.0 72289.0 73674.0 73948.0 75625.6 62092.7 63845.6 64640.0 65669.7 67368.7 57467.4 58686.1 59750.0 60768.7 61379.4 9873.3 10057.8 10358.4 10425.2 10525.4 26715.6 26756.1 27090.9 27188.7 27301.2 34886.3 35011.3 35374.3 35689.8 35736.0 13267.4 13315.6 13351.5 13356.2 13500.3 15178.0 15357.0 15414.2 15643.4 15831.5 12507.9 13648.6 13655.3 13739.9 13767.3 12520.0 13080.7 13392.7 13436.4 13551.5 13733.0 13813.1 14129.3 14135.9 14347.6 9816.5 9828.1 10051.4 10089.7 10685.5 11113.6 11741.2 11813.2 11832.3 11991.4 18:39:44 INFO - PID 25009 | -------- Summary: end -------- 18:39:44 INFO - PID 25009 | 18:39:45 INFO - TEST-INFO | 25009: exit 0 18:39:45 INFO - TEST-OK | dromaeo_css | took 1146136ms 18:39:45 INFO - TEST-START | kraken 18:39:45 INFO - operating with platform_type : linux_ 18:39:45 INFO - Initialising browser for kraken test... 18:39:45 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50827/getInfo.html -profile /tmp/tmpsad06k/profile 18:39:45 INFO - TEST-INFO | started process 25409 (/builds/slave/test/build/application/firefox/firefox http://localhost:50827/getInfo.html) 18:40:19 INFO - TEST-INFO | 25409: killed by SIGTERM 18:40:20 INFO - Browser initialized. 18:40:20 INFO - Running cycle 1/1 for kraken test... 18:40:20 INFO - TEST-INFO | started process 25607 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsad06k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:40:32 INFO - PID 25607 | RSS: Main: 172761088 18:40:32 INFO - PID 25607 | 18:40:34 INFO - PID 25607 | Cycle 1(1): loaded http://localhost:50827/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50827/tests/kraken/driver.html?testName=audio-beat-detection) 18:40:35 INFO - PID 25607 | RSS: Main: 178860032 18:40:35 INFO - PID 25607 | 18:40:38 INFO - PID 25607 | Cycle 1(1): loaded http://localhost:50827/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50827/tests/kraken/driver.html?testName=audio-dft) 18:40:39 INFO - PID 25607 | RSS: Main: 177831936 18:40:39 INFO - PID 25607 | 18:40:42 INFO - PID 25607 | Cycle 1(1): loaded http://localhost:50827/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50827/tests/kraken/driver.html?testName=audio-fft) 18:40:42 INFO - PID 25607 | RSS: Main: 175403008 18:40:42 INFO - PID 25607 | 18:40:44 INFO - PID 25607 | Cycle 1(1): loaded http://localhost:50827/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50827/tests/kraken/driver.html?testName=audio-oscillator) 18:40:45 INFO - PID 25607 | RSS: Main: 175579136 18:40:45 INFO - PID 25607 | 18:40:47 INFO - PID 25607 | Cycle 1(1): loaded http://localhost:50827/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50827/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:40:48 INFO - PID 25607 | RSS: Main: 175865856 18:40:48 INFO - PID 25607 | 18:40:51 INFO - PID 25607 | Cycle 1(1): loaded http://localhost:50827/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50827/tests/kraken/driver.html?testName=imaging-darkroom) 18:40:51 INFO - PID 25607 | RSS: Main: 177123328 18:40:51 INFO - PID 25607 | 18:40:55 INFO - PID 25607 | Cycle 1(1): loaded http://localhost:50827/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50827/tests/kraken/driver.html?testName=imaging-desaturate) 18:40:56 INFO - PID 25607 | RSS: Main: 175710208 18:40:56 INFO - PID 25607 | 18:40:58 INFO - PID 25607 | Cycle 1(1): loaded http://localhost:50827/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50827/tests/kraken/driver.html?testName=json-parse-financial) 18:40:59 INFO - PID 25607 | RSS: Main: 176877568 18:40:59 INFO - PID 25607 | 18:41:01 INFO - PID 25607 | Cycle 1(1): loaded http://localhost:50827/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50827/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:41:01 INFO - PID 25607 | RSS: Main: 177115136 18:41:01 INFO - PID 25607 | 18:41:03 INFO - PID 25607 | Cycle 1(1): loaded http://localhost:50827/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50827/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:41:04 INFO - PID 25607 | RSS: Main: 176754688 18:41:04 INFO - PID 25607 | 18:41:06 INFO - PID 25607 | Cycle 1(1): loaded http://localhost:50827/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50827/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:41:07 INFO - PID 25607 | RSS: Main: 176431104 18:41:07 INFO - PID 25607 | 18:41:09 INFO - PID 25607 | Cycle 1(1): loaded http://localhost:50827/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50827/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:41:10 INFO - PID 25607 | RSS: Main: 177217536 18:41:10 INFO - PID 25607 | 18:41:13 INFO - PID 25607 | Cycle 1(1): loaded http://localhost:50827/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50827/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:41:14 INFO - PID 25607 | RSS: Main: 176889856 18:41:14 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | Cycle 1(1): loaded http://localhost:50827/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50827/tests/kraken/driver.html?testName=ai-astar) 18:41:16 INFO - PID 25607 | RSS: Main: 178171904 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | __start_tp_report 18:41:16 INFO - PID 25607 | _x_x_mozilla_page_load 18:41:16 INFO - PID 25607 | _x_x_mozilla_page_load_details 18:41:16 INFO - PID 25607 | |i|pagename|runs| 18:41:16 INFO - PID 25607 | |0;ai-astar;105;111;111;112;111;114;113;111;111;112 18:41:16 INFO - PID 25607 | |1;audio-beat-detection;150;125;127;166;123;123;124;127;144;123 18:41:16 INFO - PID 25607 | |2;audio-dft;165;165;164;166;169;168;169;165;164;162 18:41:16 INFO - PID 25607 | |3;audio-fft;74;75;74;110;73;75;74;74;95;75 18:41:16 INFO - PID 25607 | |4;audio-oscillator;83;83;106;85;84;82;97;84;83;115 18:41:16 INFO - PID 25607 | |5;imaging-gaussian-blur;114;115;114;114;114;113;114;115;114;114 18:41:16 INFO - PID 25607 | |6;imaging-darkroom;150;151;149;150;151;151;150;150;150;151 18:41:16 INFO - PID 25607 | |7;imaging-desaturate;96;99;94;94;94;94;94;95;94;95 18:41:16 INFO - PID 25607 | |8;json-parse-financial;53;53;53;54;55;55;54;54;55;54 18:41:16 INFO - PID 25607 | |9;json-stringify-tinderbox;57;57;57;57;57;56;56;56;56;56 18:41:16 INFO - PID 25607 | |10;stanford-crypto-aes;86;90;88;87;90;86;98;89;117;85 18:41:16 INFO - PID 25607 | |11;stanford-crypto-ccm;136;139;136;136;138;135;138;141;138;137 18:41:16 INFO - PID 25607 | |12;stanford-crypto-pbkdf2;185;199;191;196;204;192;193;189;197;199 18:41:16 INFO - PID 25607 | |13;stanford-crypto-sha256-iterative;75;78;74;74;77;72;75;72;71;74 18:41:16 INFO - PID 25607 | __end_tp_report 18:41:16 INFO - PID 25607 | __start_cc_report 18:41:16 INFO - PID 25607 | _x_x_mozilla_cycle_collect,934 18:41:16 INFO - PID 25607 | __end_cc_report 18:41:16 INFO - PID 25607 | __startTimestamp1495676476420__endTimestamp 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | ------- Summary: start ------- 18:41:16 INFO - PID 25607 | Number of tests: 14 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.50 stddev:2.38 (2.1%) stddev-sans-first:1.09 18:41:16 INFO - PID 25607 | Values: 105.0 111.0 111.0 112.0 111.0 114.0 113.0 111.0 111.0 112.0 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | [#1] audio-beat-detection Cycles:10 Average:133.20 Median:127.00 stddev:14.97 (11.8%) stddev-sans-first:14.59 18:41:16 INFO - PID 25607 | Values: 150.0 125.0 127.0 166.0 123.0 123.0 124.0 127.0 144.0 123.0 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | [#2] audio-dft Cycles:10 Average:165.70 Median:165.50 stddev:2.31 (1.4%) stddev-sans-first:2.44 18:41:16 INFO - PID 25607 | Values: 165.0 165.0 164.0 166.0 169.0 168.0 169.0 165.0 164.0 162.0 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | [#3] audio-fft Cycles:10 Average:79.90 Median:74.50 stddev:12.44 (16.7%) stddev-sans-first:13.01 18:41:16 INFO - PID 25607 | Values: 74.0 75.0 74.0 110.0 73.0 75.0 74.0 74.0 95.0 75.0 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:83.50 stddev:11.73 (14.0%) stddev-sans-first:12.14 18:41:16 INFO - PID 25607 | Values: 83.0 83.0 106.0 85.0 84.0 82.0 97.0 84.0 83.0 115.0 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 18:41:16 INFO - PID 25607 | Values: 114.0 115.0 114.0 114.0 114.0 113.0 114.0 115.0 114.0 114.0 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | [#6] imaging-darkroom Cycles:10 Average:150.30 Median:150.50 stddev:0.67 (0.4%) stddev-sans-first:0.71 18:41:16 INFO - PID 25607 | Values: 150.0 151.0 149.0 150.0 151.0 151.0 150.0 150.0 150.0 151.0 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:94.50 stddev:1.60 (1.7%) stddev-sans-first:1.64 18:41:16 INFO - PID 25607 | Values: 96.0 99.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 95.0 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.78 18:41:16 INFO - PID 25607 | Values: 53.0 53.0 53.0 54.0 55.0 55.0 54.0 54.0 55.0 54.0 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | [#9] json-stringify-tinderbox Cycles:10 Average:56.50 Median:57.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 18:41:16 INFO - PID 25607 | Values: 57.0 57.0 57.0 57.0 57.0 56.0 56.0 56.0 56.0 56.0 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:88.50 stddev:9.65 (10.9%) stddev-sans-first:10.02 18:41:16 INFO - PID 25607 | Values: 86.0 90.0 88.0 87.0 90.0 86.0 98.0 89.0 117.0 85.0 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | [#11] stanford-crypto-ccm Cycles:10 Average:137.40 Median:138.00 stddev:1.78 (1.3%) stddev-sans-first:1.81 18:41:16 INFO - PID 25607 | Values: 136.0 139.0 136.0 136.0 138.0 135.0 138.0 141.0 138.0 137.0 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.50 Median:196.50 stddev:5.58 (2.8%) stddev-sans-first:4.75 18:41:16 INFO - PID 25607 | Values: 185.0 199.0 191.0 196.0 204.0 192.0 193.0 189.0 197.0 199.0 18:41:16 INFO - PID 25607 | 18:41:16 INFO - PID 25607 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.20 Median:74.50 stddev:2.20 (3.0%) stddev-sans-first:2.32 18:41:16 INFO - PID 25607 | Values: 75.0 78.0 74.0 74.0 77.0 72.0 75.0 72.0 71.0 74.0 18:41:16 INFO - PID 25607 | -------- Summary: end -------- 18:41:16 INFO - PID 25607 | 18:41:17 INFO - TEST-INFO | 25607: exit 0 18:41:17 INFO - TEST-OK | kraken | took 92132ms 18:41:17 INFO - SUITE-END | took 1238s 18:41:17 INFO - Completed test suite (00:20:38) 18:41:17 INFO - javascript benchmark 18:41:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6624.310880009119, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2727.4954133485307, 2737.1241202815095, 2737.7399147081046, 2772.1822062491556, 2775.5004099262123, 5103.974480127594, 5223.3209682741235, 5234.5027222413855, 5267.051930652481, 5284.233786101013, 5255.605829562779, 5262.394824595172, 5264.973675131619, 5321.308229930113, 5338.893222135555, 4663.323818046373, 4673.6027437667835, 4742.264948932903, 4770.189067858455, 4780.976095119519, 4590.2196626573395, 4724.929126063112, 4739.241721324589, 4762.547557981986, 4791.640626952975, 4592.885177870547, 4691.906161876744, 4747.145513807513, 4756.0, 4782.163121453749, 4617.376654151713, 4687.554682305175, 4694.248920172756, 4722.197226471508, 4731.077439899221, 4619.0, 4630.86107416778, 4642.303654451859, 4666.626669866402, 4684.110019096361, 5165.302684137631, 5169.9744938625845, 5179.766910489037, 5189.40321862983, 5197.038547868657, 5609.186667933168, 5660.094384898438, 5675.829725108253, 5688.345840228385, 5743.0, 5617.971910140464, 5674.290713660751, 5687.118496633028, 5725.942740572583, 5728.97135514324, 5241.344831896013, 5246.947530524646, 5261.13191323435, 5273.815416460406, 5323.254744335796, 5700.287464066992, 5779.739911703983, 5781.19063331134, 5811.79658711943, 5816.127580862904, 5567.220589117527, 5570.248016517799, 5638.0, 5652.463016013472, 5658.122990936411, 5673.8297851064535, 5697.401772813795, 5708.743106560215, 5709.486146246774, 5713.942860571341, 5548.167774833807, 5652.491275785199, 5665.773369065191, 5668.149777533321, 5668.773249070074, 5542.334919809648, 5579.46995035471, 5612.1581762735095, 5618.4662457066515, 5627.493525582717, 4801.399825021872, 4804.159272127364, 4805.0, 4812.494688057774, 4847.903041939141, 4090.406891000788, 4091.57038510958, 4107.178564287155, 4142.06803469217, 4154.335306350969, 4714.5521175488275, 4790.616750659939, 4791.904161916742, 4809.422869255712, 4815.927761083586, 5585.888282234414, 5675.687837168913, 5688.0, 5689.203511508392, 5743.4256574342235, 4815.566599006036, 4815.975920120377, 4830.830920917751, 4839.903201936012, 4858.271259311132, 5108.591312694975, 5109.56568691658, 5110.667806592485, 5117.283580298761, 5124.5131712487255, 5135.204043373134, 5185.896282074413, 5194.091034069098, 5235.0, 5261.079311120462, 5568.248286481273, 5769.0, 5771.249737534262, 5793.826185214451, 5840.737166827588, 5212.713300768494, 5224.843254702365, 5226.242194881797, 5251.474852514871, 5343.5457986071615, 5546.528545073714, 5640.379558248672, 5646.858828529155, 5665.3201615806365, 5698.088305871041, 5552.361478430033, 5766.3080430346945, 5769.0, 5769.1634712967225, 5783.277090363705, 5493.176023596333, 5526.088195447706, 5533.916991245054, 5561.165825126263, 5569.944300556943, 5491.478309560585, 5493.0, 5496.258005169411, 5496.697681627549, 5511.0, 5369.704666243394, 5418.918716219181, 5421.05131601976, 5421.105517589711, 5462.754176062166, 5498.092814685692, 5574.16387541872, 5580.720963951705, 5582.218489411567, 5670.234518339971, 5400.324959380077, 5404.83785486436, 5426.945730542644, 5440.319960004999, 5447.972760136174], "value": 5146.585554273047, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.9900701489479, 715.3847690985751, 736.4623824608036, 1062.2829590026736, 1064.4677661169414, 29598.260043498896, 30215.03602265854, 30280.697193028063, 31963.36073278533, 32031.51952720711, 34888.0, 35198.82400588, 35228.647713522856, 35242.0, 35342.64657353426, 6717.8992315115065, 6834.111565496503, 6845.383915447609, 6845.623490708009, 6966.16406031277, 6770.322967703215, 6810.795676129699, 6850.246472887979, 6867.175938887341, 6868.828279293008, 6820.761273355433, 6832.248452670202, 6851.623160726158, 6858.485613578977, 6867.381935625793, 6833.350831670983, 6858.759943402007, 6859.725610975555, 6861.039454476378, 6874.931250687529, 6764.188297404294, 6769.458443324522, 6771.762988295386, 6809.148856392951, 6820.079289295931, 31992.520112198337, 32019.679803201903, 32138.0, 32280.35439291201, 32426.189345266557, 2966.110166949928, 3018.864151113183, 3021.4863473209607, 3022.2746540830262, 3025.9394812103633, 24082.0, 24211.878940605184, 24328.878355608285, 24346.513069738685, 24498.142565010137, 2796.4546913351905, 2801.453716525258, 2809.0730059080456, 2810.339570201001, 2828.505011622978, 142569.28715356358, 143800.28099859433, 143828.0, 148067.0, 152974.0, 37993.24013519714, 38049.619503804606, 38247.80876095658, 38282.61717382846, 38459.0, 39703.40444893329, 39886.401703974465, 40018.59981400207, 40086.39870401946, 43387.56612433835, 12421.31682757457, 12788.872111278954, 12809.487620495262, 12855.485780568673, 12900.225986440844, 10322.0, 10383.896161038445, 10411.687649370531, 10480.213983951235, 10483.790324193626, 12394.008479321632, 12469.75060498785, 12535.498580056696, 12652.430640621194, 12684.556040538537, 5358.437364076716, 5397.8110766123, 5418.45815418455, 5452.127659574449, 5462.617616766867, 851.1275942159212, 883.1874675298741, 894.5840184314325, 902.0753727429437, 917.5824999625155, 18628.068596570116, 18742.25030998745, 19117.808821911603, 19858.60282794365, 25423.348156133332, 948.7343543807744, 950.2350607760819, 972.6790159247573, 974.2790335151936, 984.17329443268, 11084.334939903632, 11188.328700278009, 11340.376279304552, 11404.543818247177, 11565.537378504934, 157105.2144739269, 159224.0, 159763.20118399334, 160302.1984890068, 168972.0, 43475.56524434715, 44342.11315773666, 44499.0, 44630.0, 44678.55321446809, 1201.130127361214, 1225.4791713521895, 1229.9508019679292, 1232.1744431231098, 1232.6302109367155, 24035.27894163178, 24065.278041658778, 25287.620685689362, 25469.23592292234, 25673.743262567135, 29562.556561651163, 33278.0, 33671.3265734679, 33672.663273367936, 33813.3237335247, 154133.0, 155094.22452887663, 155731.0, 155949.0, 156177.0, 29603.259918502208, 29644.851775740983, 29698.554521682625, 30092.849535752182, 30101.247468813453, 34814.12964675904, 35491.645083548836, 35744.82127589345, 35760.28479430449, 35991.460128098624, 26341.34146646349, 26596.0, 26970.32574185661, 27422.314442139104, 27478.29043079159, 26771.19686409411, 27338.863305683346, 27839.58240626352, 27856.721432786224, 28031.579526306712, 31223.687763122078, 31230.0, 31324.216894577818, 31327.0, 31355.686443135277, 26461.60307595349, 26830.86584567143, 27263.86368068147, 27823.44353112967, 28174.71825281803, 21100.788992110498, 22053.33839984803, 22129.0, 22140.889295553417, 22368.328950131523], "value": 14475.087220302343, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.3262616682472, 411.2145801519099, 416.98749037528876, 421.51736261980034, 440.0516886110433, 14054.0, 15340.079595224266, 16813.747793783088, 17486.475405737816, 17500.0, 18682.719759203625, 18954.24183032677, 18976.525586860334, 19155.90422047891, 19187.61624767504, 5313.601479889005, 5400.216968539559, 5559.666420014792, 5586.329640443152, 5612.326520817487, 5372.758225879826, 5537.197106419567, 5539.141433077859, 5544.889102217953, 5549.056660367747, 5086.465921078289, 5324.946750532484, 5330.786768529274, 5358.758855851476, 5509.944900550983, 5224.164133738603, 5229.477052294778, 5231.24146998683, 5231.895362092775, 5259.053370393328, 4770.212914869043, 4790.305405716186, 4939.185034469308, 4951.653384263103, 4975.4527002029745, 21386.358409247747, 21650.0, 21756.564868702695, 21817.781822181732, 21842.907854607205, 1480.9333579988818, 1610.8630766384872, 1618.8866779325451, 1622.5132460261875, 1626.9023858568405, 13858.514951976733, 14034.508792192326, 14044.859551404457, 14242.216678082701, 14293.714125717532, 2000.7799142094505, 2003.8096380843797, 2051.128270485053, 2057.886816225107, 2074.1806986240504, 87416.12583874143, 91600.08399915982, 92022.0, 92689.53655231748, 93728.0, 10589.84115238272, 10884.455777211107, 10913.563457461612, 11191.32852028881, 11261.887381126084, 14858.628534286729, 15239.923800380928, 15341.0, 15370.308336124901, 15436.0, 7352.816179595446, 7404.740834070782, 7440.4791664584045, 7525.172231054579, 7641.69433222676, 6815.114035175495, 6828.054004608663, 6972.511924165361, 7003.509754317149, 7025.297470252934, 6828.317168283132, 6841.4344620767215, 6867.828304292432, 6872.484563657746, 6932.376086152271, 2025.9898700506699, 2064.6386882295837, 2069.5240094778287, 2091.6025955068794, 2238.160689741347, 834.4200780457523, 864.6066039951806, 868.5613765048736, 879.3404946290283, 892.790194304338, 16535.917320413562, 16596.336146554004, 16811.579710507336, 17353.132343383033, 17445.563860903578, 839.6473481137815, 858.4677499949946, 861.5003298087095, 874.5758307220992, 879.0769691823688, 9092.499912504876, 9300.581473833694, 9494.287928405398, 9549.474778887228, 9619.374740641835, 351939.0, 353167.0, 355279.0, 358727.0, 360961.0, 72475.2752472479, 72496.0, 73686.26313736795, 75311.62344188245, 75392.0, 997.7306127345576, 1008.5663164839043, 1024.6823484719762, 1032.767627283855, 1036.7356324137297, 13864.653383665489, 14191.787123192953, 14291.0, 14386.856131438552, 14667.48663796762, 36126.458103127945, 36233.27533449369, 36518.0, 37290.254194916495, 37775.81112094422, 44953.77523112494, 45119.77440112778, 45590.54409455863, 46460.30309545292, 47044.29433558431, 7905.960470197612, 8005.719799806979, 8079.272865442138, 8119.350451963947, 8229.506229626242, 11460.140489463156, 11738.765224695288, 11921.165518413627, 11972.940135299268, 12026.879731202576, 10304.536295866854, 10312.329698569569, 10349.844752328872, 10440.53017614194, 10563.894361056291, 9381.155695987121, 9485.146336829719, 9614.134727874525, 9669.854952175583, 9850.162736167505, 10056.748581285234, 10265.178785697275, 10537.683869483928, 10689.412082335235, 11100.11199104086, 7816.648250828613, 8056.879146812686, 8060.919390806017, 8159.673613055411, 8219.794505137419, 10371.533281002223, 10445.9477702611, 10818.837717434088, 10892.891071089187, 10913.727156821144], "value": 8580.86883180909, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1286.8777466140716, 1301.2517802263694, 1332.6934804994858, 1334.7263810918762, 1338.1168428836966, 10122.797544049115, 10168.237382196328, 10507.894921050787, 10629.52167152478, 10640.308379955297, 10451.268411211218, 10542.0, 10659.307145035567, 10673.0, 10697.19771017173, 2175.119076773909, 2222.0112050137695, 2227.7326720793526, 2233.36349140495, 2233.888305584714, 2159.7948194921455, 2164.7618761936255, 2188.070070220158, 2195.034184958613, 2195.1768086967386, 2152.6448136057534, 2174.4781252499442, 2176.107795803721, 2188.6826410170515, 2192.9122835086578, 2133.8612990155666, 2142.046789178816, 2154.407537927075, 2155.0194661429014, 2162.643163877958, 2092.7697953225133, 2097.98951005244, 2182.3998400439846, 2205.757366689663, 2239.2162743039808, 10548.050675439248, 10583.735406614896, 10636.46817659114, 10688.946555267252, 10708.25042247043, 5397.27136836526, 5546.500814926676, 5555.916661250085, 5578.609497335188, 5628.662280263156, 2888.6966868478717, 2984.910452686423, 3019.0942717184757, 3025.319303156794, 3079.8306093164865, 879.7316818370394, 885.4642941020654, 903.0292435631723, 916.6516723644972, 935.8596210568401, 15582.922085389613, 15677.216139192995, 15718.21408929549, 15740.0, 15761.921190394089, 4525.321201819687, 4569.611583015481, 4623.595730220267, 4748.525147485224, 4770.356001939763, 11178.44107794607, 11265.7607663157, 11276.1542884284, 11315.377654228932, 11322.660320190407, 5424.105022671294, 5515.283013208257, 5531.557475401958, 5534.833954981357, 5548.0, 5054.519820617035, 5119.436861945183, 5140.717260550631, 5155.484451554815, 5168.3797944246935, 3879.786611736325, 3895.3962135868987, 3911.1786524830104, 3984.940225896614, 4028.697847661437, 1084.4794498640697, 1085.234909388879, 1100.6753007862699, 1108.7228192951761, 1114.7714718482691, 684.1072400517273, 707.2785758526275, 711.2460791560962, 716.7527203114917, 722.9710811567584, 5279.894402111936, 5388.0, 5390.598444404404, 5422.40353561108, 5520.723963801794, 530.737285043898, 533.2587703092704, 536.8550491367387, 539.2962184349383, 540.1007322807482, 3507.5790905091044, 3603.531540899666, 3619.5656521217625, 3672.192117734094, 3806.8477260909253, 13482.460701572023, 14164.220967846868, 14205.78691319631, 14207.78688319676, 14217.289135543182, 13654.385552650154, 13879.722405551833, 14059.156450612996, 14264.857351426353, 14454.927725361516, 7477.850442991218, 7584.0, 7589.43079269046, 7602.771916842504, 7613.08642962848, 5327.840164795062, 5412.134058550613, 5598.1322894951345, 5731.054376027909, 5755.309362876482, 12037.638870833538, 12084.154109212272, 12135.332556709465, 12205.877941220475, 12346.0, 12569.622911312676, 13058.281794501394, 13115.606531804062, 13308.134971226838, 13321.933390332986, 7781.105172904962, 7936.0, 8039.758807235792, 8045.034595848536, 8084.110747817617, 8479.745607631781, 9038.593263303035, 9130.223930965942, 9176.541172941194, 9208.723738287863, 7236.963815181101, 7302.671379788028, 7314.341709246193, 7320.707171713072, 7324.230955749569, 7607.239276072349, 7659.655315510704, 7660.961695191489, 7780.338671212784, 7815.491993020435, 6884.4148247399535, 7003.089598352181, 7003.54476958996, 7008.614526201108, 7016.438684905093, 4719.5752382285755, 5163.225516172604, 5165.9483405165465, 5170.302009228856, 5392.056390131633, 5404.648697834628, 5424.942136283387, 5438.483344082306, 5485.533729633026, 5576.052071147834], "value": 4628.055590336815, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.0911547801286, 342.0081762887626, 352.1091638155466, 352.977056491328, 353.22819639088607, 5376.274202982596, 5534.584906132041, 5541.362743284523, 5551.611387202897, 5572.832815015551, 5288.603354748399, 5367.973160134194, 5372.435894231098, 5395.865103372417, 5409.0, 4003.8598649047144, 4005.1589166275116, 4006.198760247962, 4007.3788562772816, 4018.899527511821, 3854.169269596947, 3935.685145188392, 3999.740016898907, 4010.939835902464, 4026.4161696553983, 3931.095847954972, 3943.8225279862477, 3957.940630890539, 3970.3051965905997, 3977.2244412339605, 3918.902027449308, 3930.980345098285, 3937.1731936293413, 3953.2093581283857, 3962.960370396288, 3412.3004784019463, 3416.974907527732, 3420.0765793235937, 3430.2967891582166, 3435.7766745161484, 5218.510793305445, 5230.110881150214, 5284.339457567804, 5314.927178400427, 5316.494932981399, 4680.812767489297, 4697.271922851964, 4704.176769065434, 4709.340692303081, 4713.740744259065, 5558.944410555923, 5649.180868774046, 5663.122216056477, 5723.456271654186, 5724.847637126578, 3808.333541630231, 3809.3904975203836, 3810.714196435251, 3822.9617703822883, 3831.616838316146, 5828.805094955524, 5992.273515550414, 6051.515878729735, 6093.421124993163, 6117.265928088658, 3896.79199448172, 3919.451276821247, 3926.7251292409255, 3929.44987701722, 3935.252302062599, 3956.248312820555, 3979.403089536535, 3989.1024519483462, 3991.900202494961, 3996.8800935971967, 4539.319102134707, 4678.672492925531, 4681.81272749093, 4682.765861706969, 4684.180268453007, 5645.887082258414, 5715.742791574389, 5729.0, 5742.913856292159, 5749.482546570745, 1351.6418149190472, 1356.5387768158873, 1359.6261028217123, 1360.693843885118, 1362.9659258518616, 865.6234537976001, 898.1229230907435, 904.9592768325441, 913.712180663089, 913.981720365589, 4942.912559236962, 4943.492234868328, 4954.777035033432, 4964.776585053682, 4978.228374601942, 5799.623024503394, 5859.2090067841145, 5911.9113213301835, 5913.172155898178, 5926.170336152942, 4869.123557759566, 4925.21196608541, 4952.529509696573, 4975.378077740283, 4986.551210391082, 3132.9034837806585, 3144.3554071415306, 3145.2451411661036, 3162.414953233659, 3170.651228364878, 5478.0, 5479.123340265538, 5480.835574932678, 5501.504864562208, 5516.531094856902, 5907.734151963172, 6045.33501314855, 6047.183630209865, 6084.60450070744, 6084.908726369108, 5452.836414907559, 5472.0, 5483.396826349099, 5486.807961721321, 5516.14499752539, 5711.571632127524, 5846.269216347957, 5882.735276912464, 5890.793822216202, 5893.6463812171405, 5838.12428135783, 5841.649501029951, 5847.415258474289, 5864.149698293809, 5868.684193430698, 5302.992431438014, 5433.972830135824, 5445.455454454523, 5450.890982180414, 5452.427495113115, 3882.4370466281666, 3884.18432129256, 3886.1839013806284, 3886.5336159661024, 3892.6107389260847, 5852.151438041546, 5909.793157239476, 5957.4042595740075, 5980.312264089685, 5981.431763982415, 5694.515966142924, 5717.313922329181, 5729.255196824386, 5739.368669446441, 5755.769769209185, 5709.8287051388525, 5716.199732037435, 5757.898367095896, 5761.0, 5766.538676905921, 5562.332520097614, 5651.886962260814, 5663.253322138842, 5666.21514223035, 5667.773289068391, 5788.681622510802, 5792.1482214327, 5793.623414478046, 5795.478406943395, 5818.94181058201, 5541.972290138685, 5590.329160500766, 5629.802956896489, 5630.60585758993, 5633.985882541182], "value": 4212.087791313859, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.7048898484669, 687.6905392573343, 723.5080145501058, 724.0334153904537, 730.455810421236, 13140.145890517122, 13982.440702371905, 14014.859851401483, 15919.92040039801, 16466.588335291624, 16110.677786444265, 16276.918615406907, 16317.510474685778, 16372.83627163728, 16464.83535164648, 894.5616647842543, 899.1952202778509, 899.6131663384742, 900.3922352412096, 902.8149229407956, 822.983540329193, 855.0978717453085, 857.6355049104137, 861.2593169873935, 900.8648702694616, 904.023654453189, 914.3005600715425, 916.9358144929857, 920.1258804136097, 930.8557173638097, 904.1049361132465, 906.7687739626354, 909.9044600316971, 913.2785099771173, 933.2673851026946, 670.9530332876701, 672.4183581202274, 675.0144788608637, 676.347324831535, 683.4873844616537, 13624.0, 13924.094933829268, 13965.371558279901, 14007.579772606838, 14024.929875350557, 14110.647233819134, 14310.64223394413, 14412.78380824278, 14495.275236238145, 14569.0, 11262.0, 11291.943540282327, 11345.886541134565, 11484.36835974021, 11592.420378981102, 2601.9089331873292, 2618.56793629051, 2622.1740151852105, 2626.0808716948914, 2629.526685196664, 68739.78232285638, 69796.30203697897, 70757.2924270761, 71158.64420677915, 77811.0, 10758.386242063752, 11559.30644161353, 11584.942075289739, 11639.185257031926, 11643.941780291214, 21520.56958860814, 21613.75793630968, 21777.67333489967, 22129.0, 22710.886445567998, 5659.858503537444, 5797.072468405035, 5807.883842323215, 5836.037053886062, 5837.6497410155525, 2894.493463643854, 2936.8237905725723, 2939.8824047038306, 2952.099609619087, 2957.290250339946, 3542.3977923753023, 3600.765950213228, 3717.4795528626014, 3792.4311353297226, 3830.3871380578976, 710.0343532795395, 711.6086152616041, 714.1608609883366, 719.1585844561876, 723.0528008309132, 561.9940306850691, 566.7817890112336, 572.7250919558635, 577.2121054760222, 578.1068249554448, 9757.658481953098, 9855.901440985499, 10029.686915304977, 10263.692089237335, 10391.109253897697, 562.9155626655952, 565.6097292867908, 566.07164250629, 566.498648695907, 566.9681180251902, 4055.7566546007333, 4117.5882411759185, 4119.279126152911, 4337.6963612546815, 4555.612772914273, 71505.0, 72289.0, 73674.0, 73948.0, 75625.62187189028, 62092.689536552934, 63845.565711030824, 64640.0, 65669.67165164143, 67368.66315668488, 57467.42532574704, 58686.11970820441, 59750.0, 60768.69615651982, 61379.38620613826, 9873.272177369076, 10057.849132262876, 10358.430286334324, 10425.165986720893, 10525.36847789135, 26715.599266010635, 26756.063537776085, 27090.864545677145, 27188.728112718618, 27301.180964571096, 34886.30227395488, 35011.29977400489, 35374.29251415009, 35689.82155089208, 35736.0, 13267.402966866708, 13315.600531984055, 13351.465941362625, 13356.198628082346, 13500.25748583798, 15178.0, 15357.0, 15414.229288535304, 15643.374265029272, 15831.525054248392, 12507.874921251036, 13648.590542283748, 13655.317234138054, 13739.862601373858, 13767.311634418038, 12520.0, 13080.672983175496, 13392.665183370495, 13436.39536220892, 13551.457941682222, 13733.0, 13813.102148360324, 14129.293535323399, 14135.858641413866, 14347.56957291283, 9816.509174541387, 9828.11546960777, 10051.447170405405, 10089.679373743933, 10685.465726713788, 11113.611023614134, 11741.236819606698, 11813.173077884465, 11832.290062596272, 11991.400429978292], "value": 6780.223010471771, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1547.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 111.0, 111.0, 112.0, 111.0, 114.0, 113.0, 111.0, 111.0, 112.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 125.0, 127.0, 166.0, 123.0, 123.0, 124.0, 127.0, 144.0, 123.0], "value": 133.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 164.0, 166.0, 169.0, 168.0, 169.0, 165.0, 164.0, 162.0], "value": 165.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 110.0, 73.0, 75.0, 74.0, 74.0, 95.0, 75.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 106.0, 85.0, 84.0, 82.0, 97.0, 84.0, 83.0, 115.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 114.0, 114.0, 114.0, 113.0, 114.0, 115.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 149.0, 150.0, 151.0, 151.0, 150.0, 150.0, 150.0, 151.0], "value": 150.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 99.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 54.0, 55.0, 55.0, 54.0, 54.0, 55.0, 54.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 57.0, 57.0, 56.0, 56.0, 56.0, 56.0, 56.0], "value": 56.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 88.0, 87.0, 90.0, 86.0, 98.0, 89.0, 117.0, 85.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 139.0, 136.0, 136.0, 138.0, 135.0, 138.0, 141.0, 138.0, 137.0], "value": 137.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 199.0, 191.0, 196.0, 204.0, 192.0, 193.0, 189.0, 197.0, 199.0], "value": 194.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 74.0, 74.0, 77.0, 72.0, 75.0, 72.0, 71.0, 74.0], "value": 74.2, "unit": "score"}]}]} 18:41:17 INFO - Return code: 0 18:41:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:41:17 INFO - # TBPL SUCCESS # 18:41:17 INFO - Running post-action listener: _resource_record_post_action 18:41:17 INFO - [mozharness: 2017-05-25 01:41:17.717349Z] Finished run-tests step (success) 18:41:17 INFO - Running post-run listener: _resource_record_post_run 18:41:17 INFO - Total resource usage - Wall time: 1254s; CPU: 13.0%; Read bytes: 4096; Write bytes: 400293888; Read time: 40; Write time: 234412 18:41:17 INFO - TinderboxPrint: CPU usage
12.7% 18:41:17 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 40 18:41:17 INFO - TinderboxPrint: I/O write bytes / time
400,293,888 / 234,412 18:41:17 INFO - TinderboxPrint: CPU idle
8,732.1 (87.3%) 18:41:17 INFO - TinderboxPrint: CPU user
1,221.1 (12.2%) 18:41:17 INFO - TinderboxPrint: Swap in / out
0 / 0 18:41:17 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 29138944; Read time: 0; Write time: 4584 18:41:17 INFO - run-tests - Wall time: 1239s; CPU: 13.0%; Read bytes: 4096; Write bytes: 302948352; Read time: 40; Write time: 158140 18:41:18 INFO - Running post-run listener: _upload_blobber_files 18:41:18 INFO - Blob upload gear active. 18:41:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:41:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:41:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:41:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:41:18 INFO - (blobuploader) - INFO - Open directory for files ... 18:41:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:41:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:41:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:19 INFO - (blobuploader) - INFO - Done attempting. 18:41:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:41:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:41:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:27 INFO - (blobuploader) - INFO - Done attempting. 18:41:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:41:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:41:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:28 INFO - (blobuploader) - INFO - Done attempting. 18:41:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:41:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:41:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:29 INFO - (blobuploader) - INFO - Done attempting. 18:41:29 INFO - (blobuploader) - INFO - Iteration through files over. 18:41:29 INFO - Return code: 0 18:41:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:41:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:41:29 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be98fe53f8b4674d4f850787738b156614c5747ab1f77c6044444baaa668a8bc75d6e2d47f4c0c74b150d8636c72d6cd7562fad29ee72138572f37d0d700a8b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cae1ff2c27619b19f6d09007ae28eb9cde37efc414ddf6edd7196dbdbff5f9d422fdbdcc9f50bd0a1445cbdf454414a5e71f89ad24e870987572dc23100497bf", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/de037dd9e10cd11dcc632a80127d7a7d43281a3fe3698eb137aa39c3960a914efca099fa18e78896f96ce581c03bb0ad50b5709eea97c82a22ddebedf802e50e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71e14d8fd86fa8d77843b0d2c8a77d76bdbd648e678ee436505a7276241d712d5e310cf24b9cc41e63695e96be493bc5888ac4c77c70d4105ec99468b5c2ba51"} 18:41:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:41:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:41:29 INFO - Contents: 18:41:29 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be98fe53f8b4674d4f850787738b156614c5747ab1f77c6044444baaa668a8bc75d6e2d47f4c0c74b150d8636c72d6cd7562fad29ee72138572f37d0d700a8b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cae1ff2c27619b19f6d09007ae28eb9cde37efc414ddf6edd7196dbdbff5f9d422fdbdcc9f50bd0a1445cbdf454414a5e71f89ad24e870987572dc23100497bf", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/de037dd9e10cd11dcc632a80127d7a7d43281a3fe3698eb137aa39c3960a914efca099fa18e78896f96ce581c03bb0ad50b5709eea97c82a22ddebedf802e50e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71e14d8fd86fa8d77843b0d2c8a77d76bdbd648e678ee436505a7276241d712d5e310cf24b9cc41e63695e96be493bc5888ac4c77c70d4105ec99468b5c2ba51"} 18:41:29 INFO - Running post-run listener: copy_logs_to_upload_dir 18:41:29 INFO - Copying logs to upload dir... 18:41:29 INFO - mkdir: /builds/slave/test/build/upload/logs 18:41:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1315.730312 ========= master_lag: -0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 55 secs) (at 2017-05-24 18:41:29.075894) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 18:41:29.083383) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be98fe53f8b4674d4f850787738b156614c5747ab1f77c6044444baaa668a8bc75d6e2d47f4c0c74b150d8636c72d6cd7562fad29ee72138572f37d0d700a8b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cae1ff2c27619b19f6d09007ae28eb9cde37efc414ddf6edd7196dbdbff5f9d422fdbdcc9f50bd0a1445cbdf454414a5e71f89ad24e870987572dc23100497bf", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/de037dd9e10cd11dcc632a80127d7a7d43281a3fe3698eb137aa39c3960a914efca099fa18e78896f96ce581c03bb0ad50b5709eea97c82a22ddebedf802e50e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71e14d8fd86fa8d77843b0d2c8a77d76bdbd648e678ee436505a7276241d712d5e310cf24b9cc41e63695e96be493bc5888ac4c77c70d4105ec99468b5c2ba51"} build_url:https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011222 build_url: 'https://queue.taskcluster.net/v1/task/O6MD1N43Rp-bCmGfoqftNw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/be98fe53f8b4674d4f850787738b156614c5747ab1f77c6044444baaa668a8bc75d6e2d47f4c0c74b150d8636c72d6cd7562fad29ee72138572f37d0d700a8b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/cae1ff2c27619b19f6d09007ae28eb9cde37efc414ddf6edd7196dbdbff5f9d422fdbdcc9f50bd0a1445cbdf454414a5e71f89ad24e870987572dc23100497bf", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/de037dd9e10cd11dcc632a80127d7a7d43281a3fe3698eb137aa39c3960a914efca099fa18e78896f96ce581c03bb0ad50b5709eea97c82a22ddebedf802e50e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71e14d8fd86fa8d77843b0d2c8a77d76bdbd648e678ee436505a7276241d712d5e310cf24b9cc41e63695e96be493bc5888ac4c77c70d4105ec99468b5c2ba51"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 18:41:29.114477) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 18:41:29.114813) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1495675171.336750-1519185828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004533 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 18:41:29.196714) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 18:41:29.200049) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 18:41:29.200365) ========= ========= Total master_lag: 0.08 =========