builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-037 starttime: 1500987690.67 results: success (0) revision: 80394cbcae0f02da8eb801edd92e56f974e5db4f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-25 06:01:30.671044) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-25 06:01:30.671707) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-25 06:01:30.671977) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-25 06:01:30.706349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-25 06:01:30.706755) ========= 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-1500981757.417677-267865685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-25 06:01:30-- 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% 39.8M=0s 2017-07-25 06:01:31 (39.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118114 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-25 06:01:30.850535) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-25 06:01:30.850811) ========= 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-1500981757.417677-267865685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022060 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-25 06:01:30.899518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-25 06:01:30.899803) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 80394cbcae0f02da8eb801edd92e56f974e5db4f --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 80394cbcae0f02da8eb801edd92e56f974e5db4f --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-1500981757.417677-267865685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-25 06:01:31,143 truncating revision to first 12 chars 2017-07-25 06:01:31,143 Setting DEBUG logging. 2017-07-25 06:01:31,143 attempt 1/10 2017-07-25 06:01:31,143 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/80394cbcae0f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-25 06:01:32,428 unpacking tar archive at: mozilla-central-80394cbcae0f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.612661 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-25 06:01:32.528021) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-25 06:01:32.531344) ========= script_repo_revision: 80394cbcae0f02da8eb801edd92e56f974e5db4f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-25 06:01:32.531709) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-25 06:01:32.531973) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-25 06:01:32.541950) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-07-25 06:01:32.542289) ========= /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-1500981757.417677-267865685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:01:32 INFO - MultiFileLogger online at 20170725 06:01:32 in /builds/slave/test 06:01:32 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 06:01:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:01:32 INFO - {'append_to_log': False, 06:01:32 INFO - 'base_work_dir': '/builds/slave/test', 06:01:32 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 06:01:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:01:32 INFO - 'branch': 'Firefox-Non-PGO', 06:01:32 INFO - 'buildbot_json_path': 'buildprops.json', 06:01:32 INFO - 'code_coverage': False, 06:01:32 INFO - 'config_files': ('talos/linux_config.py',), 06:01:32 INFO - 'default_actions': ('clobber', 06:01:32 INFO - 'read-buildbot-config', 06:01:32 INFO - 'download-and-extract', 06:01:32 INFO - 'populate-webroot', 06:01:32 INFO - 'create-virtualenv', 06:01:32 INFO - 'install', 06:01:32 INFO - 'setup-mitmproxy', 06:01:32 INFO - 'run-tests'), 06:01:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:01:32 INFO - 'disable_ccov_upload': False, 06:01:32 INFO - 'download_minidump_stackwalk': True, 06:01:32 INFO - 'download_symbols': 'ondemand', 06:01:32 INFO - 'enable_stylo': False, 06:01:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:01:32 INFO - 'tooltool.py': '/tools/tooltool.py', 06:01:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:01:32 INFO - '/tools/misc-python/virtualenv.py')}, 06:01:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:01:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:01:32 INFO - 'gecko_profile': False, 06:01:32 INFO - 'gecko_profile_interval': 0, 06:01:32 INFO - 'installer_path': 'installer.exe', 06:01:32 INFO - 'log_level': 'info', 06:01:32 INFO - 'log_name': 'talos', 06:01:32 INFO - 'log_to_console': True, 06:01:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:01:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:01:32 INFO - 'opt_config_files': (), 06:01:32 INFO - 'pip_index': False, 06:01:32 INFO - 'suite': 'dromaeojs-e10s', 06:01:32 INFO - 'system_bits': '32', 06:01:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:01:32 INFO - 'title': 'talos-linux64-ix-037', 06:01:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:01:32 INFO - 'use_talos_json': True, 06:01:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:01:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:01:32 INFO - 'work_dir': 'build'} 06:01:32 INFO - [mozharness: 2017-07-25 13:01:32.879126Z] Running clobber step. 06:01:32 INFO - Running pre-action listener: _resource_record_pre_action 06:01:32 INFO - Running main action method: clobber 06:01:32 INFO - rmtree: /builds/slave/test/build 06:01:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:01:34 INFO - Running post-action listener: _resource_record_post_action 06:01:34 INFO - [mozharness: 2017-07-25 13:01:34.415148Z] Finished clobber step (success) 06:01:34 INFO - [mozharness: 2017-07-25 13:01:34.415249Z] Running read-buildbot-config step. 06:01:34 INFO - Running pre-action listener: _resource_record_pre_action 06:01:34 INFO - Running main action method: read_buildbot_config 06:01:34 INFO - Using buildbot properties: 06:01:34 INFO - { 06:01:34 INFO - "project": "", 06:01:34 INFO - "product": "firefox", 06:01:34 INFO - "who": "cbook@mozilla.com", 06:01:34 INFO - "installer_path": "public/build/target.tar.bz2", 06:01:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 06:01:34 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 06:01:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 06:01:34 INFO - "stage_platform": "linux64", 06:01:34 INFO - "basedir": "/builds/slave/test", 06:01:34 INFO - "buildnumber": 742, 06:01:34 INFO - "platform": "ubuntu64_hw", 06:01:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 06:01:34 INFO - "slavebuilddir": "test", 06:01:34 INFO - "taskId": "JlqH0zloQ3-OwS6RyBRTkA", 06:01:34 INFO - "branch": "mozilla-central", 06:01:34 INFO - "script_repo_revision": "production", 06:01:34 INFO - "revision": "80394cbcae0f02da8eb801edd92e56f974e5db4f", 06:01:34 INFO - "slavename": "talos-linux64-ix-037", 06:01:34 INFO - "repo_path": "mozilla-central" 06:01:34 INFO - } 06:01:34 INFO - Finding installer, test and symbols from parent task. 06:01:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JlqH0zloQ3-OwS6RyBRTkA'}, attempt #1 06:01:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JlqH0zloQ3-OwS6RyBRTkA'}, attempt #1 06:01:35 INFO - Task dependencies: Yn2UwQizTWWRdDlPQ6LLig 06:01:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig'}, attempt #1 06:01:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.tar.bz2 06:01:35 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/test_packages.json 06:01:35 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.crashreporter-symbols.zip 06:01:35 INFO - Running post-action listener: _resource_record_post_action 06:01:35 INFO - [mozharness: 2017-07-25 13:01:35.762146Z] Finished read-buildbot-config step (success) 06:01:35 INFO - [mozharness: 2017-07-25 13:01:35.762368Z] Running download-and-extract step. 06:01:35 INFO - Running pre-action listener: _resource_record_pre_action 06:01:35 INFO - Running main action method: download_and_extract 06:01:35 INFO - mkdir: /builds/slave/test/build/tests 06:01:35 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')]} 06:01:35 INFO - trying https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.test_packages.json 06:01:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 06:01:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 06:01:36 INFO - Downloaded 989 bytes. 06:01:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 06:01:36 INFO - Using the following test package requirements: 06:01:36 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 06:01:36 INFO - u'common': [u'target.common.tests.zip'], 06:01:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 06:01:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 06:01:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 06:01:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 06:01:36 INFO - u'mozbase': [u'target.common.tests.zip'], 06:01:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 06:01:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 06:01:36 INFO - u'web-platform': [u'target.common.tests.zip', 06:01:36 INFO - u'target.web-platform.tests.tar.gz'], 06:01:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 06:01:36 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 06:01:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.common.tests.zip 06:01:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:01:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.common.tests.zip into memory 06:01:37 INFO - Content-Length response header: 72761499 06:01:37 INFO - Bytes received: 72761499 06:01:48 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 06:01:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.common.tests.zip 06:01:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:01:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.common.tests.zip into memory 06:01:49 INFO - Content-Length response header: 72761499 06:01:49 INFO - Bytes received: 72761499 06:02:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.talos.tests.zip 06:02:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.talos.tests.zip'}, attempt #1 06:02:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.talos.tests.zip into memory 06:02:02 INFO - Content-Length response header: 14498866 06:02:02 INFO - Bytes received: 14498866 06:02:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:02:02 INFO - trying https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.tar.bz2 06:02:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 06:02:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 06:02:04 INFO - Downloaded 62053083 bytes. 06:02:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.tar.bz2 06:02:04 INFO - mkdir: /builds/slave/test/properties 06:02:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:02:04 INFO - Writing to file /builds/slave/test/properties/build_url 06:02:04 INFO - Contents: 06:02:04 INFO - build_url:https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.tar.bz2 06:02:04 INFO - Running post-action listener: _resource_record_post_action 06:02:04 INFO - Running post-action listener: set_extra_try_arguments 06:02:04 INFO - [mozharness: 2017-07-25 13:02:04.076132Z] Finished download-and-extract step (success) 06:02:04 INFO - [mozharness: 2017-07-25 13:02:04.076229Z] Running populate-webroot step. 06:02:04 INFO - Running pre-action listener: _resource_record_pre_action 06:02:04 INFO - Running main action method: populate_webroot 06:02:04 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:02:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:02:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:02:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:02:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:02:04 INFO - 'tests': ['damp', 'tps']}, 06:02:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:02:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 06:02:04 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 06:02:04 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 06:02:04 INFO - 'other-e10s': {'tests': ['a11yr', 06:02:04 INFO - 'ts_paint', 06:02:04 INFO - 'tpaint', 06:02:04 INFO - 'sessionrestore', 06:02:04 INFO - 'sessionrestore_many_windows', 06:02:04 INFO - 'sessionrestore_no_auto_restore', 06:02:04 INFO - 'tabpaint']}, 06:02:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 06:02:04 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 06:02:04 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:02:04 INFO - 'talos_options': ['--mitmproxy', 06:02:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:02:04 INFO - '--firstNonBlankPaint'], 06:02:04 INFO - 'tests': ['quantum_pageload_google', 06:02:04 INFO - 'quantum_pageload_youtube', 06:02:04 INFO - 'quantum_pageload_amazon', 06:02:04 INFO - 'quantum_pageload_facebook']}, 06:02:04 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:02:04 INFO - 'talos_options': ['--stylo', 06:02:04 INFO - '--mitmproxy', 06:02:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:02:04 INFO - '--firstNonBlankPaint'], 06:02:04 INFO - 'tests': ['quantum_pageload_google', 06:02:04 INFO - 'quantum_pageload_youtube', 06:02:04 INFO - 'quantum_pageload_amazon', 06:02:04 INFO - 'quantum_pageload_facebook']}, 06:02:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:02:04 INFO - 'tsvgr_opacity', 06:02:04 INFO - 'tart', 06:02:04 INFO - 'tscrollx', 06:02:04 INFO - 'cart', 06:02:04 INFO - 'tsvg_static']}, 06:02:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:02:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:02:04 INFO - 'talos_options': ['--xperf_path', 06:02:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:02:04 INFO - 'tests': ['tp5n']}}} 06:02:04 INFO - Running post-action listener: _resource_record_post_action 06:02:04 INFO - [mozharness: 2017-07-25 13:02:04.083554Z] Finished populate-webroot step (success) 06:02:04 INFO - [mozharness: 2017-07-25 13:02:04.083645Z] Running create-virtualenv step. 06:02:04 INFO - Running pre-action listener: _resource_record_pre_action 06:02:04 INFO - Running main action method: create_virtualenv 06:02:04 INFO - Creating virtualenv /builds/slave/test/build/venv 06:02: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 06:02:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:02:04 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 06:02:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:02:04 INFO - Using real prefix '/usr' 06:02:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:02:04 INFO - Installing distribute.............................................................................................................................................................................................done. 06:02:06 INFO - Installing pip.................done. 06:02:06 INFO - Return code: 0 06:02:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:02:06 INFO - Reading from file tmpfile_stdout 06:02:06 INFO - Output received: 06:02:06 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) 06:02:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02:06 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 0x202d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239aeb0>, '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 0x239bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c240>, '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-1500981757.417677-267865685', '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 06:02:06 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 06:02:06 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 06:02:06 INFO - Using env: {'DISPLAY': ':0', 06:02:06 INFO - 'HOME': '/home/cltbld', 06:02:06 INFO - 'LANG': 'en_US.UTF-8', 06:02:06 INFO - 'LANGUAGE': 'en_US:en', 06:02:06 INFO - 'LOGNAME': 'cltbld', 06:02:06 INFO - 'MAIL': '/var/mail/cltbld', 06:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:06 INFO - 'MOZ_NO_REMOTE': '1', 06:02:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:02:06 INFO - 'NO_EM_RESTART': '1', 06:02:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:06 INFO - 'PWD': '/builds/slave/test', 06:02:06 INFO - 'SHELL': '/bin/bash', 06:02:06 INFO - 'SHLVL': '1', 06:02:06 INFO - 'TERM': 'linux', 06:02:06 INFO - 'TMOUT': '86400', 06:02:06 INFO - 'USER': 'cltbld', 06:02:06 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500981757.417677-267865685', 06:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:06 INFO - '_': '/tools/buildbot/bin/python'} 06:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02: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 06:02:07 INFO - Cleaning up... 06:02:07 INFO - Return code: 0 06:02:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:02: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')]} 06:02:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02: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')]} 06:02:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02: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 0x202d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239aeb0>, '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 0x239bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c240>, '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-1500981757.417677-267865685', '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 06:02: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 06:02: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 06:02:07 INFO - Using env: {'DISPLAY': ':0', 06:02:07 INFO - 'HOME': '/home/cltbld', 06:02:07 INFO - 'LANG': 'en_US.UTF-8', 06:02:07 INFO - 'LANGUAGE': 'en_US:en', 06:02:07 INFO - 'LOGNAME': 'cltbld', 06:02:07 INFO - 'MAIL': '/var/mail/cltbld', 06:02:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:07 INFO - 'MOZ_NO_REMOTE': '1', 06:02:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:02:07 INFO - 'NO_EM_RESTART': '1', 06:02:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:02:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:07 INFO - 'PWD': '/builds/slave/test', 06:02:07 INFO - 'SHELL': '/bin/bash', 06:02:07 INFO - 'SHLVL': '1', 06:02:07 INFO - 'TERM': 'linux', 06:02:07 INFO - 'TMOUT': '86400', 06:02:07 INFO - 'USER': 'cltbld', 06:02:07 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500981757.417677-267865685', 06:02:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:07 INFO - '_': '/tools/buildbot/bin/python'} 06:02:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:07 INFO - Downloading/unpacking psutil>=3.1.1 06:02: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 06:02: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 06:02: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 06:02: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 06:02:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:02:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:02:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:02:08 INFO - Installing collected packages: psutil 06:02:08 INFO - Running setup.py install for psutil 06:02:08 INFO - building 'psutil._psutil_linux' extension 06:02: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 06:02: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 06:02:08 INFO - building 'psutil._psutil_posix' extension 06:02: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 06:02: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_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 06:02:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:02:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:02:08 INFO - Successfully installed psutil 06:02:08 INFO - Cleaning up... 06:02:09 INFO - Return code: 0 06:02:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:02: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')]} 06:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02: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')]} 06:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02: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 0x202d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239aeb0>, '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 0x239bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c240>, '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-1500981757.417677-267865685', '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 06:02: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 06:02: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 06:02:09 INFO - Using env: {'DISPLAY': ':0', 06:02:09 INFO - 'HOME': '/home/cltbld', 06:02:09 INFO - 'LANG': 'en_US.UTF-8', 06:02:09 INFO - 'LANGUAGE': 'en_US:en', 06:02:09 INFO - 'LOGNAME': 'cltbld', 06:02:09 INFO - 'MAIL': '/var/mail/cltbld', 06:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:09 INFO - 'MOZ_NO_REMOTE': '1', 06:02:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:02:09 INFO - 'NO_EM_RESTART': '1', 06:02:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:09 INFO - 'PWD': '/builds/slave/test', 06:02:09 INFO - 'SHELL': '/bin/bash', 06:02:09 INFO - 'SHLVL': '1', 06:02:09 INFO - 'TERM': 'linux', 06:02:09 INFO - 'TMOUT': '86400', 06:02:09 INFO - 'USER': 'cltbld', 06:02:09 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500981757.417677-267865685', 06:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:09 INFO - '_': '/tools/buildbot/bin/python'} 06:02:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:02: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 06:02: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 06:02: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 06:02: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 06:02:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:02:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:02:10 INFO - Installing collected packages: mozsystemmonitor 06:02:10 INFO - Running setup.py install for mozsystemmonitor 06:02:10 INFO - Successfully installed mozsystemmonitor 06:02:10 INFO - Cleaning up... 06:02:10 INFO - Return code: 0 06:02:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:02: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')]} 06:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02: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')]} 06:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02: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 0x202d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239aeb0>, '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 0x239bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c240>, '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-1500981757.417677-267865685', '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 06:02: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 06:02: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 06:02:10 INFO - Using env: {'DISPLAY': ':0', 06:02:10 INFO - 'HOME': '/home/cltbld', 06:02:10 INFO - 'LANG': 'en_US.UTF-8', 06:02:10 INFO - 'LANGUAGE': 'en_US:en', 06:02:10 INFO - 'LOGNAME': 'cltbld', 06:02:10 INFO - 'MAIL': '/var/mail/cltbld', 06:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:10 INFO - 'MOZ_NO_REMOTE': '1', 06:02:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:02:10 INFO - 'NO_EM_RESTART': '1', 06:02:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:10 INFO - 'PWD': '/builds/slave/test', 06:02:10 INFO - 'SHELL': '/bin/bash', 06:02:10 INFO - 'SHLVL': '1', 06:02:10 INFO - 'TERM': 'linux', 06:02:10 INFO - 'TMOUT': '86400', 06:02:10 INFO - 'USER': 'cltbld', 06:02:10 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500981757.417677-267865685', 06:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:10 INFO - '_': '/tools/buildbot/bin/python'} 06:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:10 INFO - Downloading/unpacking jsonschema==2.5.1 06:02: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 06:02: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 06:02: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 06:02: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 06:02:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:02:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:02:11 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 06:02:11 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 06:02:11 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 06:02:11 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 06:02:11 INFO - Downloading functools32-3.2.3-2.tar.gz 06:02:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:02:12 INFO - warning: no files found matching '*.txt' 06:02:12 INFO - no previously-included directories found matching 'build' 06:02:12 INFO - no previously-included directories found matching 'dist' 06:02:12 INFO - no previously-included directories found matching '.git*' 06:02:12 INFO - Installing collected packages: jsonschema, functools32 06:02:12 INFO - Running setup.py install for functools32 06:02:12 INFO - warning: no files found matching '*.txt' 06:02:12 INFO - no previously-included directories found matching 'build' 06:02:12 INFO - no previously-included directories found matching 'dist' 06:02:12 INFO - no previously-included directories found matching '.git*' 06:02:12 INFO - Successfully installed jsonschema functools32 06:02:12 INFO - Cleaning up... 06:02:12 INFO - Return code: 0 06:02:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:02: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')]} 06:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02: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')]} 06:02:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02: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 0x202d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239aeb0>, '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 0x239bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c240>, '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-1500981757.417677-267865685', '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 06:02: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 06:02: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 06:02:12 INFO - Using env: {'DISPLAY': ':0', 06:02:12 INFO - 'HOME': '/home/cltbld', 06:02:12 INFO - 'LANG': 'en_US.UTF-8', 06:02:12 INFO - 'LANGUAGE': 'en_US:en', 06:02:12 INFO - 'LOGNAME': 'cltbld', 06:02:12 INFO - 'MAIL': '/var/mail/cltbld', 06:02:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:12 INFO - 'MOZ_NO_REMOTE': '1', 06:02:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:02:12 INFO - 'NO_EM_RESTART': '1', 06:02:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:02:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:12 INFO - 'PWD': '/builds/slave/test', 06:02:12 INFO - 'SHELL': '/bin/bash', 06:02:12 INFO - 'SHLVL': '1', 06:02:12 INFO - 'TERM': 'linux', 06:02:12 INFO - 'TMOUT': '86400', 06:02:12 INFO - 'USER': 'cltbld', 06:02:12 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500981757.417677-267865685', 06:02:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:12 INFO - '_': '/tools/buildbot/bin/python'} 06:02:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:02:12 INFO - Cleaning up... 06:02:12 INFO - Return code: 0 06:02:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:02: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')]} 06:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02: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')]} 06:02:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02: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 0x202d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239aeb0>, '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 0x239bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c240>, '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-1500981757.417677-267865685', '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 06:02: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 06:02: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 06:02:12 INFO - Using env: {'DISPLAY': ':0', 06:02:12 INFO - 'HOME': '/home/cltbld', 06:02:12 INFO - 'LANG': 'en_US.UTF-8', 06:02:12 INFO - 'LANGUAGE': 'en_US:en', 06:02:12 INFO - 'LOGNAME': 'cltbld', 06:02:12 INFO - 'MAIL': '/var/mail/cltbld', 06:02:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:12 INFO - 'MOZ_NO_REMOTE': '1', 06:02:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:02:12 INFO - 'NO_EM_RESTART': '1', 06:02:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:02:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:12 INFO - 'PWD': '/builds/slave/test', 06:02:12 INFO - 'SHELL': '/bin/bash', 06:02:12 INFO - 'SHLVL': '1', 06:02:12 INFO - 'TERM': 'linux', 06:02:12 INFO - 'TMOUT': '86400', 06:02:12 INFO - 'USER': 'cltbld', 06:02:12 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500981757.417677-267865685', 06:02:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:12 INFO - '_': '/tools/buildbot/bin/python'} 06:02:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:12 INFO - Downloading/unpacking blobuploader==1.2.4 06:02: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 06:02: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 06:02: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 06:02: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 06:02:13 INFO - Downloading blobuploader-1.2.4.tar.gz 06:02:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:02:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:02:13 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 06:02:13 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 06:02:13 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 06:02:13 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 06:02:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:02:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:02: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 06:02: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 06:02: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 06:02: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 06:02:14 INFO - Downloading docopt-0.6.1.tar.gz 06:02:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:02:14 INFO - Installing collected packages: blobuploader, requests, docopt 06:02:14 INFO - Running setup.py install for blobuploader 06:02:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:02:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:02:14 INFO - Running setup.py install for requests 06:02:14 INFO - Running setup.py install for docopt 06:02:14 INFO - Successfully installed blobuploader requests docopt 06:02:14 INFO - Cleaning up... 06:02:14 INFO - Return code: 0 06:02:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:02:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:02:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02:14 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 0x202d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239aeb0>, '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 0x239bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c240>, '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-1500981757.417677-267865685', '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 06:02:14 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 06:02:14 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 06:02:14 INFO - Using env: {'DISPLAY': ':0', 06:02:14 INFO - 'HOME': '/home/cltbld', 06:02:14 INFO - 'LANG': 'en_US.UTF-8', 06:02:14 INFO - 'LANGUAGE': 'en_US:en', 06:02:14 INFO - 'LOGNAME': 'cltbld', 06:02:14 INFO - 'MAIL': '/var/mail/cltbld', 06:02:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:14 INFO - 'MOZ_NO_REMOTE': '1', 06:02:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:02:14 INFO - 'NO_EM_RESTART': '1', 06:02:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:02:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:14 INFO - 'PWD': '/builds/slave/test', 06:02:14 INFO - 'SHELL': '/bin/bash', 06:02:14 INFO - 'SHLVL': '1', 06:02:14 INFO - 'TERM': 'linux', 06:02:14 INFO - 'TMOUT': '86400', 06:02:14 INFO - 'USER': 'cltbld', 06:02:14 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500981757.417677-267865685', 06:02:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:14 INFO - '_': '/tools/buildbot/bin/python'} 06:02:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:02:15 INFO - Running setup.py (path:/tmp/pip-8Kc1mt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:02:15 INFO - Running setup.py (path:/tmp/pip-_15q40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:02:15 INFO - Running setup.py (path:/tmp/pip-_C0sIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:02:15 INFO - Running setup.py (path:/tmp/pip-LegBHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:02:15 INFO - Running setup.py (path:/tmp/pip-41mdgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:02:15 INFO - Running setup.py (path:/tmp/pip-XY2DOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:02:15 INFO - Running setup.py (path:/tmp/pip-tQL8AS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:02:15 INFO - Running setup.py (path:/tmp/pip-EVR3_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:02:15 INFO - Running setup.py (path:/tmp/pip-gl6WlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:02:15 INFO - Running setup.py (path:/tmp/pip-V3fsCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:02:15 INFO - Running setup.py (path:/tmp/pip-5PR4jZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:02:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:02:15 INFO - Running setup.py (path:/tmp/pip-WeT8DN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:02:16 INFO - Running setup.py (path:/tmp/pip-Me0Oh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:02:16 INFO - Running setup.py (path:/tmp/pip-Qp0XAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:02:16 INFO - Running setup.py (path:/tmp/pip-BmMh4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:02:16 INFO - Running setup.py (path:/tmp/pip-UiweUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:02:16 INFO - Running setup.py (path:/tmp/pip-OdcS9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:02:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:02:16 INFO - Running setup.py install for manifestparser 06:02:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:02:16 INFO - Running setup.py install for mozcrash 06:02:16 INFO - Running setup.py install for mozdebug 06:02:16 INFO - Running setup.py install for mozdevice 06:02:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:02:16 INFO - Running setup.py install for mozfile 06:02:16 INFO - Running setup.py install for mozhttpd 06:02:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:02:17 INFO - Running setup.py install for mozinfo 06:02:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:02:17 INFO - Running setup.py install for mozInstall 06:02:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:02:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:02:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:02:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:02:17 INFO - Running setup.py install for mozleak 06:02:17 INFO - Running setup.py install for mozlog 06:02:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:02:17 INFO - Running setup.py install for moznetwork 06:02:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:02:17 INFO - Running setup.py install for mozprocess 06:02:17 INFO - Running setup.py install for mozprofile 06:02:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:02:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:02:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:02:17 INFO - Running setup.py install for mozrunner 06:02:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:02:17 INFO - Running setup.py install for mozscreenshot 06:02:17 INFO - Running setup.py install for moztest 06:02:18 INFO - Running setup.py install for mozversion 06:02:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:02:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:02:18 INFO - Cleaning up... 06:02:18 INFO - Return code: 0 06:02:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:02: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')]} 06:02:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02: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')]} 06:02:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02: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 0x202d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239aeb0>, '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 0x239bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c240>, '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-1500981757.417677-267865685', '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 06:02: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 06:02: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 06:02:18 INFO - Using env: {'DISPLAY': ':0', 06:02:18 INFO - 'HOME': '/home/cltbld', 06:02:18 INFO - 'LANG': 'en_US.UTF-8', 06:02:18 INFO - 'LANGUAGE': 'en_US:en', 06:02:18 INFO - 'LOGNAME': 'cltbld', 06:02:18 INFO - 'MAIL': '/var/mail/cltbld', 06:02:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:18 INFO - 'MOZ_NO_REMOTE': '1', 06:02:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:02:18 INFO - 'NO_EM_RESTART': '1', 06:02:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:02:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:18 INFO - 'PWD': '/builds/slave/test', 06:02:18 INFO - 'SHELL': '/bin/bash', 06:02:18 INFO - 'SHLVL': '1', 06:02:18 INFO - 'TERM': 'linux', 06:02:18 INFO - 'TMOUT': '86400', 06:02:18 INFO - 'USER': 'cltbld', 06:02:18 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500981757.417677-267865685', 06:02:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:18 INFO - '_': '/tools/buildbot/bin/python'} 06:02:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:02:18 INFO - Running setup.py (path:/tmp/pip-NdxwDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:02: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)) 06:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:02:18 INFO - Running setup.py (path:/tmp/pip-HSmvSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:02: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)) 06:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:02:18 INFO - Running setup.py (path:/tmp/pip-6c03OS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:02: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)) 06:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:02:18 INFO - Running setup.py (path:/tmp/pip-RJabBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:02: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)) 06:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:02:18 INFO - Running setup.py (path:/tmp/pip-IYAkba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:02: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)) 06:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:02:18 INFO - Running setup.py (path:/tmp/pip-gb_9LI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:02:18 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)) 06:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:02:18 INFO - Running setup.py (path:/tmp/pip-SJARox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:02:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:02:18 INFO - Running setup.py (path:/tmp/pip-Pax2W9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:02:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:02:18 INFO - Running setup.py (path:/tmp/pip-aku5R4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:02: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)) 06:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:02:19 INFO - Running setup.py (path:/tmp/pip-VVkQsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:02:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:02:19 INFO - Running setup.py (path:/tmp/pip-mdzYrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:02: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)) 06:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:02:19 INFO - Running setup.py (path:/tmp/pip-0k5t_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:02: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)) 06:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:02:19 INFO - Running setup.py (path:/tmp/pip-7e6OoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:02: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)) 06:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:02:19 INFO - Running setup.py (path:/tmp/pip-l9hL2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:02: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)) 06:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:02:19 INFO - Running setup.py (path:/tmp/pip-YwaW8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:02: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)) 06:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:02:19 INFO - Running setup.py (path:/tmp/pip-eJV_Gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:02: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)) 06:02:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:02:19 INFO - Running setup.py (path:/tmp/pip-yJPcM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:02: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)) 06:02: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)) 06:02: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)) 06:02: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)) 06:02: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)) 06:02: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)) 06:02:19 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:02:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:02: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 06:02: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 06:02: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 06:02: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 06:02:20 INFO - Downloading blessings-1.6.tar.gz 06:02:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:02:20 INFO - Installing collected packages: blessings 06:02:20 INFO - Running setup.py install for blessings 06:02:20 INFO - Successfully installed blessings 06:02:20 INFO - Cleaning up... 06:02:20 INFO - Return code: 0 06:02:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:02:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:02:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:02:21 INFO - Reading from file tmpfile_stdout 06:02:21 INFO - Current package versions: 06:02:21 INFO - argparse == 1.2.1 06:02:21 INFO - blessings == 1.6 06:02:21 INFO - blobuploader == 1.2.4 06:02:21 INFO - docopt == 0.6.1 06:02:21 INFO - functools32 == 3.2.3-2 06:02:21 INFO - jsonschema == 2.5.1 06:02:21 INFO - manifestparser == 1.1 06:02:21 INFO - mozInstall == 1.13 06:02:21 INFO - mozcrash == 1.0 06:02:21 INFO - mozdebug == 0.1 06:02:21 INFO - mozdevice == 0.51 06:02:21 INFO - mozfile == 1.2 06:02:21 INFO - mozhttpd == 0.7 06:02:21 INFO - mozinfo == 0.10 06:02:21 INFO - mozleak == 0.1 06:02:21 INFO - mozlog == 3.5 06:02:21 INFO - moznetwork == 0.27 06:02:21 INFO - mozprocess == 0.25 06:02:21 INFO - mozprofile == 0.28 06:02:21 INFO - mozrunner == 6.13 06:02:21 INFO - mozscreenshot == 0.1 06:02:21 INFO - mozsystemmonitor == 0.3 06:02:21 INFO - moztest == 0.8 06:02:21 INFO - mozversion == 1.4 06:02:21 INFO - psutil == 3.1.1 06:02:21 INFO - requests == 1.2.3 06:02:21 INFO - wsgiref == 0.1.2 06:02:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:02: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')]} 06:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02: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')]} 06:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02: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 0x202d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239aeb0>, '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 0x239bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c240>, '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-1500981757.417677-267865685', '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 06:02: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 06:02: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 06:02:21 INFO - Using env: {'DISPLAY': ':0', 06:02:21 INFO - 'HOME': '/home/cltbld', 06:02:21 INFO - 'LANG': 'en_US.UTF-8', 06:02:21 INFO - 'LANGUAGE': 'en_US:en', 06:02:21 INFO - 'LOGNAME': 'cltbld', 06:02:21 INFO - 'MAIL': '/var/mail/cltbld', 06:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:21 INFO - 'MOZ_NO_REMOTE': '1', 06:02:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:02:21 INFO - 'NO_EM_RESTART': '1', 06:02:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:21 INFO - 'PWD': '/builds/slave/test', 06:02:21 INFO - 'SHELL': '/bin/bash', 06:02:21 INFO - 'SHLVL': '1', 06:02:21 INFO - 'TERM': 'linux', 06:02:21 INFO - 'TMOUT': '86400', 06:02:21 INFO - 'USER': 'cltbld', 06:02:21 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500981757.417677-267865685', 06:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:21 INFO - '_': '/tools/buildbot/bin/python'} 06:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02: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)) 06:02: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)) 06:02: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)) 06:02: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)) 06:02: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)) 06:02: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)) 06:02: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)) 06:02: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)) 06:02: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)) 06:02:21 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:02: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 06:02: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 06:02: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 06:02: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 06:02:22 INFO - Downloading marionette_driver-1.4.0.tar.gz 06:02:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 06:02:22 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 06:02:22 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 06:02:22 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 06:02:22 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 06:02:22 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 06:02:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 06:02: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)) 06:02: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)) 06:02: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)) 06:02: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)) 06:02:22 INFO - Installing collected packages: marionette-driver, simplejson 06:02:22 INFO - Running setup.py install for marionette-driver 06:02:22 INFO - Running setup.py install for simplejson 06:02:23 INFO - building 'simplejson._speedups' extension 06:02:23 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 06:02:23 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 06:02:23 INFO - Successfully installed marionette-driver simplejson 06:02:23 INFO - Cleaning up... 06:02:23 INFO - Return code: 0 06:02:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:02:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:02:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:02:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:02:23 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 0x202d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21faa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x239aeb0>, '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 0x239bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c240>, '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-1500981757.417677-267865685', '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 06:02:23 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 06:02:23 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 06:02:23 INFO - Using env: {'DISPLAY': ':0', 06:02:23 INFO - 'HOME': '/home/cltbld', 06:02:23 INFO - 'LANG': 'en_US.UTF-8', 06:02:23 INFO - 'LANGUAGE': 'en_US:en', 06:02:23 INFO - 'LOGNAME': 'cltbld', 06:02:23 INFO - 'MAIL': '/var/mail/cltbld', 06:02:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:23 INFO - 'MOZ_NO_REMOTE': '1', 06:02:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:02:23 INFO - 'NO_EM_RESTART': '1', 06:02:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:02:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:23 INFO - 'PWD': '/builds/slave/test', 06:02:23 INFO - 'SHELL': '/bin/bash', 06:02:23 INFO - 'SHLVL': '1', 06:02:23 INFO - 'TERM': 'linux', 06:02:23 INFO - 'TMOUT': '86400', 06:02:23 INFO - 'USER': 'cltbld', 06:02:23 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500981757.417677-267865685', 06:02:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:23 INFO - '_': '/tools/buildbot/bin/python'} 06:02:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:02:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:02:24 INFO - Cleaning up... 06:02:24 INFO - Return code: 0 06:02:24 INFO - Running post-action listener: _resource_record_post_action 06:02:24 INFO - Running post-action listener: _start_resource_monitoring 06:02:24 INFO - Starting resource monitoring. 06:02:24 INFO - [mozharness: 2017-07-25 13:02:24.262221Z] Finished create-virtualenv step (success) 06:02:24 INFO - [mozharness: 2017-07-25 13:02:24.262687Z] Running install step. 06:02:24 INFO - Running pre-action listener: _resource_record_pre_action 06:02:24 INFO - Running main action method: install 06:02:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:02:24 INFO - Reading from file tmpfile_stdout 06:02:24 INFO - Detecting whether we're running mozinstall >=1.0... 06:02:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:02:24 INFO - Reading from file tmpfile_stdout 06:02:24 INFO - Output received: 06:02:24 INFO - Usage: mozinstall [options] installer 06:02:24 INFO - Options: 06:02:24 INFO - -h, --help show this help message and exit 06:02:24 INFO - -d DEST, --destination=DEST 06:02:24 INFO - Directory to install application into. [default: 06:02:24 INFO - "/builds/slave/test"] 06:02:24 INFO - --app=APP Application being installed. [default: firefox] 06:02:24 INFO - mkdir: /builds/slave/test/build/application 06:02:24 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'] 06:02:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 06:02:42 INFO - Reading from file tmpfile_stdout 06:02:42 INFO - Output received: 06:02:42 INFO - /builds/slave/test/build/application/firefox/firefox 06:02:42 INFO - Running post-action listener: _resource_record_post_action 06:02:42 INFO - [mozharness: 2017-07-25 13:02:42.559506Z] Finished install step (success) 06:02:42 INFO - [mozharness: 2017-07-25 13:02:42.559759Z] Running setup-mitmproxy step. 06:02:42 INFO - Running pre-action listener: _resource_record_pre_action 06:02:42 INFO - Running main action method: setup_mitmproxy 06:02:42 INFO - Skipping: mitmproxy is not required 06:02:42 INFO - Running post-action listener: _resource_record_post_action 06:02:42 INFO - [mozharness: 2017-07-25 13:02:42.560891Z] Finished setup-mitmproxy step (success) 06:02:42 INFO - [mozharness: 2017-07-25 13:02:42.561112Z] Running run-tests step. 06:02:42 INFO - Running pre-action listener: _resource_record_pre_action 06:02:42 INFO - Running pre-action listener: _set_gcov_prefix 06:02:42 INFO - Running main action method: run_tests 06:02:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:02:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:02:42 INFO - Python 2.7.3 06:02:42 INFO - Return code: 0 06:02:42 INFO - grabbing minidump binary from tooltool 06:02:42 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')]} 06:02:42 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 0x239aeb0>, '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 0x239bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x239c240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:02:42 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 06:02:42 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 06:02:42 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 06:02:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:02:42 INFO - Return code: 0 06:02:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:02:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:02:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:02:42 INFO - ENV: RUST_BACKTRACE is now 1 06:02:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:02:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:02:42 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/Yn2UwQizTWWRdDlPQ6LLig/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 06:02:42 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/Yn2UwQizTWWRdDlPQ6LLig/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 06:02:42 INFO - Using env: {'DISPLAY': ':0', 06:02:42 INFO - 'HOME': '/home/cltbld', 06:02:42 INFO - 'LANG': 'en_US.UTF-8', 06:02:42 INFO - 'LANGUAGE': 'en_US:en', 06:02:42 INFO - 'LOGNAME': 'cltbld', 06:02:42 INFO - 'MAIL': '/var/mail/cltbld', 06:02:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:02:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:02:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:02:42 INFO - 'MOZ_NO_REMOTE': '1', 06:02:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:02:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:02:42 INFO - 'NO_EM_RESTART': '1', 06:02:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:02:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:02:42 INFO - 'PWD': '/builds/slave/test', 06:02:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:02:42 INFO - 'RUST_BACKTRACE': '1', 06:02:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:02:42 INFO - 'SHELL': '/bin/bash', 06:02:42 INFO - 'SHLVL': '1', 06:02:42 INFO - 'TERM': 'linux', 06:02:42 INFO - 'TMOUT': '86400', 06:02:42 INFO - 'USER': 'cltbld', 06:02:42 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500981757.417677-267865685', 06:02:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:02:42 INFO - '_': '/tools/buildbot/bin/python'} 06:02:42 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/Yn2UwQizTWWRdDlPQ6LLig/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 06:02:42 INFO - mozversion application_buildid: 20170725122010 06:02:42 INFO - mozversion application_changeset: 80394cbcae0f02da8eb801edd92e56f974e5db4f 06:02:42 INFO - mozversion application_display_name: Nightly 06:02:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:02:42 INFO - mozversion application_name: Firefox 06:02:42 INFO - mozversion application_remotingname: firefox 06:02:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:02:42 INFO - mozversion application_vendor: Mozilla 06:02:42 INFO - mozversion application_version: 56.0a1 06:02:42 INFO - mozversion platform_buildid: 20170725122010 06:02:42 INFO - mozversion platform_changeset: 80394cbcae0f02da8eb801edd92e56f974e5db4f 06:02:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:02:42 INFO - mozversion platform_version: 56.0a1 06:02:42 INFO - using testdate: 1500987762 06:02:42 INFO - actual date: 1500987762 06:02:42 INFO - starting webserver on 'localhost:58430' 06:02:42 INFO - SUITE-START | Running 2 tests 06:02:42 INFO - TEST-START | dromaeo_css 06:02:42 INFO - Initialising browser for dromaeo_css test... 06:02:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58430/getInfo.html -profile /tmp/tmpXe4V3h/profile 06:02:42 INFO - TEST-INFO | started process 11790 (/builds/slave/test/build/application/firefox/firefox http://localhost:58430/getInfo.html) 06:02:48 INFO - TEST-INFO | 11790: exit 0 06:02:48 INFO - Browser initialized. 06:02:48 INFO - Running cycle 1/1 for dromaeo_css test... 06:02:48 INFO - TEST-INFO | started process 11997 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXe4V3h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 06:02:59 INFO - PID 11997 | RSS: Main: 171724800 06:02:59 INFO - PID 11997 | 06:05:59 INFO - PID 11997 | Cycle 1(1): loaded http://localhost:58430/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58430/tests/dromaeo/cssquery-ext.html) 06:06:00 INFO - PID 11997 | RSS: Main: 173244416 06:06:00 INFO - PID 11997 | 06:09:02 INFO - PID 11997 | Cycle 1(1): loaded http://localhost:58430/tests/dromaeo/cssquery-ext.html (next: http://localhost:58430/tests/dromaeo/cssquery-jquery.html) 06:09:02 INFO - PID 11997 | RSS: Main: 170770432 06:09:02 INFO - PID 11997 | 06:12:04 INFO - PID 11997 | Cycle 1(1): loaded http://localhost:58430/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58430/tests/dromaeo/cssquery-mootools.html) 06:12:05 INFO - PID 11997 | RSS: Main: 172716032 06:12:05 INFO - PID 11997 | 06:15:06 INFO - PID 11997 | Cycle 1(1): loaded http://localhost:58430/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58430/tests/dromaeo/cssquery-prototype.html) 06:15:07 INFO - PID 11997 | RSS: Main: 171524096 06:15:07 INFO - PID 11997 | 06:18:11 INFO - PID 11997 | Cycle 1(1): loaded http://localhost:58430/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58430/tests/dromaeo/cssquery-yui.html) 06:18:11 INFO - PID 11997 | RSS: Main: 171593728 06:18:11 INFO - PID 11997 | 06:21:13 INFO - PID 11997 | Cycle 1(1): loaded http://localhost:58430/tests/dromaeo/cssquery-yui.html (next: http://localhost:58430/tests/dromaeo/cssquery-dojo.html) 06:21:13 INFO - PID 11997 | RSS: Main: 175173632 06:21:13 INFO - PID 11997 | 06:21:13 INFO - PID 11997 | __start_tp_report 06:21:13 INFO - PID 11997 | _x_x_mozilla_page_load 06:21:13 INFO - PID 11997 | _x_x_mozilla_page_load_details 06:21:13 INFO - PID 11997 | |i|pagename|runs| 06:21:13 INFO - PID 11997 | |0;dojo.html;3113.470709979303;3156.8421578921048;3170.7780455368124;3194.1375828526293;3198.088544764741;6015.939840601593;6104.877902441948;6196.628202307865;6203.813885583429;6247.406496382842;6125.846853828645;6165.383461653843;6182.443580077792;6207.28616209135;6207.37926207379;5641.802536911209;5650.49145576898;5661.094224924013;5666.178404131399;5766.625169363999;5663.348714897777;5713.17159011943;5734.426557344253;5747.310322761254;5754.683492407916;5452;5605.523530499912;5655.26481557397;5677.40387259336;5686.971565142168;5624.690642014688;5701.629394089412;5709.885802283972;5720.513756330717;5725.341585717654;5561.304836895388;5597.244372009767;5611.691356975365;5635.042042852725;5681.857953551153;6122.571420000601;6137.877242455126;6138.232720909887;6163.2604087509335;6185.783497577609;6532.608043517356;6544.083828264047;6544.672766361663;6642.767503137367;6646.734130634817;6532.7713530026695;6632.369924857131;6633;6726.630035348054;6733;6157.568970172089;6160.6611636359985;6203.100550420254;6220.066989951453;6227.097070924736;6766.830829229309;6830.59016459014;6847.828804279933;6856.040154378391;6864.21061577925;6435.774747883802;6467.547271691031;6525.086487891699;6526.379993900572;6546.247181574085;6455.096286519891;6487.3836985486305;6498.285188629247;6538.542302038812;6599.53803233769;6409.839754006187;6477.287498375269;6511.34886511345;6513.934860651428;6575.80272591823;6438.6136831790245;6438.645874476855;6533.673316334164;6542.607443553402;6554.246261679872;5224.268602395668;5345.599080069011;5350.91973620396;5426.104692725656;5428.647137936022;4603.700759450692;4606.654500912445;4623.976880115578;4627.398438203012;4646.907061858744;5705.115707065412;5705.144228365778;5749.453801888814;5791.3339965903615;5792.333881603669;6418.679066046679;6501.902471462838;6516.902246466307;6575.408213260829;6638.966805165944;5765.8558536036935;5769.163471296639;5771.68255745938;5772;5784;5778.27771528559;5838.241028666331;5912.438318359749;5916.112583112482;5919.852003699942;6231.781887633911;6347.206599175103;6379.043143528508;6384.138141351044;6496.415322620986;6687.297833727584;6729.259781423942;6736.494762892705;6738.022986667004;6739.730410783513;6417.26201486835;6419.101325814297;6425.550211485338;6497.285298617243;6511.088447617242;6409.327020662763;6487.286398496068;6492.188476440445;6560.376764207393;6606.8017959461295;6673.366030227121;6692.230393504615;6709.698063587052;6722.697478613376;6727.159105111861;6398.71205795731;6401;6483.4164925156965;6494.415502604788;6500;6301.96849015752;6386.265579458236;6404.135441715309;6490.188726409199;6497.9025314619375;6275.592086514362;6350.555461117678;6361.650109244036;6363.427291543783;6365.427111559982;6436.9034464484;6442.90335644975;6477.093206950936;6520.250171230369;6542.051402546509;6157.815265542041;6233.563650544418;6237.345078766845;6254.9687251563455;6280.811575652738 06:21:13 INFO - PID 11997 | |1;ext.html;833.6082041440521;857.5755001274368;887.0331338840663;1159.8434211381461;1187.026638156712;40708.389374159415;44123.77938110313;44136.33795493062;44172.55827441725;44298.778506107425;43899;44553.777231113796;44605.553944460546;44632.330515042304;44651.55348446515;7567.356774674159;7739.574323412238;7742.14836367999;7754.030746156731;7759.767206983771;7525.962370188169;7654.540727556337;7657.846843063136;7667.5782831944225;7669.884951725729;7110.288971102874;7726.7681969541;7735;7736.34241089508;7742.961285193566;7649.158592554814;7711.460197786158;7713.22867713227;7714.151443341228;7716.5370077795515;7461.477696561243;7477.439192060563;7570.05374328209;7606;7695.846083078363;39737.20525589472;39938;39990.40014399786;40064.19871602581;40081.79754607366;3607.9098022549383;3638.108663377463;3654.104744337628;3656.9817150914073;3657.7073834093208;32387.51418728721;32571.348573028645;32587.674123258937;32611.83694081538;32623.18442038968;4965.155923492979;5026.623003274733;5039.042581909425;5057.620678449094;5065.442801291855;242628;243172;246391;247024;247435;51222.23166652503;51344.229836552484;51657;51691;51778.22332665013;54393;54860;54907.725461372436;55239.17141242885;55331.44668553343;16353.754693679604;16599.585010374838;16694.58263543421;16701.749473757904;16717.49847504577;13619.931900340434;13679.110857794212;13680.31598420075;13686.726265474834;13689.110207836458;13192.802107968388;13518.324083795771;14810.703785924437;14865;14987.775183372258;5953.553483488756;6054.455099041019;6068.362821903725;6170.629762214281;6287.1198032275515;989.2036910287288;992.3896803465816;1001.8196724589498;1019.4240254256322;1031.1132426113536;22761.430964225696;22777.88611056934;22813.885930570243;23437.51561734772;23469.295921122393;1054.8206804843196;1079.6760971708457;1119.3899324867966;1123.8089524780808;1133.1784456269304;12292.139550231577;12476.313802740755;12498.687532811753;12506.437210325557;12583.245005299712;238049;238663;238852;239480;240108;53557;53742.73128634332;53833.730831345594;53928.46071539313;53932.46067539353;1449.5144126717457;1514.9318280677176;1522.6045917603806;1525.2526262131698;1528.0678785940568;22028.559428811655;22090.779092209516;22538.98574564116;23062.42343941415;23223.651645225673;43263.13473730571;43277.56722432735;52219.47780522298;52266.738666307945;52316.21525677194;205224;205333;205951;206028;207818;35778.64221357857;35800.28399432049;36046.377912993456;36192.27615447729;36615.816920915226;42687;43335.133297334505;43475.565244348414;43698.126037479706;43879.34180987351;30612.540811887393;30911.381772364875;31947.6805231954;31992;32428.675713242566;29604.259893501974;31791.682083178872;32040.5193922096;32294.515582266755;32327.35345293034;35507;35721.46417803787;35846.64153358433;35935.28129437449;36172.638273616925;29776.255593610334;30985;31304.843475782476;31418.21454463565;31465.21336966594;23010.309690709306;24795.752042479344;25202.24393268205;25403.237902862944;25525.87237063803 06:21:13 INFO - PID 11997 | |2;jquery.html;577.4773829684137;582.2023827356522;586.9559783016275;598.6587645042327;610.4261993725897;21959.23142690006;22879.084836606515;22918.427039324026;22965.4258643534;23021.309360719162;26420.20739377822;26857;26944.056958006473;26966.46067078657;26993.190204293853;6570.277269500351;6732.057511948331;6732.899006514906;6734.663266836663;6736.157980252468;6110.358412366704;6398.9360106399035;6412.93587064128;6507.219133703962;6583.506237032242;6338.239411270654;6339.366063393647;6359.36406359365;6372.076048972896;6373.362663733613;6331.493480521547;6335.334789847069;6366.172397588284;6368.267649220306;6372.362763723637;5880.647161170344;5907.261592300963;5914.319853216892;5916.526677865761;5918.496927761145;28394.148175554765;28989.42021159586;29418.558721619193;29467.70532294671;29522.11433656994;1926.6146770645928;1965.7837637859825;1966.242996446364;1966.7738210105736;1967.4097770668743;19160.329388471335;19729.112189951487;19762.11070501831;19769;19787.802121978882;2176.847620666554;2178.9019494122804;2186.3440967709626;2215.6233440315186;2244.865308081504;129440.35279823634;132324;132574;133249;137159.31420342834;14608.488702895347;14905.776413353808;14954.70090598182;15268.541943741706;15578.298976546082;23439.531209375717;23720.762792371854;23723.525529489314;23744.7625523746;23834.16580419677;10066.798664026825;10530.31552949056;10764.46177691128;10815.080718139046;10844.13246940243;8566.357523185787;9918.454485003394;9940;9986.10125088731;10083.798324033478;8149.796255093552;9215.170634642915;9525.761855953519;9531.761705957406;9553.90446095544;2862.470442968058;2871.2821794551364;2884.4952133376996;2926.590277361171;3010.6537748159244;908.1463424381061;941.8304705153138;950.2065775077888;979.5200351827696;988.8071825994006;19162.904185478983;19484.512887177934;19498;19767.802321976596;20085.297014604712;1021.6219998600566;1022.504085518523;1035.9067683908484;1040.1262939131166;1045.1012129568564;10650.934906509443;10751.40867252293;10887.074598659045;10916.563337466412;10936.945315273373;501102;510497;513653;516270;522516;124160.37919810343;125121.12318315233;125133;126957.36521317334;127434;1189.347414895533;1220.1824777399163;1231.2550906701317;1235.5675513570359;1240.34881687115;16793.74809377836;17257.871467304918;17287.13564321804;17464;17891;54828.45171548393;56332.43667563272;56435;57159.42840571707;57477.137842933225;76337.61831191031;77047.61476192583;77652;77908.61045694735;77989.61005194938;12460.563880264148;12969.610911672666;14023.579292621238;14402.85597144015;14745;22841.20055798039;23094.884525577265;23281.18515851937;23286.534269314856;23526.058957642188;17936.551586210448;18196;18323.26706931761;18348.266069357076;18408.35570755017;16756.32974680999;17144.39994600183;17428;17891.910540447734;17989.370372036916;17836.55408614795;19737.309194178135;20082.297119601328;20088.39734807958;20168.697469538263;14471.565853024425;15006.399744010118;15148.697026059637;15149.621259468602;15163.620909477351;18779.812201878354;18890.14994325286;19188.424347269603;19200.80799192046;19263.133159008157 06:21:13 INFO - PID 11997 | |3;mootools.html;1324.6026192142358;1365.8975576831733;1397.9301034948253;1398.3497673581783;1402.1657114017162;13688.794668079987;13710;14309.427622895084;14367.137971721704;14367.999999999987;14541.709165816677;14825.406983720639;14846.628834279149;14848.331825067871;14856;2593.260920637614;2660.2551285639956;2661.4277930244994;2663.5471969765185;2664.080892092225;2516.3709072731817;2587.8706064696785;2619.4499155177436;2649.562822134355;2661.8935242590273;2642.801789865758;2675.879585418651;2677.0897894715795;2678.1429942418436;2678.9062382816605;2569.280601431593;2572.9614055789084;2583.121738608882;2587.650667159928;2591.4687489064695;2484.5776218042975;2507;2539.6444497770326;2541.059807871081;2541.745825417462;13776.93111534436;13947.16317020982;13973.650658733512;14025.78961315581;14078.944079194102;7231;7512.060992375953;7519.436042296795;7525.924740752632;7528.360089392409;4667.13657973276;4806.663533552653;4889.193283108283;4964.776585053682;4980.701157930535;1794.0670851157324;1805.0794095011508;1806.5844855683267;1846.1507706455047;1854.1934258597553;23315.76684233153;23546;23742.406439839142;23767.286981390414;23791.76208237913;6036.486898613667;6139.447449729457;6244.37556244381;6335.714892829834;6340.112384266206;16010.119443430724;16033.599160021093;16069.919650401907;16135.67728645444;16149.35402583907;7403.074615673041;7525.510841795266;7526.6989320427665;7532.924670753332;7537.660805263777;6869.622170780655;6909.654517274116;6971.58170509771;6999.965000174967;7004.3696067354185;4901.730404827697;4980.5019498049905;4983.380401369569;5022.171341728647;5048.242763585492;1264.5384434681275;1283.768921594122;1416.3980308368903;1421.6872288096602;1433.9211343376005;857.4041041476114;877.8244351129799;885.685581618529;886.9778255543663;914.2731528434911;7046.894296585555;7429.554226746304;8047.155048719917;8069.072056713436;8117.686459359054;945.6973768394186;962.9951850240705;967.9903200968041;1001.0940099210227;1023.1354505442889;4698.553637404441;4702.388689470347;4781.11549363362;5268.683878967274;5518.696471694015;18514.722279165824;19078.61842763137;19307.227710891406;19364.903175484313;19390.4182874514;18941.62116757657;18954.810451895304;18987.145578448726;19013.524661883563;19014.239430422906;10362.481875906175;10867.619633312795;11190.216684832147;11238.876112388696;11293.71765705864;7734.92265077342;7849;7853;8040.155783642634;8085.797855053671;16616.2522686477;16754.329826806792;16846.57883552921;17016.319347225974;17278;16534.751978720087;17031.14844257807;17031.829681703024;17219.913900430416;17455.650886982443;12537.811932820832;13157.802632960322;13278.136921100098;13333.266670332836;13337.599872003855;12711.42798574085;13270.1374410663;13405.061645685098;13405.463781448632;13429.865701342862;10784.784304314027;10824.621138260123;10864.619738309122;11075.335479871233;11358.716032098933;10209.336393134423;10233.74415639615;10322.432266225429;10677.305975111592;10850.945745271223;9396.67111651089;9508.049195080437;9510.429374237568;9517.952410237904;9522.857157142787;6455.709493072729;6752.358525940028;6883.070785443901;6924.3421874921805;6977.8255543609785;6626.370494802986;6966.686499107654;6981.2669669683955;7047.7180912762915;7345.1553071397475 06:21:13 INFO - PID 11997 | |4;prototype.html;485.68187836966797;493.71364608527045;509.86233716896464;520.593936729351;524.5593701290916;6117.26592808863;6135.263768347793;6172.845678858031;6193.690315484225;6207.162033125526;5827.621204621697;5855.970720146404;6034.69826508675;6063.484603808682;6076.8480787980325;4873.366462359888;4887.169181239189;4922.778474968617;4923;4950.985048065156;4740.677068236557;4869.366982292315;4897.142999975008;4972.850814475553;4983.451820299764;4843.418789745236;4853.762290615888;4897.583705385046;4912.7297998610065;4938.7530623468865;4843.273508973647;4882.365292511986;4882.7558622068755;4910.754462276872;4940.28365886948;4345.326474396474;4378.299472084451;4378.496472905625;4393.692441529094;4417.359482874966;5989.101634754778;6010.226983040014;6031.306399764039;6032.577719559633;6034.36639152887;5530.806421775259;5535.944640553624;5592.888142237132;5624.831255062314;5628.183913332585;6291.559590828644;6332.240131184288;6357.459615932651;6366.427021568034;6404.839879003016;4754.334393184957;4810.470848206694;4825.276208568707;4844.32179494871;4875.439324477695;6679.76620818269;6684.190011693386;6688.866222675568;6699.765508207287;6790.898136527956;4848.515148485123;4883.511648835088;4892.559669629751;4925.75371231437;4947.109520286383;5002.899362140323;5015.899682006339;5016.147254966664;5033.721336863709;5036.748162591855;5518.641288316247;5567.860803479945;5619.044762390404;5623.915641265385;5629.4652008059165;6316.62100273985;6412.3267056959285;6443.48452123829;6483.157189565326;6492.350764923564;1621.1488968291505;1656.1057029204335;1690.5351028467269;1718.2611477064731;1726.4906852478298;1009.5759780892043;1180.3803003423272;1186.3119390753343;1201.3812886363355;1209.7580483903255;5505.476979686923;5564.888702225933;5644.153376993484;5647.887042259132;5651.0675738503505;6597.241317248578;6600.174978127734;6661;6664.6010534627685;6680.732770689118;5644.774209031592;5696.772129114872;5697.173909783059;5700.914486282709;5709.714514274269;3489.3370259650587;3541.7166626669805;3559.697425718843;3560.3413368526903;3566.197605538785;6322.336154703782;6434.806955791334;6439.388258115471;6444.2589102252905;6505.803756576981;6687.899681504781;6706.753237665437;6727.477196051481;6768.390844823891;6801.829954251183;6334.873302533923;6397;6423.137290185888;6462.450691691165;6509.218893732783;6472.288048314776;6556.770513032021;6666.466682665278;6669.666516674244;6670.39910421505;6455.9677201613695;6506.804795856132;6611.966940165268;6617.106690596708;6638.90041649366;6369.490440764635;6396.42432181106;6445.2587952386075;6477.190351206099;6524.618514242362;4849.636277279147;4920.335754673073;4952.4057113146655;4958;4982.676126051795;6534.281229064894;6607.867842643216;6630.668466576748;6694.933050669431;6706;6242.406971337716;6255.655938923403;6336.144620476177;6441.840468715676;6452.451541619015;6279.403456671609;6328.177336946167;6379.7129129190225;6400.903986440114;6437.710303036468;6204.7828326008375;6265.4361107500545;6297.716602752796;6302.779402720883;6404.327545607831;6408.647524386203;6439.839004024937;6508.446782023581;6534.803955881332;6550.674510548686;6028.33688294296;6150.569460137747;6157.291911430064;6184.690765461799;6258.843528911813 06:21:13 INFO - PID 11997 | |5;yui.html;873.9781505462363;890.1365675294961;893.504105221602;898.3935843305767;932.1983094538696;16893.662126757474;17550;17691.380801671945;17966.28134874605;21437.14251429945;21042.89478552609;21703.891480542574;21908.780912190872;21993.230236941672;22147.446313842163;1028.2699283508719;1064.4943651765388;1064.5635289531294;1068.0601071057458;1069.5989004123453;1054.878688950769;1063.2450959818539;1063.7074804428767;1064.2284343850686;1065.8667666541683;1043.3635482355758;1094.6770702642739;1101.1191047162279;1101.3446999035561;1103.713034611003;1087.4182312462833;1088.3741848437141;1091.579741799409;1093.8960798724108;1094.6332978452149;804.2721337189853;804.5454318310194;805.2028491793142;807.9313258373045;808.1433680298843;18774;18777.62444751111;18932.43202703921;18978.240870365167;20299;20613.381598552067;21019.159233630635;21031.158753649837;21436.14255429781;21438.464038399165;16212.432564860173;16413.343466261336;16585.751213731804;16665.500034998848;16851.41020064292;3617.4212126059706;3632.0193547742224;3636.6726994570618;3639.1266096136737;3646.161382881952;120162.3991880035;124626.37686811508;124805;126175;128409.35795321057;15212.1633310169;16175.514734557746;16195.757063644054;16338.509844704678;16350.34598616042;30592.23519411988;31059.068227953197;31241.687583124334;31432;31497.84251078776;7394.7781566553085;7760.417968652374;8019.478733882279;8623.137686231326;8853.867191992125;4153.460050193516;4224.471941007374;4233.322668373045;4236.555161707976;4257.467816522934;5045.344105266292;5140;5164.14791559396;5172.431032586413;5183.533481986639;1048.5386429970788;1065.6057258814287;1071.978560428787;1078.8597482327248;1086.6739978006724;636.4494712074115;671.6507416143578;686.1868685607571;721.0986267166043;726.6657337624646;11621.76756464866;11653.359065251323;12307.26156430617;12519.123661343801;12799.616011519669;678.5555461172939;681.2540268406087;688.6419062087687;689.4725534965655;704.281632734618;5085.313482679865;5381.569474442035;5491.835244942658;5508.3114610673665;5613.663180209201;101107.49446252722;102386;102656.48671756593;104806;107634;85591.57204214063;86677.5666121678;89192;91299;92094;74813.62593187108;78121.60939195154;78817;81878.59060704659;84440;11870.584529541424;11999.82000270014;12048.51805927753;12315.93842030784;12638.115331926678;34697.653023469444;36639;37547.436788448744;37830;38059.2388152241;62109;64161.35838641554;65565.34434655592;66027.27586037198;68222.3177768216;18811.90594047021;19257.80742192616;19327.130279137753;19401.611967760848;20343.28798492046;18458.169382377557;18824.623507529497;18966.43100706981;19016.809831902057;21430.78569214288;17144.22850971734;18580.628387432447;19354.129064191864;19381.806181938002;19608.313709020116;19368;19476.61046779028;19704.90147549253;19876.90061549683;19958.900205498878;17076.74384884203;17270.568235794206;17852.55368615743;19301.710474343177;19433.31983380575;13459.05786594929;13522.323883805968;14361;14510.1293922365;14535.345909433889;13856.436559236574;14383.137011779327;14392.928035359757;14422.495212667507;14455.710885782435 06:21:13 INFO - PID 11997 | __end_tp_report 06:21:13 INFO - PID 11997 | __start_cc_report 06:21:13 INFO - PID 11997 | _x_x_mozilla_cycle_collect,371 06:21:13 INFO - PID 11997 | __end_cc_report 06:21:13 INFO - PID 11997 | __startTimestamp1500988873962__endTimestamp 06:21:13 INFO - PID 11997 | 06:21:13 INFO - PID 11997 | ------- Summary: start ------- 06:21:13 INFO - PID 11997 | Number of tests: 6 06:21:13 INFO - PID 11997 | 06:21:13 INFO - PID 11997 | [#0] dojo.html Cycles:165 Average:6086.35 Median:6301.97 stddev:702.00 (11.1%) stddev-sans-first:664.29 06:21:13 INFO - PID 11997 | Values: 3113.5 3156.8 3170.8 3194.1 3198.1 6015.9 6104.9 6196.6 6203.8 6247.4 6125.8 6165.4 6182.4 6207.3 6207.4 5641.8 5650.5 5661.1 5666.2 5766.6 5663.3 5713.2 5734.4 5747.3 5754.7 5452.0 5605.5 5655.3 5677.4 5687.0 5624.7 5701.6 5709.9 5720.5 5725.3 5561.3 5597.2 5611.7 5635.0 5681.9 6122.6 6137.9 6138.2 6163.3 6185.8 6532.6 6544.1 6544.7 6642.8 6646.7 6532.8 6632.4 6633.0 6726.6 6733.0 6157.6 6160.7 6203.1 6220.1 6227.1 6766.8 6830.6 6847.8 6856.0 6864.2 6435.8 6467.5 6525.1 6526.4 6546.2 6455.1 6487.4 6498.3 6538.5 6599.5 6409.8 6477.3 6511.3 6513.9 6575.8 6438.6 6438.6 6533.7 6542.6 6554.2 5224.3 5345.6 5350.9 5426.1 5428.6 4603.7 4606.7 4624.0 4627.4 4646.9 5705.1 5705.1 5749.5 5791.3 5792.3 6418.7 6501.9 6516.9 6575.4 6639.0 5765.9 5769.2 5771.7 5772.0 5784.0 5778.3 5838.2 5912.4 5916.1 5919.9 6231.8 6347.2 6379.0 6384.1 6496.4 6687.3 6729.3 6736.5 6738.0 6739.7 6417.3 6419.1 6425.6 6497.3 6511.1 6409.3 6487.3 6492.2 6560.4 6606.8 6673.4 6692.2 6709.7 6722.7 6727.2 6398.7 6401.0 6483.4 6494.4 6500.0 6302.0 6386.3 6404.1 6490.2 6497.9 6275.6 6350.6 6361.7 6363.4 6365.4 6436.9 6442.9 6477.1 6520.3 6542.1 6157.8 6233.6 6237.3 6255.0 6280.8 06:21:13 INFO - PID 11997 | 06:21:13 INFO - PID 11997 | [#1] ext.html Cycles:180 Average:40401.10 Median:35990.83 stddev:59981.76 (166.7%) stddev-sans-first:60076.45 06:21:13 INFO - PID 11997 | Values: 833.6 857.6 887.0 1159.8 1187.0 40708.4 44123.8 44136.3 44172.6 44298.8 43899.0 44553.8 44605.6 44632.3 44651.6 7567.4 7739.6 7742.1 7754.0 7759.8 7526.0 7654.5 7657.8 7667.6 7669.9 7110.3 7726.8 7735.0 7736.3 7743.0 7649.2 7711.5 7713.2 7714.2 7716.5 7461.5 7477.4 7570.1 7606.0 7695.8 39737.2 39938.0 39990.4 40064.2 40081.8 3607.9 3638.1 3654.1 3657.0 3657.7 32387.5 32571.3 32587.7 32611.8 32623.2 4965.2 5026.6 5039.0 5057.6 5065.4 242628.0 243172.0 246391.0 247024.0 247435.0 51222.2 51344.2 51657.0 51691.0 51778.2 54393.0 54860.0 54907.7 55239.2 55331.4 16353.8 16599.6 16694.6 16701.7 16717.5 13619.9 13679.1 13680.3 13686.7 13689.1 13192.8 13518.3 14810.7 14865.0 14987.8 5953.6 6054.5 6068.4 6170.6 6287.1 989.2 992.4 1001.8 1019.4 1031.1 22761.4 22777.9 22813.9 23437.5 23469.3 1054.8 1079.7 1119.4 1123.8 1133.2 12292.1 12476.3 12498.7 12506.4 12583.2 238049.0 238663.0 238852.0 239480.0 240108.0 53557.0 53742.7 53833.7 53928.5 53932.5 1449.5 1514.9 1522.6 1525.3 1528.1 22028.6 22090.8 22539.0 23062.4 23223.7 43263.1 43277.6 52219.5 52266.7 52316.2 205224.0 205333.0 205951.0 206028.0 207818.0 35778.6 35800.3 36046.4 36192.3 36615.8 42687.0 43335.1 43475.6 43698.1 43879.3 30612.5 30911.4 31947.7 31992.0 32428.7 29604.3 31791.7 32040.5 32294.5 32327.4 35507.0 35721.5 35846.6 35935.3 36172.6 29776.3 30985.0 31304.8 31418.2 31465.2 23010.3 24795.8 25202.2 25403.2 25525.9 06:21:13 INFO - PID 11997 | 06:21:13 INFO - PID 11997 | [#2] jquery.html Cycles:180 Average:35794.38 Median:2230.24 stddev:86277.55 (3868.5%) stddev-sans-first:86479.07 06:21:13 INFO - PID 11997 | Values: 577.5 582.2 587.0 598.7 610.4 21959.2 22879.1 22918.4 22965.4 23021.3 26420.2 26857.0 26944.1 26966.5 26993.2 6570.3 6732.1 6732.9 6734.7 6736.2 6110.4 6398.9 6412.9 6507.2 6583.5 6338.2 6339.4 6359.4 6372.1 6373.4 6331.5 6335.3 6366.2 6368.3 6372.4 5880.6 5907.3 5914.3 5916.5 5918.5 28394.1 28989.4 29418.6 29467.7 29522.1 1926.6 1965.8 1966.2 1966.8 1967.4 19160.3 19729.1 19762.1 19769.0 19787.8 2176.8 2178.9 2186.3 2215.6 2244.9 129440.4 132324.0 132574.0 133249.0 137159.3 14608.5 14905.8 14954.7 15268.5 15578.3 23439.5 23720.8 23723.5 23744.8 23834.2 10066.8 10530.3 10764.5 10815.1 10844.1 8566.4 9918.5 9940.0 9986.1 10083.8 8149.8 9215.2 9525.8 9531.8 9553.9 2862.5 2871.3 2884.5 2926.6 3010.7 908.1 941.8 950.2 979.5 988.8 19162.9 19484.5 19498.0 19767.8 20085.3 1021.6 1022.5 1035.9 1040.1 1045.1 10650.9 10751.4 10887.1 10916.6 10936.9 501102.0 510497.0 513653.0 516270.0 522516.0 124160.4 125121.1 125133.0 126957.4 127434.0 1189.3 1220.2 1231.3 1235.6 1240.3 16793.7 17257.9 17287.1 17464.0 17891.0 54828.5 56332.4 56435.0 57159.4 57477.1 76337.6 77047.6 77652.0 77908.6 77989.6 12460.6 12969.6 14023.6 14402.9 14745.0 22841.2 23094.9 23281.2 23286.5 23526.1 17936.6 18196.0 18323.3 18348.3 18408.4 16756.3 17144.4 17428.0 17891.9 17989.4 17836.6 19737.3 20082.3 20088.4 20168.7 14471.6 15006.4 15148.7 15149.6 15163.6 18779.8 18890.1 19188.4 19200.8 19263.1 06:21:13 INFO - PID 11997 | 06:21:13 INFO - PID 11997 | [#3] mootools.html Cycles:180 Average:8744.36 Median:2540.35 stddev:6030.74 (237.4%) stddev-sans-first:6021.89 06:21:13 INFO - PID 11997 | Values: 1324.6 1365.9 1397.9 1398.3 1402.2 13688.8 13710.0 14309.4 14367.1 14368.0 14541.7 14825.4 14846.6 14848.3 14856.0 2593.3 2660.3 2661.4 2663.5 2664.1 2516.4 2587.9 2619.4 2649.6 2661.9 2642.8 2675.9 2677.1 2678.1 2678.9 2569.3 2573.0 2583.1 2587.7 2591.5 2484.6 2507.0 2539.6 2541.1 2541.7 13776.9 13947.2 13973.7 14025.8 14078.9 7231.0 7512.1 7519.4 7525.9 7528.4 4667.1 4806.7 4889.2 4964.8 4980.7 1794.1 1805.1 1806.6 1846.2 1854.2 23315.8 23546.0 23742.4 23767.3 23791.8 6036.5 6139.4 6244.4 6335.7 6340.1 16010.1 16033.6 16069.9 16135.7 16149.4 7403.1 7525.5 7526.7 7532.9 7537.7 6869.6 6909.7 6971.6 7000.0 7004.4 4901.7 4980.5 4983.4 5022.2 5048.2 1264.5 1283.8 1416.4 1421.7 1433.9 857.4 877.8 885.7 887.0 914.3 7046.9 7429.6 8047.2 8069.1 8117.7 945.7 963.0 968.0 1001.1 1023.1 4698.6 4702.4 4781.1 5268.7 5518.7 18514.7 19078.6 19307.2 19364.9 19390.4 18941.6 18954.8 18987.1 19013.5 19014.2 10362.5 10867.6 11190.2 11238.9 11293.7 7734.9 7849.0 7853.0 8040.2 8085.8 16616.3 16754.3 16846.6 17016.3 17278.0 16534.8 17031.1 17031.8 17219.9 17455.7 12537.8 13157.8 13278.1 13333.3 13337.6 12711.4 13270.1 13405.1 13405.5 13429.9 10784.8 10824.6 10864.6 11075.3 11358.7 10209.3 10233.7 10322.4 10677.3 10850.9 9396.7 9508.0 9510.4 9518.0 9522.9 6455.7 6752.4 6883.1 6924.3 6977.8 6626.4 6966.7 6981.3 7047.7 7345.2 06:21:13 INFO - PID 11997 | 06:21:13 INFO - PID 11997 | [#4] prototype.html Cycles:180 Average:5410.57 Median:6031.94 stddev:1520.13 (25.2%) stddev-sans-first:1478.77 06:21:13 INFO - PID 11997 | Values: 485.7 493.7 509.9 520.6 524.6 6117.3 6135.3 6172.8 6193.7 6207.2 5827.6 5856.0 6034.7 6063.5 6076.8 4873.4 4887.2 4922.8 4923.0 4951.0 4740.7 4869.4 4897.1 4972.9 4983.5 4843.4 4853.8 4897.6 4912.7 4938.8 4843.3 4882.4 4882.8 4910.8 4940.3 4345.3 4378.3 4378.5 4393.7 4417.4 5989.1 6010.2 6031.3 6032.6 6034.4 5530.8 5535.9 5592.9 5624.8 5628.2 6291.6 6332.2 6357.5 6366.4 6404.8 4754.3 4810.5 4825.3 4844.3 4875.4 6679.8 6684.2 6688.9 6699.8 6790.9 4848.5 4883.5 4892.6 4925.8 4947.1 5002.9 5015.9 5016.1 5033.7 5036.7 5518.6 5567.9 5619.0 5623.9 5629.5 6316.6 6412.3 6443.5 6483.2 6492.4 1621.1 1656.1 1690.5 1718.3 1726.5 1009.6 1180.4 1186.3 1201.4 1209.8 5505.5 5564.9 5644.2 5647.9 5651.1 6597.2 6600.2 6661.0 6664.6 6680.7 5644.8 5696.8 5697.2 5700.9 5709.7 3489.3 3541.7 3559.7 3560.3 3566.2 6322.3 6434.8 6439.4 6444.3 6505.8 6687.9 6706.8 6727.5 6768.4 6801.8 6334.9 6397.0 6423.1 6462.5 6509.2 6472.3 6556.8 6666.5 6669.7 6670.4 6456.0 6506.8 6612.0 6617.1 6638.9 6369.5 6396.4 6445.3 6477.2 6524.6 4849.6 4920.3 4952.4 4958.0 4982.7 6534.3 6607.9 6630.7 6694.9 6706.0 6242.4 6255.7 6336.1 6441.8 6452.5 6279.4 6328.2 6379.7 6400.9 6437.7 6204.8 6265.4 6297.7 6302.8 6404.3 6408.6 6439.8 6508.4 6534.8 6550.7 6028.3 6150.6 6157.3 6184.7 6258.8 06:21:13 INFO - PID 11997 | 06:21:13 INFO - PID 11997 | [#5] yui.html Cycles:180 Average:22960.48 Median:19454.97 stddev:30400.68 (156.3%) stddev-sans-first:30440.73 06:21:13 INFO - PID 11997 | Values: 874.0 890.1 893.5 898.4 932.2 16893.7 17550.0 17691.4 17966.3 21437.1 21042.9 21703.9 21908.8 21993.2 22147.4 1028.3 1064.5 1064.6 1068.1 1069.6 1054.9 1063.2 1063.7 1064.2 1065.9 1043.4 1094.7 1101.1 1101.3 1103.7 1087.4 1088.4 1091.6 1093.9 1094.6 804.3 804.5 805.2 807.9 808.1 18774.0 18777.6 18932.4 18978.2 20299.0 20613.4 21019.2 21031.2 21436.1 21438.5 16212.4 16413.3 16585.8 16665.5 16851.4 3617.4 3632.0 3636.7 3639.1 3646.2 120162.4 124626.4 124805.0 126175.0 128409.4 15212.2 16175.5 16195.8 16338.5 16350.3 30592.2 31059.1 31241.7 31432.0 31497.8 7394.8 7760.4 8019.5 8623.1 8853.9 4153.5 4224.5 4233.3 4236.6 4257.5 5045.3 5140.0 5164.1 5172.4 5183.5 1048.5 1065.6 1072.0 1078.9 1086.7 636.4 671.7 686.2 721.1 726.7 11621.8 11653.4 12307.3 12519.1 12799.6 678.6 681.3 688.6 689.5 704.3 5085.3 5381.6 5491.8 5508.3 5613.7 101107.5 102386.0 102656.5 104806.0 107634.0 85591.6 86677.6 89192.0 91299.0 92094.0 74813.6 78121.6 78817.0 81878.6 84440.0 11870.6 11999.8 12048.5 12315.9 12638.1 34697.7 36639.0 37547.4 37830.0 38059.2 62109.0 64161.4 65565.3 66027.3 68222.3 18811.9 19257.8 19327.1 19401.6 20343.3 18458.2 18824.6 18966.4 19016.8 21430.8 17144.2 18580.6 19354.1 19381.8 19608.3 19368.0 19476.6 19704.9 19876.9 19958.9 17076.7 17270.6 17852.6 19301.7 19433.3 13459.1 13522.3 14361.0 14510.1 14535.3 13856.4 14383.1 14392.9 14422.5 14455.7 06:21:13 INFO - PID 11997 | -------- Summary: end -------- 06:21:13 INFO - PID 11997 | 06:21:14 INFO - TEST-INFO | 11997: exit 0 06:21:14 INFO - TEST-OK | dromaeo_css | took 1111687ms 06:21:14 INFO - TEST-START | kraken 06:21:14 INFO - Initialising browser for kraken test... 06:21:14 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58430/getInfo.html -profile /tmp/tmpeMP0J1/profile 06:21:14 INFO - TEST-INFO | started process 12360 (/builds/slave/test/build/application/firefox/firefox http://localhost:58430/getInfo.html) 06:21:20 INFO - TEST-INFO | 12360: exit 0 06:21:20 INFO - Browser initialized. 06:21:20 INFO - Running cycle 1/1 for kraken test... 06:21:20 INFO - TEST-INFO | started process 12568 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeMP0J1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 06:21:31 INFO - PID 12568 | RSS: Main: 168947712 06:21:31 INFO - PID 12568 | 06:21:34 INFO - PID 12568 | Cycle 1(1): loaded http://localhost:58430/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58430/tests/kraken/driver.html?testName=audio-beat-detection) 06:21:35 INFO - PID 12568 | RSS: Main: 174993408 06:21:35 INFO - PID 12568 | 06:21:37 INFO - PID 12568 | Cycle 1(1): loaded http://localhost:58430/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58430/tests/kraken/driver.html?testName=audio-dft) 06:21:38 INFO - PID 12568 | RSS: Main: 175792128 06:21:38 INFO - PID 12568 | 06:21:41 INFO - PID 12568 | Cycle 1(1): loaded http://localhost:58430/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58430/tests/kraken/driver.html?testName=audio-fft) 06:21:41 INFO - PID 12568 | RSS: Main: 171376640 06:21:41 INFO - PID 12568 | 06:21:44 INFO - PID 12568 | Cycle 1(1): loaded http://localhost:58430/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58430/tests/kraken/driver.html?testName=audio-oscillator) 06:21:44 INFO - PID 12568 | RSS: Main: 171532288 06:21:44 INFO - PID 12568 | 06:21:46 INFO - PID 12568 | Cycle 1(1): loaded http://localhost:58430/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58430/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:21:47 INFO - PID 12568 | RSS: Main: 171552768 06:21:47 INFO - PID 12568 | 06:21:50 INFO - PID 12568 | Cycle 1(1): loaded http://localhost:58430/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58430/tests/kraken/driver.html?testName=imaging-darkroom) 06:21:51 INFO - PID 12568 | RSS: Main: 174190592 06:21:51 INFO - PID 12568 | 06:21:54 INFO - PID 12568 | Cycle 1(1): loaded http://localhost:58430/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58430/tests/kraken/driver.html?testName=imaging-desaturate) 06:21:55 INFO - PID 12568 | RSS: Main: 174284800 06:21:55 INFO - PID 12568 | 06:21:58 INFO - PID 12568 | Cycle 1(1): loaded http://localhost:58430/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58430/tests/kraken/driver.html?testName=json-parse-financial) 06:21:58 INFO - PID 12568 | RSS: Main: 175058944 06:21:58 INFO - PID 12568 | 06:22:00 INFO - PID 12568 | Cycle 1(1): loaded http://localhost:58430/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58430/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:22:01 INFO - PID 12568 | RSS: Main: 175296512 06:22:01 INFO - PID 12568 | 06:22:02 INFO - PID 12568 | Cycle 1(1): loaded http://localhost:58430/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58430/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:22:03 INFO - PID 12568 | RSS: Main: 175198208 06:22:03 INFO - PID 12568 | 06:22:05 INFO - PID 12568 | Cycle 1(1): loaded http://localhost:58430/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58430/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:22:06 INFO - PID 12568 | RSS: Main: 172077056 06:22:06 INFO - PID 12568 | 06:22:08 INFO - PID 12568 | Cycle 1(1): loaded http://localhost:58430/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58430/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:22:09 INFO - PID 12568 | RSS: Main: 172191744 06:22:09 INFO - PID 12568 | 06:22:12 INFO - PID 12568 | Cycle 1(1): loaded http://localhost:58430/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58430/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:22:13 INFO - PID 12568 | RSS: Main: 172425216 06:22:13 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | Cycle 1(1): loaded http://localhost:58430/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58430/tests/kraken/driver.html?testName=ai-astar) 06:22:15 INFO - PID 12568 | RSS: Main: 174227456 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | __start_tp_report 06:22:15 INFO - PID 12568 | _x_x_mozilla_page_load 06:22:15 INFO - PID 12568 | _x_x_mozilla_page_load_details 06:22:15 INFO - PID 12568 | |i|pagename|runs| 06:22:15 INFO - PID 12568 | |0;ai-astar;99;109;103;110;110;110;113;103;108;111 06:22:15 INFO - PID 12568 | |1;audio-beat-detection;148;126;147;192;119;147;145;118;168;119 06:22:15 INFO - PID 12568 | |2;audio-dft;163;162;167;161;164;162;165;165;170;166 06:22:15 INFO - PID 12568 | |3;audio-fft;74;74;76;172;75;74;74;75;147;76 06:22:15 INFO - PID 12568 | |4;audio-oscillator;81;80;138;82;81;81;128;81;80;128 06:22:15 INFO - PID 12568 | |5;imaging-gaussian-blur;114;115;113;114;114;115;114;115;114;115 06:22:15 INFO - PID 12568 | |6;imaging-darkroom;153;152;151;149;150;150;151;151;151;151 06:22:15 INFO - PID 12568 | |7;imaging-desaturate;94;94;96;94;93;95;95;94;93;94 06:22:15 INFO - PID 12568 | |8;json-parse-financial;54;55;53;52;52;56;52;52;51;52 06:22:15 INFO - PID 12568 | |9;json-stringify-tinderbox;54;55;54;55;55;55;55;55;55;55 06:22:15 INFO - PID 12568 | |10;stanford-crypto-aes;87;78;77;86;77;76;78;76;78;75 06:22:15 INFO - PID 12568 | |11;stanford-crypto-ccm;124;134;128;133;146;129;141;124;139;162 06:22:15 INFO - PID 12568 | |12;stanford-crypto-pbkdf2;175;180;190;176;182;174;176;186;177;175 06:22:15 INFO - PID 12568 | |13;stanford-crypto-sha256-iterative;71;69;73;68;68;69;68;69;74;68 06:22:15 INFO - PID 12568 | __end_tp_report 06:22:15 INFO - PID 12568 | __start_cc_report 06:22:15 INFO - PID 12568 | _x_x_mozilla_cycle_collect,964 06:22:15 INFO - PID 12568 | __end_cc_report 06:22:15 INFO - PID 12568 | __startTimestamp1500988935336__endTimestamp 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | ------- Summary: start ------- 06:22:15 INFO - PID 12568 | Number of tests: 14 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | [#0] ai-astar Cycles:10 Average:107.60 Median:110.00 stddev:4.43 (4.0%) stddev-sans-first:3.43 06:22:15 INFO - PID 12568 | Values: 99.0 109.0 103.0 110.0 110.0 110.0 113.0 103.0 108.0 111.0 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:147.00 stddev:23.93 (16.3%) stddev-sans-first:25.31 06:22:15 INFO - PID 12568 | Values: 148.0 126.0 147.0 192.0 119.0 147.0 145.0 118.0 168.0 119.0 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | [#2] audio-dft Cycles:10 Average:164.50 Median:165.00 stddev:2.72 (1.6%) stddev-sans-first:2.83 06:22:15 INFO - PID 12568 | Values: 163.0 162.0 167.0 161.0 164.0 162.0 165.0 165.0 170.0 166.0 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | [#3] audio-fft Cycles:10 Average:91.70 Median:74.50 stddev:36.22 (48.6%) stddev-sans-first:37.85 06:22:15 INFO - PID 12568 | Values: 74.0 74.0 76.0 172.0 75.0 74.0 74.0 75.0 147.0 76.0 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:81.00 stddev:24.54 (30.3%) stddev-sans-first:25.42 06:22:15 INFO - PID 12568 | Values: 81.0 80.0 138.0 82.0 81.0 81.0 128.0 81.0 80.0 128.0 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 06:22:15 INFO - PID 12568 | Values: 114.0 115.0 113.0 114.0 114.0 115.0 114.0 115.0 114.0 115.0 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.00 stddev:1.10 (0.7%) stddev-sans-first:0.87 06:22:15 INFO - PID 12568 | Values: 153.0 152.0 151.0 149.0 150.0 150.0 151.0 151.0 151.0 151.0 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.92 (1.0%) stddev-sans-first:0.97 06:22:15 INFO - PID 12568 | Values: 94.0 94.0 96.0 94.0 93.0 95.0 95.0 94.0 93.0 94.0 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:52.50 stddev:1.60 (3.0%) stddev-sans-first:1.64 06:22:15 INFO - PID 12568 | Values: 54.0 55.0 53.0 52.0 52.0 56.0 52.0 52.0 51.0 52.0 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | [#9] json-stringify-tinderbox Cycles:10 Average:54.80 Median:55.00 stddev:0.42 (0.8%) stddev-sans-first:0.33 06:22:15 INFO - PID 12568 | Values: 54.0 55.0 54.0 55.0 55.0 55.0 55.0 55.0 55.0 55.0 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:78.00 stddev:4.18 (5.4%) stddev-sans-first:3.22 06:22:15 INFO - PID 12568 | Values: 87.0 78.0 77.0 86.0 77.0 76.0 78.0 76.0 78.0 75.0 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | [#11] stanford-crypto-ccm Cycles:10 Average:136.00 Median:136.50 stddev:11.66 (8.5%) stddev-sans-first:11.53 06:22:15 INFO - PID 12568 | Values: 124.0 134.0 128.0 133.0 146.0 129.0 141.0 124.0 139.0 162.0 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:178.50 stddev:5.36 (3.0%) stddev-sans-first:5.48 06:22:15 INFO - PID 12568 | Values: 175.0 180.0 190.0 176.0 182.0 174.0 176.0 186.0 177.0 175.0 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:69.00 stddev:2.21 (3.2%) stddev-sans-first:2.30 06:22:15 INFO - PID 12568 | Values: 71.0 69.0 73.0 68.0 68.0 69.0 68.0 69.0 74.0 68.0 06:22:15 INFO - PID 12568 | -------- Summary: end -------- 06:22:15 INFO - PID 12568 | 06:22:15 INFO - PID 12568 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 06:22:15 INFO - TEST-INFO | 12568: exit 0 06:22:16 INFO - TEST-OK | kraken | took 61427ms 06:22:16 INFO - SUITE-END | took 1173s 06:22:16 INFO - Completed test suite (00:19:33) 06:22:16 INFO - javascript benchmark 06:22:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8478.152080087706, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3113.470709979303, 3156.8421578921048, 3170.7780455368124, 3194.1375828526293, 3198.088544764741, 6015.939840601593, 6104.877902441948, 6196.628202307865, 6203.813885583429, 6247.406496382842, 6125.846853828645, 6165.383461653843, 6182.443580077792, 6207.28616209135, 6207.37926207379, 5641.802536911209, 5650.49145576898, 5661.094224924013, 5666.178404131399, 5766.625169363999, 5663.348714897777, 5713.17159011943, 5734.426557344253, 5747.310322761254, 5754.683492407916, 5452.0, 5605.523530499912, 5655.26481557397, 5677.40387259336, 5686.971565142168, 5624.690642014688, 5701.629394089412, 5709.885802283972, 5720.513756330717, 5725.341585717654, 5561.304836895388, 5597.244372009767, 5611.691356975365, 5635.042042852725, 5681.857953551153, 6122.571420000601, 6137.877242455126, 6138.232720909887, 6163.2604087509335, 6185.783497577609, 6532.608043517356, 6544.083828264047, 6544.672766361663, 6642.767503137367, 6646.734130634817, 6532.7713530026695, 6632.369924857131, 6633.0, 6726.630035348054, 6733.0, 6157.568970172089, 6160.6611636359985, 6203.100550420254, 6220.066989951453, 6227.097070924736, 6766.830829229309, 6830.59016459014, 6847.828804279933, 6856.040154378391, 6864.21061577925, 6435.774747883802, 6467.547271691031, 6525.086487891699, 6526.379993900572, 6546.247181574085, 6455.096286519891, 6487.3836985486305, 6498.285188629247, 6538.542302038812, 6599.53803233769, 6409.839754006187, 6477.287498375269, 6511.34886511345, 6513.934860651428, 6575.80272591823, 6438.6136831790245, 6438.645874476855, 6533.673316334164, 6542.607443553402, 6554.246261679872, 5224.268602395668, 5345.599080069011, 5350.91973620396, 5426.104692725656, 5428.647137936022, 4603.700759450692, 4606.654500912445, 4623.976880115578, 4627.398438203012, 4646.907061858744, 5705.115707065412, 5705.144228365778, 5749.453801888814, 5791.3339965903615, 5792.333881603669, 6418.679066046679, 6501.902471462838, 6516.902246466307, 6575.408213260829, 6638.966805165944, 5765.8558536036935, 5769.163471296639, 5771.68255745938, 5772.0, 5784.0, 5778.27771528559, 5838.241028666331, 5912.438318359749, 5916.112583112482, 5919.852003699942, 6231.781887633911, 6347.206599175103, 6379.043143528508, 6384.138141351044, 6496.415322620986, 6687.297833727584, 6729.259781423942, 6736.494762892705, 6738.022986667004, 6739.730410783513, 6417.26201486835, 6419.101325814297, 6425.550211485338, 6497.285298617243, 6511.088447617242, 6409.327020662763, 6487.286398496068, 6492.188476440445, 6560.376764207393, 6606.8017959461295, 6673.366030227121, 6692.230393504615, 6709.698063587052, 6722.697478613376, 6727.159105111861, 6398.71205795731, 6401.0, 6483.4164925156965, 6494.415502604788, 6500.0, 6301.96849015752, 6386.265579458236, 6404.135441715309, 6490.188726409199, 6497.9025314619375, 6275.592086514362, 6350.555461117678, 6361.650109244036, 6363.427291543783, 6365.427111559982, 6436.9034464484, 6442.90335644975, 6477.093206950936, 6520.250171230369, 6542.051402546509, 6157.815265542041, 6233.563650544418, 6237.345078766845, 6254.9687251563455, 6280.811575652738], "value": 6034.938439088324, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [833.6082041440521, 857.5755001274368, 887.0331338840663, 1159.8434211381461, 1187.026638156712, 40708.389374159415, 44123.77938110313, 44136.33795493062, 44172.55827441725, 44298.778506107425, 43899.0, 44553.777231113796, 44605.553944460546, 44632.330515042304, 44651.55348446515, 7567.356774674159, 7739.574323412238, 7742.14836367999, 7754.030746156731, 7759.767206983771, 7525.962370188169, 7654.540727556337, 7657.846843063136, 7667.5782831944225, 7669.884951725729, 7110.288971102874, 7726.7681969541, 7735.0, 7736.34241089508, 7742.961285193566, 7649.158592554814, 7711.460197786158, 7713.22867713227, 7714.151443341228, 7716.5370077795515, 7461.477696561243, 7477.439192060563, 7570.05374328209, 7606.0, 7695.846083078363, 39737.20525589472, 39938.0, 39990.40014399786, 40064.19871602581, 40081.79754607366, 3607.9098022549383, 3638.108663377463, 3654.104744337628, 3656.9817150914073, 3657.7073834093208, 32387.51418728721, 32571.348573028645, 32587.674123258937, 32611.83694081538, 32623.18442038968, 4965.155923492979, 5026.623003274733, 5039.042581909425, 5057.620678449094, 5065.442801291855, 242628.0, 243172.0, 246391.0, 247024.0, 247435.0, 51222.23166652503, 51344.229836552484, 51657.0, 51691.0, 51778.22332665013, 54393.0, 54860.0, 54907.725461372436, 55239.17141242885, 55331.44668553343, 16353.754693679604, 16599.585010374838, 16694.58263543421, 16701.749473757904, 16717.49847504577, 13619.931900340434, 13679.110857794212, 13680.31598420075, 13686.726265474834, 13689.110207836458, 13192.802107968388, 13518.324083795771, 14810.703785924437, 14865.0, 14987.775183372258, 5953.553483488756, 6054.455099041019, 6068.362821903725, 6170.629762214281, 6287.1198032275515, 989.2036910287288, 992.3896803465816, 1001.8196724589498, 1019.4240254256322, 1031.1132426113536, 22761.430964225696, 22777.88611056934, 22813.885930570243, 23437.51561734772, 23469.295921122393, 1054.8206804843196, 1079.6760971708457, 1119.3899324867966, 1123.8089524780808, 1133.1784456269304, 12292.139550231577, 12476.313802740755, 12498.687532811753, 12506.437210325557, 12583.245005299712, 238049.0, 238663.0, 238852.0, 239480.0, 240108.0, 53557.0, 53742.73128634332, 53833.730831345594, 53928.46071539313, 53932.46067539353, 1449.5144126717457, 1514.9318280677176, 1522.6045917603806, 1525.2526262131698, 1528.0678785940568, 22028.559428811655, 22090.779092209516, 22538.98574564116, 23062.42343941415, 23223.651645225673, 43263.13473730571, 43277.56722432735, 52219.47780522298, 52266.738666307945, 52316.21525677194, 205224.0, 205333.0, 205951.0, 206028.0, 207818.0, 35778.64221357857, 35800.28399432049, 36046.377912993456, 36192.27615447729, 36615.816920915226, 42687.0, 43335.133297334505, 43475.565244348414, 43698.126037479706, 43879.34180987351, 30612.540811887393, 30911.381772364875, 31947.6805231954, 31992.0, 32428.675713242566, 29604.259893501974, 31791.682083178872, 32040.5193922096, 32294.515582266755, 32327.35345293034, 35507.0, 35721.46417803787, 35846.64153358433, 35935.28129437449, 36172.638273616925, 29776.255593610334, 30985.0, 31304.843475782476, 31418.21454463565, 31465.21336966594, 23010.309690709306, 24795.752042479344, 25202.24393268205, 25403.237902862944, 25525.87237063803], "value": 17776.557543694915, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.4773829684137, 582.2023827356522, 586.9559783016275, 598.6587645042327, 610.4261993725897, 21959.23142690006, 22879.084836606515, 22918.427039324026, 22965.4258643534, 23021.309360719162, 26420.20739377822, 26857.0, 26944.056958006473, 26966.46067078657, 26993.190204293853, 6570.277269500351, 6732.057511948331, 6732.899006514906, 6734.663266836663, 6736.157980252468, 6110.358412366704, 6398.9360106399035, 6412.93587064128, 6507.219133703962, 6583.506237032242, 6338.239411270654, 6339.366063393647, 6359.36406359365, 6372.076048972896, 6373.362663733613, 6331.493480521547, 6335.334789847069, 6366.172397588284, 6368.267649220306, 6372.362763723637, 5880.647161170344, 5907.261592300963, 5914.319853216892, 5916.526677865761, 5918.496927761145, 28394.148175554765, 28989.42021159586, 29418.558721619193, 29467.70532294671, 29522.11433656994, 1926.6146770645928, 1965.7837637859825, 1966.242996446364, 1966.7738210105736, 1967.4097770668743, 19160.329388471335, 19729.112189951487, 19762.11070501831, 19769.0, 19787.802121978882, 2176.847620666554, 2178.9019494122804, 2186.3440967709626, 2215.6233440315186, 2244.865308081504, 129440.35279823634, 132324.0, 132574.0, 133249.0, 137159.31420342834, 14608.488702895347, 14905.776413353808, 14954.70090598182, 15268.541943741706, 15578.298976546082, 23439.531209375717, 23720.762792371854, 23723.525529489314, 23744.7625523746, 23834.16580419677, 10066.798664026825, 10530.31552949056, 10764.46177691128, 10815.080718139046, 10844.13246940243, 8566.357523185787, 9918.454485003394, 9940.0, 9986.10125088731, 10083.798324033478, 8149.796255093552, 9215.170634642915, 9525.761855953519, 9531.761705957406, 9553.90446095544, 2862.470442968058, 2871.2821794551364, 2884.4952133376996, 2926.590277361171, 3010.6537748159244, 908.1463424381061, 941.8304705153138, 950.2065775077888, 979.5200351827696, 988.8071825994006, 19162.904185478983, 19484.512887177934, 19498.0, 19767.802321976596, 20085.297014604712, 1021.6219998600566, 1022.504085518523, 1035.9067683908484, 1040.1262939131166, 1045.1012129568564, 10650.934906509443, 10751.40867252293, 10887.074598659045, 10916.563337466412, 10936.945315273373, 501102.0, 510497.0, 513653.0, 516270.0, 522516.0, 124160.37919810343, 125121.12318315233, 125133.0, 126957.36521317334, 127434.0, 1189.347414895533, 1220.1824777399163, 1231.2550906701317, 1235.5675513570359, 1240.34881687115, 16793.74809377836, 17257.871467304918, 17287.13564321804, 17464.0, 17891.0, 54828.45171548393, 56332.43667563272, 56435.0, 57159.42840571707, 57477.137842933225, 76337.61831191031, 77047.61476192583, 77652.0, 77908.61045694735, 77989.61005194938, 12460.563880264148, 12969.610911672666, 14023.579292621238, 14402.85597144015, 14745.0, 22841.20055798039, 23094.884525577265, 23281.18515851937, 23286.534269314856, 23526.058957642188, 17936.551586210448, 18196.0, 18323.26706931761, 18348.266069357076, 18408.35570755017, 16756.32974680999, 17144.39994600183, 17428.0, 17891.910540447734, 17989.370372036916, 17836.55408614795, 19737.309194178135, 20082.297119601328, 20088.39734807958, 20168.697469538263, 14471.565853024425, 15006.399744010118, 15148.697026059637, 15149.621259468602, 15163.620909477351, 18779.812201878354, 18890.14994325286, 19188.424347269603, 19200.80799192046, 19263.133159008157], "value": 12059.888376076504, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1324.6026192142358, 1365.8975576831733, 1397.9301034948253, 1398.3497673581783, 1402.1657114017162, 13688.794668079987, 13710.0, 14309.427622895084, 14367.137971721704, 14367.999999999987, 14541.709165816677, 14825.406983720639, 14846.628834279149, 14848.331825067871, 14856.0, 2593.260920637614, 2660.2551285639956, 2661.4277930244994, 2663.5471969765185, 2664.080892092225, 2516.3709072731817, 2587.8706064696785, 2619.4499155177436, 2649.562822134355, 2661.8935242590273, 2642.801789865758, 2675.879585418651, 2677.0897894715795, 2678.1429942418436, 2678.9062382816605, 2569.280601431593, 2572.9614055789084, 2583.121738608882, 2587.650667159928, 2591.4687489064695, 2484.5776218042975, 2507.0, 2539.6444497770326, 2541.059807871081, 2541.745825417462, 13776.93111534436, 13947.16317020982, 13973.650658733512, 14025.78961315581, 14078.944079194102, 7231.0, 7512.060992375953, 7519.436042296795, 7525.924740752632, 7528.360089392409, 4667.13657973276, 4806.663533552653, 4889.193283108283, 4964.776585053682, 4980.701157930535, 1794.0670851157324, 1805.0794095011508, 1806.5844855683267, 1846.1507706455047, 1854.1934258597553, 23315.76684233153, 23546.0, 23742.406439839142, 23767.286981390414, 23791.76208237913, 6036.486898613667, 6139.447449729457, 6244.37556244381, 6335.714892829834, 6340.112384266206, 16010.119443430724, 16033.599160021093, 16069.919650401907, 16135.67728645444, 16149.35402583907, 7403.074615673041, 7525.510841795266, 7526.6989320427665, 7532.924670753332, 7537.660805263777, 6869.622170780655, 6909.654517274116, 6971.58170509771, 6999.965000174967, 7004.3696067354185, 4901.730404827697, 4980.5019498049905, 4983.380401369569, 5022.171341728647, 5048.242763585492, 1264.5384434681275, 1283.768921594122, 1416.3980308368903, 1421.6872288096602, 1433.9211343376005, 857.4041041476114, 877.8244351129799, 885.685581618529, 886.9778255543663, 914.2731528434911, 7046.894296585555, 7429.554226746304, 8047.155048719917, 8069.072056713436, 8117.686459359054, 945.6973768394186, 962.9951850240705, 967.9903200968041, 1001.0940099210227, 1023.1354505442889, 4698.553637404441, 4702.388689470347, 4781.11549363362, 5268.683878967274, 5518.696471694015, 18514.722279165824, 19078.61842763137, 19307.227710891406, 19364.903175484313, 19390.4182874514, 18941.62116757657, 18954.810451895304, 18987.145578448726, 19013.524661883563, 19014.239430422906, 10362.481875906175, 10867.619633312795, 11190.216684832147, 11238.876112388696, 11293.71765705864, 7734.92265077342, 7849.0, 7853.0, 8040.155783642634, 8085.797855053671, 16616.2522686477, 16754.329826806792, 16846.57883552921, 17016.319347225974, 17278.0, 16534.751978720087, 17031.14844257807, 17031.829681703024, 17219.913900430416, 17455.650886982443, 12537.811932820832, 13157.802632960322, 13278.136921100098, 13333.266670332836, 13337.599872003855, 12711.42798574085, 13270.1374410663, 13405.061645685098, 13405.463781448632, 13429.865701342862, 10784.784304314027, 10824.621138260123, 10864.619738309122, 11075.335479871233, 11358.716032098933, 10209.336393134423, 10233.74415639615, 10322.432266225429, 10677.305975111592, 10850.945745271223, 9396.67111651089, 9508.049195080437, 9510.429374237568, 9517.952410237904, 9522.857157142787, 6455.709493072729, 6752.358525940028, 6883.070785443901, 6924.3421874921805, 6977.8255543609785, 6626.370494802986, 6966.686499107654, 6981.2669669683955, 7047.7180912762915, 7345.1553071397475], "value": 6336.4943475783875, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.68187836966797, 493.71364608527045, 509.86233716896464, 520.593936729351, 524.5593701290916, 6117.26592808863, 6135.263768347793, 6172.845678858031, 6193.690315484225, 6207.162033125526, 5827.621204621697, 5855.970720146404, 6034.69826508675, 6063.484603808682, 6076.8480787980325, 4873.366462359888, 4887.169181239189, 4922.778474968617, 4923.0, 4950.985048065156, 4740.677068236557, 4869.366982292315, 4897.142999975008, 4972.850814475553, 4983.451820299764, 4843.418789745236, 4853.762290615888, 4897.583705385046, 4912.7297998610065, 4938.7530623468865, 4843.273508973647, 4882.365292511986, 4882.7558622068755, 4910.754462276872, 4940.28365886948, 4345.326474396474, 4378.299472084451, 4378.496472905625, 4393.692441529094, 4417.359482874966, 5989.101634754778, 6010.226983040014, 6031.306399764039, 6032.577719559633, 6034.36639152887, 5530.806421775259, 5535.944640553624, 5592.888142237132, 5624.831255062314, 5628.183913332585, 6291.559590828644, 6332.240131184288, 6357.459615932651, 6366.427021568034, 6404.839879003016, 4754.334393184957, 4810.470848206694, 4825.276208568707, 4844.32179494871, 4875.439324477695, 6679.76620818269, 6684.190011693386, 6688.866222675568, 6699.765508207287, 6790.898136527956, 4848.515148485123, 4883.511648835088, 4892.559669629751, 4925.75371231437, 4947.109520286383, 5002.899362140323, 5015.899682006339, 5016.147254966664, 5033.721336863709, 5036.748162591855, 5518.641288316247, 5567.860803479945, 5619.044762390404, 5623.915641265385, 5629.4652008059165, 6316.62100273985, 6412.3267056959285, 6443.48452123829, 6483.157189565326, 6492.350764923564, 1621.1488968291505, 1656.1057029204335, 1690.5351028467269, 1718.2611477064731, 1726.4906852478298, 1009.5759780892043, 1180.3803003423272, 1186.3119390753343, 1201.3812886363355, 1209.7580483903255, 5505.476979686923, 5564.888702225933, 5644.153376993484, 5647.887042259132, 5651.0675738503505, 6597.241317248578, 6600.174978127734, 6661.0, 6664.6010534627685, 6680.732770689118, 5644.774209031592, 5696.772129114872, 5697.173909783059, 5700.914486282709, 5709.714514274269, 3489.3370259650587, 3541.7166626669805, 3559.697425718843, 3560.3413368526903, 3566.197605538785, 6322.336154703782, 6434.806955791334, 6439.388258115471, 6444.2589102252905, 6505.803756576981, 6687.899681504781, 6706.753237665437, 6727.477196051481, 6768.390844823891, 6801.829954251183, 6334.873302533923, 6397.0, 6423.137290185888, 6462.450691691165, 6509.218893732783, 6472.288048314776, 6556.770513032021, 6666.466682665278, 6669.666516674244, 6670.39910421505, 6455.9677201613695, 6506.804795856132, 6611.966940165268, 6617.106690596708, 6638.90041649366, 6369.490440764635, 6396.42432181106, 6445.2587952386075, 6477.190351206099, 6524.618514242362, 4849.636277279147, 4920.335754673073, 4952.4057113146655, 4958.0, 4982.676126051795, 6534.281229064894, 6607.867842643216, 6630.668466576748, 6694.933050669431, 6706.0, 6242.406971337716, 6255.655938923403, 6336.144620476177, 6441.840468715676, 6452.451541619015, 6279.403456671609, 6328.177336946167, 6379.7129129190225, 6400.903986440114, 6437.710303036468, 6204.7828326008375, 6265.4361107500545, 6297.716602752796, 6302.779402720883, 6404.327545607831, 6408.647524386203, 6439.839004024937, 6508.446782023581, 6534.803955881332, 6550.674510548686, 6028.33688294296, 6150.569460137747, 6157.291911430064, 6184.690765461799, 6258.843528911813], "value": 4959.583379237504, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [873.9781505462363, 890.1365675294961, 893.504105221602, 898.3935843305767, 932.1983094538696, 16893.662126757474, 17550.0, 17691.380801671945, 17966.28134874605, 21437.14251429945, 21042.89478552609, 21703.891480542574, 21908.780912190872, 21993.230236941672, 22147.446313842163, 1028.2699283508719, 1064.4943651765388, 1064.5635289531294, 1068.0601071057458, 1069.5989004123453, 1054.878688950769, 1063.2450959818539, 1063.7074804428767, 1064.2284343850686, 1065.8667666541683, 1043.3635482355758, 1094.6770702642739, 1101.1191047162279, 1101.3446999035561, 1103.713034611003, 1087.4182312462833, 1088.3741848437141, 1091.579741799409, 1093.8960798724108, 1094.6332978452149, 804.2721337189853, 804.5454318310194, 805.2028491793142, 807.9313258373045, 808.1433680298843, 18774.0, 18777.62444751111, 18932.43202703921, 18978.240870365167, 20299.0, 20613.381598552067, 21019.159233630635, 21031.158753649837, 21436.14255429781, 21438.464038399165, 16212.432564860173, 16413.343466261336, 16585.751213731804, 16665.500034998848, 16851.41020064292, 3617.4212126059706, 3632.0193547742224, 3636.6726994570618, 3639.1266096136737, 3646.161382881952, 120162.3991880035, 124626.37686811508, 124805.0, 126175.0, 128409.35795321057, 15212.1633310169, 16175.514734557746, 16195.757063644054, 16338.509844704678, 16350.34598616042, 30592.23519411988, 31059.068227953197, 31241.687583124334, 31432.0, 31497.84251078776, 7394.7781566553085, 7760.417968652374, 8019.478733882279, 8623.137686231326, 8853.867191992125, 4153.460050193516, 4224.471941007374, 4233.322668373045, 4236.555161707976, 4257.467816522934, 5045.344105266292, 5140.0, 5164.14791559396, 5172.431032586413, 5183.533481986639, 1048.5386429970788, 1065.6057258814287, 1071.978560428787, 1078.8597482327248, 1086.6739978006724, 636.4494712074115, 671.6507416143578, 686.1868685607571, 721.0986267166043, 726.6657337624646, 11621.76756464866, 11653.359065251323, 12307.26156430617, 12519.123661343801, 12799.616011519669, 678.5555461172939, 681.2540268406087, 688.6419062087687, 689.4725534965655, 704.281632734618, 5085.313482679865, 5381.569474442035, 5491.835244942658, 5508.3114610673665, 5613.663180209201, 101107.49446252722, 102386.0, 102656.48671756593, 104806.0, 107634.0, 85591.57204214063, 86677.5666121678, 89192.0, 91299.0, 92094.0, 74813.62593187108, 78121.60939195154, 78817.0, 81878.59060704659, 84440.0, 11870.584529541424, 11999.82000270014, 12048.51805927753, 12315.93842030784, 12638.115331926678, 34697.653023469444, 36639.0, 37547.436788448744, 37830.0, 38059.2388152241, 62109.0, 64161.35838641554, 65565.34434655592, 66027.27586037198, 68222.3177768216, 18811.90594047021, 19257.80742192616, 19327.130279137753, 19401.611967760848, 20343.28798492046, 18458.169382377557, 18824.623507529497, 18966.43100706981, 19016.809831902057, 21430.78569214288, 17144.22850971734, 18580.628387432447, 19354.129064191864, 19381.806181938002, 19608.313709020116, 19368.0, 19476.61046779028, 19704.90147549253, 19876.90061549683, 19958.900205498878, 17076.74384884203, 17270.568235794206, 17852.55368615743, 19301.710474343177, 19433.31983380575, 13459.05786594929, 13522.323883805968, 14361.0, 14510.1293922365, 14535.345909433889, 13856.436559236574, 14383.137011779327, 14392.928035359757, 14422.495212667507, 14455.710885782435], "value": 9133.14742350648, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1533.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 109.0, 103.0, 110.0, 110.0, 110.0, 113.0, 103.0, 108.0, 111.0], "value": 107.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 126.0, 147.0, 192.0, 119.0, 147.0, 145.0, 118.0, 168.0, 119.0], "value": 142.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 167.0, 161.0, 164.0, 162.0, 165.0, 165.0, 170.0, 166.0], "value": 164.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 172.0, 75.0, 74.0, 74.0, 75.0, 147.0, 76.0], "value": 91.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 138.0, 82.0, 81.0, 81.0, 128.0, 81.0, 80.0, 128.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 113.0, 114.0, 114.0, 115.0, 114.0, 115.0, 114.0, 115.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 151.0, 149.0, 150.0, 150.0, 151.0, 151.0, 151.0, 151.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 94.0, 93.0, 95.0, 95.0, 94.0, 93.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 53.0, 52.0, 52.0, 56.0, 52.0, 52.0, 51.0, 52.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 78.0, 77.0, 86.0, 77.0, 76.0, 78.0, 76.0, 78.0, 75.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 134.0, 128.0, 133.0, 146.0, 129.0, 141.0, 124.0, 139.0, 162.0], "value": 136.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 180.0, 190.0, 176.0, 182.0, 174.0, 176.0, 186.0, 177.0, 175.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 73.0, 68.0, 68.0, 69.0, 68.0, 69.0, 74.0, 68.0], "value": 69.7, "unit": "score"}]}]} 06:22:16 INFO - Return code: 0 06:22:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:22:16 INFO - # TBPL SUCCESS # 06:22:16 INFO - Running post-action listener: _package_coverage_data 06:22:16 INFO - Running post-action listener: _resource_record_post_action 06:22:16 INFO - [mozharness: 2017-07-25 13:22:16.635599Z] Finished run-tests step (success) 06:22:16 INFO - Running post-run listener: _resource_record_post_run 06:22:16 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 439013376; Read time: 16; Write time: 263148 06:22:16 INFO - TinderboxPrint: CPU usage
13.1% 06:22:16 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 06:22:16 INFO - TinderboxPrint: I/O write bytes / time
439,013,376 / 263,148 06:22:16 INFO - TinderboxPrint: CPU idle
8,257.2 (86.9%) 06:22:16 INFO - TinderboxPrint: CPU user
1,200.0 (12.6%) 06:22:16 INFO - TinderboxPrint: Swap in / out
0 / 0 06:22:16 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107618304; Read time: 0; Write time: 84008 06:22:16 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 06:22:16 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 331395072; Read time: 0; Write time: 179140 06:22:17 INFO - Running post-run listener: _upload_blobber_files 06:22:17 INFO - Blob upload gear active. 06:22:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:22:17 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 06:22:17 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'] 06:22:17 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 06:22:17 INFO - (blobuploader) - INFO - Open directory for files ... 06:22:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:22:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:22:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:22:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:22:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:22:18 INFO - (blobuploader) - INFO - Done attempting. 06:22:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:22:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:22:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:22:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:22:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:22:24 INFO - (blobuploader) - INFO - Done attempting. 06:22:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:22:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:22:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:22:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:22:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:22:26 INFO - (blobuploader) - INFO - Done attempting. 06:22:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:22:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:22:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:22:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:22:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:22:26 INFO - (blobuploader) - INFO - Done attempting. 06:22:26 INFO - (blobuploader) - INFO - Iteration through files over. 06:22:26 INFO - Return code: 0 06:22:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:22:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:22:26 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6b30af8fc34f1e8643c9b862d7ce51f32acded433829baae9a43d8bdb83d22503e42b40072e58310eb6844fab3d725d16a523bf8a2b23917ce08b319466df674", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3892aba9e8b249882e0c757c97e8491f1559e739fbfdfeae59eb98c77449321e9743d86c6bae2742d0b805aaf07543a8457a557df9fa0298d7301484e40a0cf1", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/908bfdd681bc87038d97507651fac2c7c420f419afa3541d704dba60edc19291220487af53e0574fa85d4ab3565ff4b8d64a85792939bb210601b0e459fd5b6d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8db31e4ecc8290a77af8b5882ce3dde0c86c57eb623e7791367a374dabcf3b1837a656ab58565029c52c5ff194deaeff7b4f44b76fcbd6ebca3c1a50566f141e"} 06:22:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:22:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:22:26 INFO - Contents: 06:22:26 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6b30af8fc34f1e8643c9b862d7ce51f32acded433829baae9a43d8bdb83d22503e42b40072e58310eb6844fab3d725d16a523bf8a2b23917ce08b319466df674", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3892aba9e8b249882e0c757c97e8491f1559e739fbfdfeae59eb98c77449321e9743d86c6bae2742d0b805aaf07543a8457a557df9fa0298d7301484e40a0cf1", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/908bfdd681bc87038d97507651fac2c7c420f419afa3541d704dba60edc19291220487af53e0574fa85d4ab3565ff4b8d64a85792939bb210601b0e459fd5b6d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8db31e4ecc8290a77af8b5882ce3dde0c86c57eb623e7791367a374dabcf3b1837a656ab58565029c52c5ff194deaeff7b4f44b76fcbd6ebca3c1a50566f141e"} 06:22:26 INFO - Running post-run listener: copy_logs_to_upload_dir 06:22:26 INFO - Copying logs to upload dir... 06:22:26 INFO - mkdir: /builds/slave/test/build/upload/logs 06:22:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.091538 ========= master_lag: -0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-07-25 06:22:26.577605) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-25 06:22:26.585603) ========= 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-1500981757.417677-267865685 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/6b30af8fc34f1e8643c9b862d7ce51f32acded433829baae9a43d8bdb83d22503e42b40072e58310eb6844fab3d725d16a523bf8a2b23917ce08b319466df674", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3892aba9e8b249882e0c757c97e8491f1559e739fbfdfeae59eb98c77449321e9743d86c6bae2742d0b805aaf07543a8457a557df9fa0298d7301484e40a0cf1", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/908bfdd681bc87038d97507651fac2c7c420f419afa3541d704dba60edc19291220487af53e0574fa85d4ab3565ff4b8d64a85792939bb210601b0e459fd5b6d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8db31e4ecc8290a77af8b5882ce3dde0c86c57eb623e7791367a374dabcf3b1837a656ab58565029c52c5ff194deaeff7b4f44b76fcbd6ebca3c1a50566f141e"} build_url:https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011298 build_url: 'https://queue.taskcluster.net/v1/task/Yn2UwQizTWWRdDlPQ6LLig/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6b30af8fc34f1e8643c9b862d7ce51f32acded433829baae9a43d8bdb83d22503e42b40072e58310eb6844fab3d725d16a523bf8a2b23917ce08b319466df674", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3892aba9e8b249882e0c757c97e8491f1559e739fbfdfeae59eb98c77449321e9743d86c6bae2742d0b805aaf07543a8457a557df9fa0298d7301484e40a0cf1", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/908bfdd681bc87038d97507651fac2c7c420f419afa3541d704dba60edc19291220487af53e0574fa85d4ab3565ff4b8d64a85792939bb210601b0e459fd5b6d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8db31e4ecc8290a77af8b5882ce3dde0c86c57eb623e7791367a374dabcf3b1837a656ab58565029c52c5ff194deaeff7b4f44b76fcbd6ebca3c1a50566f141e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-25 06:22:26.610655) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-25 06:22:26.610976) ========= 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-1500981757.417677-267865685 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004517 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-25 06:22:26.693992) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-25 06:22:26.697335) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-25 06:22:26.697635) ========= ========= Total master_lag: 0.10 =========