builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-003 starttime: 1501148945.22 results: success (0) revision: e5693cea1ec944ca077c7a46c5f127c828a90f1b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-27 02:49:05.219567) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-27 02:49:05.219983) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-27 02:49:05.220248) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-27 02:49:05.248660) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 02:49:05.248908) ========= 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=7de08804244eb36560b44971000001c3-1501144016.829062-1971585993 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-27 02:49:05-- 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% 110M=0s 2017-07-27 02:49:05 (110 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121043 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 02:49:05.382689) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 02:49:05.382958) ========= 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=7de08804244eb36560b44971000001c3-1501144016.829062-1971585993 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013463 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 02:49:05.435139) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 02:49:05.435425) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e5693cea1ec944ca077c7a46c5f127c828a90f1b --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 e5693cea1ec944ca077c7a46c5f127c828a90f1b --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=7de08804244eb36560b44971000001c3-1501144016.829062-1971585993 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-27 02:49:05,845 truncating revision to first 12 chars 2017-07-27 02:49:05,845 Setting DEBUG logging. 2017-07-27 02:49:05,845 attempt 1/10 2017-07-27 02:49:05,845 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e5693cea1ec9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-27 02:49:06,144 unpacking tar archive at: mozilla-central-e5693cea1ec9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.646549 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 02:49:06.100249) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-27 02:49:06.100504) ========= script_repo_revision: e5693cea1ec944ca077c7a46c5f127c828a90f1b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-27 02:49:06.100899) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-27 02:49:06.101208) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-27 02:49:06.127934) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-07-27 02:49:06.128189) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1501144016.829062-1971585993 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:49:06 INFO - MultiFileLogger online at 20170727 02:49:06 in /builds/slave/test 02:49:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:49:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:49:06 INFO - {'append_to_log': False, 02:49:06 INFO - 'base_work_dir': '/builds/slave/test', 02:49:06 INFO - 'blob_upload_branch': 'Firefox', 02:49:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:49:06 INFO - 'branch': 'Firefox', 02:49:06 INFO - 'buildbot_json_path': 'buildprops.json', 02:49:06 INFO - 'code_coverage': False, 02:49:06 INFO - 'config_files': ('talos/linux_config.py',), 02:49:06 INFO - 'default_actions': ('clobber', 02:49:06 INFO - 'read-buildbot-config', 02:49:06 INFO - 'download-and-extract', 02:49:06 INFO - 'populate-webroot', 02:49:06 INFO - 'create-virtualenv', 02:49:06 INFO - 'install', 02:49:06 INFO - 'setup-mitmproxy', 02:49:06 INFO - 'run-tests'), 02:49:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:49:06 INFO - 'disable_ccov_upload': False, 02:49:06 INFO - 'download_minidump_stackwalk': True, 02:49:06 INFO - 'download_symbols': 'ondemand', 02:49:06 INFO - 'enable_stylo': False, 02:49:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:49:06 INFO - 'tooltool.py': '/tools/tooltool.py', 02:49:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:49:06 INFO - '/tools/misc-python/virtualenv.py')}, 02:49:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:49:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:49:06 INFO - 'gecko_profile': False, 02:49:06 INFO - 'gecko_profile_interval': 0, 02:49:06 INFO - 'installer_path': 'installer.exe', 02:49:06 INFO - 'log_level': 'info', 02:49:06 INFO - 'log_name': 'talos', 02:49:06 INFO - 'log_to_console': True, 02:49:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:49:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:49:06 INFO - 'opt_config_files': (), 02:49:06 INFO - 'pip_index': False, 02:49:06 INFO - 'suite': 'dromaeojs-e10s', 02:49:06 INFO - 'system_bits': '32', 02:49:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:49:06 INFO - 'title': 'talos-linux64-ix-003', 02:49:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:49:06 INFO - 'use_talos_json': True, 02:49:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:49:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:49:06 INFO - 'work_dir': 'build'} 02:49:06 INFO - [mozharness: 2017-07-27 09:49:06.633937Z] Running clobber step. 02:49:06 INFO - Running pre-action listener: _resource_record_pre_action 02:49:06 INFO - Running main action method: clobber 02:49:06 INFO - rmtree: /builds/slave/test/build 02:49:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:49:08 INFO - Running post-action listener: _resource_record_post_action 02:49:08 INFO - [mozharness: 2017-07-27 09:49:08.210039Z] Finished clobber step (success) 02:49:08 INFO - [mozharness: 2017-07-27 09:49:08.210142Z] Running read-buildbot-config step. 02:49:08 INFO - Running pre-action listener: _resource_record_pre_action 02:49:08 INFO - Running main action method: read_buildbot_config 02:49:08 INFO - Using buildbot properties: 02:49:08 INFO - { 02:49:08 INFO - "project": "", 02:49:08 INFO - "product": "firefox", 02:49:08 INFO - "who": "cbook@mozilla.com", 02:49:08 INFO - "installer_path": "public/build/target.tar.bz2", 02:49:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 02:49:08 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:49:08 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 02:49:08 INFO - "stage_platform": "linux64-stylosequential", 02:49:08 INFO - "basedir": "/builds/slave/test", 02:49:08 INFO - "buildnumber": 5, 02:49:08 INFO - "platform": "ubuntu64_hw_styloseq", 02:49:08 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 02:49:08 INFO - "slavebuilddir": "test", 02:49:08 INFO - "taskId": "epIOagm_RvueOfaRFp_lfQ", 02:49:08 INFO - "branch": "mozilla-central", 02:49:08 INFO - "script_repo_revision": "production", 02:49:08 INFO - "revision": "e5693cea1ec944ca077c7a46c5f127c828a90f1b", 02:49:08 INFO - "slavename": "talos-linux64-ix-003", 02:49:08 INFO - "repo_path": "mozilla-central" 02:49:08 INFO - } 02:49:08 INFO - Finding installer, test and symbols from parent task. 02:49:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/epIOagm_RvueOfaRFp_lfQ'}, attempt #1 02:49:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/epIOagm_RvueOfaRFp_lfQ'}, attempt #1 02:49:09 INFO - Task dependencies: Nxl8AhfPQiqKvJ1nVywyHg 02:49:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg'}, attempt #1 02:49:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.tar.bz2 02:49:09 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/test_packages.json 02:49:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.crashreporter-symbols.zip 02:49:09 INFO - Running post-action listener: _resource_record_post_action 02:49:09 INFO - [mozharness: 2017-07-27 09:49:09.566637Z] Finished read-buildbot-config step (success) 02:49:09 INFO - [mozharness: 2017-07-27 09:49:09.566863Z] Running download-and-extract step. 02:49:09 INFO - Running pre-action listener: _resource_record_pre_action 02:49:09 INFO - Running main action method: download_and_extract 02:49:09 INFO - mkdir: /builds/slave/test/build/tests 02:49: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')]} 02:49:09 INFO - trying https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.test_packages.json 02:49:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:49:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:49:10 INFO - Downloaded 989 bytes. 02:49:10 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:49:10 INFO - Using the following test package requirements: 02:49:10 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:49:10 INFO - u'common': [u'target.common.tests.zip'], 02:49:10 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:49:10 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:49:10 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:49:10 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:49:10 INFO - u'mozbase': [u'target.common.tests.zip'], 02:49:10 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:49:10 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:49:10 INFO - u'web-platform': [u'target.common.tests.zip', 02:49:10 INFO - u'target.web-platform.tests.tar.gz'], 02:49:10 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:49:10 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:49:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.common.tests.zip 02:49:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:49:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.common.tests.zip into memory 02:49:11 INFO - Content-Length response header: 72558855 02:49:11 INFO - Bytes received: 72558855 02:49:23 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:49:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.common.tests.zip 02:49:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:49:23 INFO - Fetch https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.common.tests.zip into memory 02:49:25 INFO - Content-Length response header: 72558855 02:49:25 INFO - Bytes received: 72558855 02:49:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.talos.tests.zip 02:49:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:49:41 INFO - Fetch https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.talos.tests.zip into memory 02:49:42 INFO - Content-Length response header: 14496465 02:49:42 INFO - Bytes received: 14496465 02:49: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')]} 02:49:42 INFO - trying https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.tar.bz2 02:49:42 INFO - Downloading https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:49:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:49:43 INFO - Downloaded 62207033 bytes. 02:49:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.tar.bz2 02:49:43 INFO - mkdir: /builds/slave/test/properties 02:49:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:49:43 INFO - Writing to file /builds/slave/test/properties/build_url 02:49:43 INFO - Contents: 02:49:43 INFO - build_url:https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.tar.bz2 02:49:43 INFO - Running post-action listener: _resource_record_post_action 02:49:43 INFO - Running post-action listener: set_extra_try_arguments 02:49:43 INFO - [mozharness: 2017-07-27 09:49:43.643706Z] Finished download-and-extract step (success) 02:49:43 INFO - [mozharness: 2017-07-27 09:49:43.643804Z] Running populate-webroot step. 02:49:43 INFO - Running pre-action listener: _resource_record_pre_action 02:49:43 INFO - Running main action method: populate_webroot 02:49:43 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:49:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:49:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:49:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:49:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:49:43 INFO - 'tests': ['damp', 'tps']}, 02:49:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:49:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:49:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:49:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:49:43 INFO - 'other-e10s': {'tests': ['a11yr', 02:49:43 INFO - 'ts_paint', 02:49:43 INFO - 'tpaint', 02:49:43 INFO - 'sessionrestore', 02:49:43 INFO - 'sessionrestore_many_windows', 02:49:43 INFO - 'sessionrestore_no_auto_restore', 02:49:43 INFO - 'tabpaint']}, 02:49:43 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:49:43 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:49:43 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:49:43 INFO - 'talos_options': ['--mitmproxy', 02:49:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:49:43 INFO - '--firstNonBlankPaint'], 02:49:43 INFO - 'tests': ['quantum_pageload_google', 02:49:43 INFO - 'quantum_pageload_youtube', 02:49:43 INFO - 'quantum_pageload_amazon', 02:49:43 INFO - 'quantum_pageload_facebook']}, 02:49:43 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:49:43 INFO - 'talos_options': ['--stylo', 02:49:43 INFO - '--mitmproxy', 02:49:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:49:43 INFO - '--firstNonBlankPaint'], 02:49:43 INFO - 'tests': ['quantum_pageload_google', 02:49:43 INFO - 'quantum_pageload_youtube', 02:49:43 INFO - 'quantum_pageload_amazon', 02:49:43 INFO - 'quantum_pageload_facebook']}, 02:49:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:49:43 INFO - 'tsvgr_opacity', 02:49:43 INFO - 'tart', 02:49:43 INFO - 'tscrollx', 02:49:43 INFO - 'cart', 02:49:43 INFO - 'tsvg_static']}, 02:49:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:49:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:49:43 INFO - 'talos_options': ['--xperf_path', 02:49:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:49:43 INFO - 'tests': ['tp5n']}}} 02:49:43 INFO - Running post-action listener: _resource_record_post_action 02:49:43 INFO - [mozharness: 2017-07-27 09:49:43.651229Z] Finished populate-webroot step (success) 02:49:43 INFO - [mozharness: 2017-07-27 09:49:43.651338Z] Running create-virtualenv step. 02:49:43 INFO - Running pre-action listener: _resource_record_pre_action 02:49:43 INFO - Running main action method: create_virtualenv 02:49:43 INFO - Creating virtualenv /builds/slave/test/build/venv 02:49:43 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 02:49:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:49:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:49:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:49:43 INFO - Using real prefix '/usr' 02:49:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:49:44 INFO - Installing distribute.............................................................................................................................................................................................done. 02:49:46 INFO - Installing pip.................done. 02:49:46 INFO - Return code: 0 02:49:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:49:46 INFO - Reading from file tmpfile_stdout 02:49:46 INFO - Output received: 02:49:46 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) 02:49:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:49:46 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')]} 02:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:46 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')]} 02:49:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:46 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 0x218d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2359a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9eb0>, '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 0x24fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb240>, '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': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', '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 02:49:46 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 02:49:46 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 02:49:46 INFO - Using env: {'DISPLAY': ':0', 02:49:46 INFO - 'HOME': '/home/cltbld', 02:49:46 INFO - 'LANG': 'en_US.UTF-8', 02:49:46 INFO - 'LANGUAGE': 'en_US:en', 02:49:46 INFO - 'LOGNAME': 'cltbld', 02:49:46 INFO - 'MAIL': '/var/mail/cltbld', 02:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:46 INFO - 'MOZ_NO_REMOTE': '1', 02:49:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:46 INFO - 'NO_EM_RESTART': '1', 02:49:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:46 INFO - 'PWD': '/builds/slave/test', 02:49:46 INFO - 'SHELL': '/bin/bash', 02:49:46 INFO - 'SHLVL': '1', 02:49:46 INFO - 'TERM': 'linux', 02:49:46 INFO - 'TMOUT': '86400', 02:49:46 INFO - 'USER': 'cltbld', 02:49:46 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', 02:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:46 INFO - '_': '/tools/buildbot/bin/python'} 02:49:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:46 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 02:49:46 INFO - Cleaning up... 02:49:46 INFO - Return code: 0 02:49:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:49:46 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')]} 02:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:46 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')]} 02:49:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:46 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 0x218d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2359a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9eb0>, '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 0x24fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb240>, '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': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', '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 02:49:46 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 02:49:46 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 02:49:46 INFO - Using env: {'DISPLAY': ':0', 02:49:46 INFO - 'HOME': '/home/cltbld', 02:49:46 INFO - 'LANG': 'en_US.UTF-8', 02:49:46 INFO - 'LANGUAGE': 'en_US:en', 02:49:46 INFO - 'LOGNAME': 'cltbld', 02:49:46 INFO - 'MAIL': '/var/mail/cltbld', 02:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:46 INFO - 'MOZ_NO_REMOTE': '1', 02:49:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:46 INFO - 'NO_EM_RESTART': '1', 02:49:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:46 INFO - 'PWD': '/builds/slave/test', 02:49:46 INFO - 'SHELL': '/bin/bash', 02:49:46 INFO - 'SHLVL': '1', 02:49:46 INFO - 'TERM': 'linux', 02:49:46 INFO - 'TMOUT': '86400', 02:49:46 INFO - 'USER': 'cltbld', 02:49:46 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', 02:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:46 INFO - '_': '/tools/buildbot/bin/python'} 02:49:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:46 INFO - Downloading/unpacking psutil>=3.1.1 02:49:46 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 02:49:46 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 02:49:46 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 02:49:46 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 02:49:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:49:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:49:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:49:48 INFO - Installing collected packages: psutil 02:49:48 INFO - Running setup.py install for psutil 02:49:48 INFO - building 'psutil._psutil_linux' extension 02:49:48 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 02:49:48 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 02:49:48 INFO - building 'psutil._psutil_posix' extension 02:49:48 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 02:49:48 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 02:49:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:49:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:49:48 INFO - Successfully installed psutil 02:49:48 INFO - Cleaning up... 02:49:48 INFO - Return code: 0 02:49:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:49:48 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')]} 02:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:48 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')]} 02:49:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:48 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 0x218d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2359a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9eb0>, '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 0x24fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb240>, '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': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', '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 02:49:48 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 02:49:48 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 02:49:48 INFO - Using env: {'DISPLAY': ':0', 02:49:48 INFO - 'HOME': '/home/cltbld', 02:49:48 INFO - 'LANG': 'en_US.UTF-8', 02:49:48 INFO - 'LANGUAGE': 'en_US:en', 02:49:48 INFO - 'LOGNAME': 'cltbld', 02:49:48 INFO - 'MAIL': '/var/mail/cltbld', 02:49:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:48 INFO - 'MOZ_NO_REMOTE': '1', 02:49:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:48 INFO - 'NO_EM_RESTART': '1', 02:49:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:48 INFO - 'PWD': '/builds/slave/test', 02:49:48 INFO - 'SHELL': '/bin/bash', 02:49:48 INFO - 'SHLVL': '1', 02:49:48 INFO - 'TERM': 'linux', 02:49:48 INFO - 'TMOUT': '86400', 02:49:48 INFO - 'USER': 'cltbld', 02:49:48 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', 02:49:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:48 INFO - '_': '/tools/buildbot/bin/python'} 02:49:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:49:48 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 02:49:48 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 02:49:48 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 02:49:48 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 02:49:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:49:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:49:49 INFO - Installing collected packages: mozsystemmonitor 02:49:49 INFO - Running setup.py install for mozsystemmonitor 02:49:50 INFO - Successfully installed mozsystemmonitor 02:49:50 INFO - Cleaning up... 02:49:50 INFO - Return code: 0 02:49:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:49:50 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')]} 02:49:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:50 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')]} 02:49:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:50 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 0x218d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2359a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9eb0>, '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 0x24fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb240>, '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': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', '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 02:49:50 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 02:49:50 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 02:49:50 INFO - Using env: {'DISPLAY': ':0', 02:49:50 INFO - 'HOME': '/home/cltbld', 02:49:50 INFO - 'LANG': 'en_US.UTF-8', 02:49:50 INFO - 'LANGUAGE': 'en_US:en', 02:49:50 INFO - 'LOGNAME': 'cltbld', 02:49:50 INFO - 'MAIL': '/var/mail/cltbld', 02:49:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:50 INFO - 'MOZ_NO_REMOTE': '1', 02:49:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:50 INFO - 'NO_EM_RESTART': '1', 02:49:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:50 INFO - 'PWD': '/builds/slave/test', 02:49:50 INFO - 'SHELL': '/bin/bash', 02:49:50 INFO - 'SHLVL': '1', 02:49:50 INFO - 'TERM': 'linux', 02:49:50 INFO - 'TMOUT': '86400', 02:49:50 INFO - 'USER': 'cltbld', 02:49:50 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', 02:49:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:50 INFO - '_': '/tools/buildbot/bin/python'} 02:49:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:50 INFO - Downloading/unpacking jsonschema==2.5.1 02:49:50 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 02:49:50 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 02:49:50 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 02:49:50 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 02:49:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:49:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:49:51 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 02:49:51 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 02:49:51 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 02:49:51 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 02:49:51 INFO - Downloading functools32-3.2.3-2.tar.gz 02:49:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:49:51 INFO - warning: no files found matching '*.txt' 02:49:51 INFO - no previously-included directories found matching 'build' 02:49:51 INFO - no previously-included directories found matching 'dist' 02:49:51 INFO - no previously-included directories found matching '.git*' 02:49:51 INFO - Installing collected packages: jsonschema, functools32 02:49:51 INFO - Running setup.py install for functools32 02:49:51 INFO - warning: no files found matching '*.txt' 02:49:51 INFO - no previously-included directories found matching 'build' 02:49:51 INFO - no previously-included directories found matching 'dist' 02:49:51 INFO - no previously-included directories found matching '.git*' 02:49:51 INFO - Successfully installed jsonschema functools32 02:49:51 INFO - Cleaning up... 02:49:51 INFO - Return code: 0 02:49:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:49:51 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')]} 02:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:51 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')]} 02:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:51 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 0x218d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2359a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9eb0>, '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 0x24fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb240>, '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': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', '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 02:49:51 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 02:49:51 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 02:49:51 INFO - Using env: {'DISPLAY': ':0', 02:49:51 INFO - 'HOME': '/home/cltbld', 02:49:51 INFO - 'LANG': 'en_US.UTF-8', 02:49:51 INFO - 'LANGUAGE': 'en_US:en', 02:49:51 INFO - 'LOGNAME': 'cltbld', 02:49:51 INFO - 'MAIL': '/var/mail/cltbld', 02:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:51 INFO - 'MOZ_NO_REMOTE': '1', 02:49:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:51 INFO - 'NO_EM_RESTART': '1', 02:49:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:51 INFO - 'PWD': '/builds/slave/test', 02:49:51 INFO - 'SHELL': '/bin/bash', 02:49:51 INFO - 'SHLVL': '1', 02:49:51 INFO - 'TERM': 'linux', 02:49:51 INFO - 'TMOUT': '86400', 02:49:51 INFO - 'USER': 'cltbld', 02:49:51 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', 02:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:51 INFO - '_': '/tools/buildbot/bin/python'} 02:49:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:49:51 INFO - Cleaning up... 02:49:51 INFO - Return code: 0 02:49:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:49:51 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')]} 02:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:51 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')]} 02:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:51 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 0x218d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2359a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9eb0>, '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 0x24fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb240>, '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': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', '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 02:49:51 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 02:49:51 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 02:49:51 INFO - Using env: {'DISPLAY': ':0', 02:49:51 INFO - 'HOME': '/home/cltbld', 02:49:51 INFO - 'LANG': 'en_US.UTF-8', 02:49:51 INFO - 'LANGUAGE': 'en_US:en', 02:49:51 INFO - 'LOGNAME': 'cltbld', 02:49:51 INFO - 'MAIL': '/var/mail/cltbld', 02:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:51 INFO - 'MOZ_NO_REMOTE': '1', 02:49:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:51 INFO - 'NO_EM_RESTART': '1', 02:49:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:51 INFO - 'PWD': '/builds/slave/test', 02:49:51 INFO - 'SHELL': '/bin/bash', 02:49:51 INFO - 'SHLVL': '1', 02:49:51 INFO - 'TERM': 'linux', 02:49:51 INFO - 'TMOUT': '86400', 02:49:51 INFO - 'USER': 'cltbld', 02:49:51 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', 02:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:51 INFO - '_': '/tools/buildbot/bin/python'} 02:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:52 INFO - Downloading/unpacking blobuploader==1.2.4 02:49:52 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 02:49:52 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 02:49:52 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 02:49:52 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 02:49:53 INFO - Downloading blobuploader-1.2.4.tar.gz 02:49:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:49:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:49:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:53 INFO - Downloading docopt-0.6.1.tar.gz 02:49:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:49:53 INFO - Installing collected packages: blobuploader, requests, docopt 02:49:53 INFO - Running setup.py install for blobuploader 02:49:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:49:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:49:53 INFO - Running setup.py install for requests 02:49:54 INFO - Running setup.py install for docopt 02:49:54 INFO - Successfully installed blobuploader requests docopt 02:49:54 INFO - Cleaning up... 02:49:54 INFO - Return code: 0 02:49:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:49:54 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')]} 02:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:54 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')]} 02:49:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:54 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 0x218d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2359a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9eb0>, '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 0x24fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb240>, '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': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', '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 02:49:54 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 02:49:54 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 02:49:54 INFO - Using env: {'DISPLAY': ':0', 02:49:54 INFO - 'HOME': '/home/cltbld', 02:49:54 INFO - 'LANG': 'en_US.UTF-8', 02:49:54 INFO - 'LANGUAGE': 'en_US:en', 02:49:54 INFO - 'LOGNAME': 'cltbld', 02:49:54 INFO - 'MAIL': '/var/mail/cltbld', 02:49:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:54 INFO - 'MOZ_NO_REMOTE': '1', 02:49:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:54 INFO - 'NO_EM_RESTART': '1', 02:49:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:54 INFO - 'PWD': '/builds/slave/test', 02:49:54 INFO - 'SHELL': '/bin/bash', 02:49:54 INFO - 'SHLVL': '1', 02:49:54 INFO - 'TERM': 'linux', 02:49:54 INFO - 'TMOUT': '86400', 02:49:54 INFO - 'USER': 'cltbld', 02:49:54 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', 02:49:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:54 INFO - '_': '/tools/buildbot/bin/python'} 02:49:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:49:54 INFO - Running setup.py (path:/tmp/pip-QNfuyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:49:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:49:54 INFO - Running setup.py (path:/tmp/pip-N9mZsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:49:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:49:54 INFO - Running setup.py (path:/tmp/pip-4BzjN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:49:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:49:54 INFO - Running setup.py (path:/tmp/pip-Ol0eGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:49:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:49:54 INFO - Running setup.py (path:/tmp/pip-PhAVBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:49:55 INFO - Running setup.py (path:/tmp/pip-9OBRW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:49:55 INFO - Running setup.py (path:/tmp/pip-NdYFsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:49:55 INFO - Running setup.py (path:/tmp/pip-PaX6mE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:49:55 INFO - Running setup.py (path:/tmp/pip-YEUlUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:49:55 INFO - Running setup.py (path:/tmp/pip-98pxUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:49:55 INFO - Running setup.py (path:/tmp/pip-6CJpou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:49:55 INFO - Running setup.py (path:/tmp/pip-nAVhuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:49:55 INFO - Running setup.py (path:/tmp/pip-sLP81u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:49:55 INFO - Running setup.py (path:/tmp/pip-vci6oE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:55 INFO - Running setup.py (path:/tmp/pip-_l4ESq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:49:55 INFO - Running setup.py (path:/tmp/pip-kOdlx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:49:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:49:55 INFO - Running setup.py (path:/tmp/pip-vkBmph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:49:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:49:55 INFO - Running setup.py install for manifestparser 02:49:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:49:56 INFO - Running setup.py install for mozcrash 02:49:56 INFO - Running setup.py install for mozdebug 02:49:56 INFO - Running setup.py install for mozdevice 02:49:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:49:56 INFO - Running setup.py install for mozfile 02:49:56 INFO - Running setup.py install for mozhttpd 02:49:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:49:56 INFO - Running setup.py install for mozinfo 02:49:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:49:56 INFO - Running setup.py install for mozInstall 02:49:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:49:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:49:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:49:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:49:56 INFO - Running setup.py install for mozleak 02:49:56 INFO - Running setup.py install for mozlog 02:49:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:49:56 INFO - Running setup.py install for moznetwork 02:49:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:49:57 INFO - Running setup.py install for mozprocess 02:49:57 INFO - Running setup.py install for mozprofile 02:49:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:49:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:49:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:49:57 INFO - Running setup.py install for mozrunner 02:49:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:49:57 INFO - Running setup.py install for mozscreenshot 02:49:57 INFO - Running setup.py install for moztest 02:49:57 INFO - Running setup.py install for mozversion 02:49:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:49:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:49:57 INFO - Cleaning up... 02:49:57 INFO - Return code: 0 02:49:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:49:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:49:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:49:57 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 0x218d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2359a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9eb0>, '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 0x24fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb240>, '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': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', '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 02:49:57 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 02:49:57 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 02:49:57 INFO - Using env: {'DISPLAY': ':0', 02:49:57 INFO - 'HOME': '/home/cltbld', 02:49:57 INFO - 'LANG': 'en_US.UTF-8', 02:49:57 INFO - 'LANGUAGE': 'en_US:en', 02:49:57 INFO - 'LOGNAME': 'cltbld', 02:49:57 INFO - 'MAIL': '/var/mail/cltbld', 02:49:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:57 INFO - 'MOZ_NO_REMOTE': '1', 02:49:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:49:57 INFO - 'NO_EM_RESTART': '1', 02:49:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:49:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:57 INFO - 'PWD': '/builds/slave/test', 02:49:57 INFO - 'SHELL': '/bin/bash', 02:49:57 INFO - 'SHLVL': '1', 02:49:57 INFO - 'TERM': 'linux', 02:49:57 INFO - 'TMOUT': '86400', 02:49:57 INFO - 'USER': 'cltbld', 02:49:57 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', 02:49:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:57 INFO - '_': '/tools/buildbot/bin/python'} 02:49:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:49:57 INFO - Running setup.py (path:/tmp/pip-kWpvND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:49:57 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)) 02:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:49:57 INFO - Running setup.py (path:/tmp/pip-WxFX9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:49:58 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)) 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:49:58 INFO - Running setup.py (path:/tmp/pip-gyftan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:49:58 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)) 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:49:58 INFO - Running setup.py (path:/tmp/pip-KZrDoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:49:58 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)) 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:49:58 INFO - Running setup.py (path:/tmp/pip-iQnIMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:49:58 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)) 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:49:58 INFO - Running setup.py (path:/tmp/pip-ft0_kK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:49:58 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)) 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:49:58 INFO - Running setup.py (path:/tmp/pip-AdDgGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:49:58 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)) 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:49:58 INFO - Running setup.py (path:/tmp/pip-C7NzdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:49:58 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)) 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:49:58 INFO - Running setup.py (path:/tmp/pip-BjaiQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:49:58 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)) 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:49:58 INFO - Running setup.py (path:/tmp/pip-xb9KR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:49:58 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)) 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:49:58 INFO - Running setup.py (path:/tmp/pip-OXj27l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:49:58 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)) 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:49:58 INFO - Running setup.py (path:/tmp/pip-is8pwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:49:58 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)) 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:49:58 INFO - Running setup.py (path:/tmp/pip-lt6QMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:49:58 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)) 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:49:58 INFO - Running setup.py (path:/tmp/pip-6fpqvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:49:58 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)) 02:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:58 INFO - Running setup.py (path:/tmp/pip-PGvSbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:49:59 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)) 02:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:49:59 INFO - Running setup.py (path:/tmp/pip-pCRv_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:49:59 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)) 02:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:49:59 INFO - Running setup.py (path:/tmp/pip-M7pi9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:49:59 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)) 02:49:59 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)) 02:49:59 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)) 02:49:59 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)) 02:49:59 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)) 02:49:59 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)) 02:49:59 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)) 02:49:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:49:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:00 INFO - Downloading blessings-1.6.tar.gz 02:50:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:50:00 INFO - Installing collected packages: blessings 02:50:00 INFO - Running setup.py install for blessings 02:50:00 INFO - Successfully installed blessings 02:50:00 INFO - Cleaning up... 02:50:00 INFO - Return code: 0 02:50:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:50:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:50:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:50:00 INFO - Reading from file tmpfile_stdout 02:50:00 INFO - Current package versions: 02:50:00 INFO - argparse == 1.2.1 02:50:00 INFO - blessings == 1.6 02:50:00 INFO - blobuploader == 1.2.4 02:50:00 INFO - docopt == 0.6.1 02:50:00 INFO - functools32 == 3.2.3-2 02:50:00 INFO - jsonschema == 2.5.1 02:50:00 INFO - manifestparser == 1.1 02:50:00 INFO - mozInstall == 1.13 02:50:00 INFO - mozcrash == 1.0 02:50:00 INFO - mozdebug == 0.1 02:50:00 INFO - mozdevice == 0.51 02:50:00 INFO - mozfile == 1.2 02:50:00 INFO - mozhttpd == 0.7 02:50:00 INFO - mozinfo == 0.10 02:50:00 INFO - mozleak == 0.1 02:50:00 INFO - mozlog == 3.5 02:50:00 INFO - moznetwork == 0.27 02:50:00 INFO - mozprocess == 0.25 02:50:00 INFO - mozprofile == 0.28 02:50:00 INFO - mozrunner == 6.13 02:50:00 INFO - mozscreenshot == 0.1 02:50:00 INFO - mozsystemmonitor == 0.3 02:50:00 INFO - moztest == 0.8 02:50:00 INFO - mozversion == 1.4 02:50:00 INFO - psutil == 3.1.1 02:50:00 INFO - requests == 1.2.3 02:50:00 INFO - wsgiref == 0.1.2 02:50:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:50:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:00 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 0x218d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2359a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9eb0>, '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 0x24fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb240>, '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': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', '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 02:50:00 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 02:50:00 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 02:50:00 INFO - Using env: {'DISPLAY': ':0', 02:50:00 INFO - 'HOME': '/home/cltbld', 02:50:00 INFO - 'LANG': 'en_US.UTF-8', 02:50:00 INFO - 'LANGUAGE': 'en_US:en', 02:50:00 INFO - 'LOGNAME': 'cltbld', 02:50:00 INFO - 'MAIL': '/var/mail/cltbld', 02:50:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:00 INFO - 'MOZ_NO_REMOTE': '1', 02:50:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:50:00 INFO - 'NO_EM_RESTART': '1', 02:50:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:50:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:00 INFO - 'PWD': '/builds/slave/test', 02:50:00 INFO - 'SHELL': '/bin/bash', 02:50:00 INFO - 'SHLVL': '1', 02:50:00 INFO - 'TERM': 'linux', 02:50:00 INFO - 'TMOUT': '86400', 02:50:00 INFO - 'USER': 'cltbld', 02:50:00 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', 02:50:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:00 INFO - '_': '/tools/buildbot/bin/python'} 02:50:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:00 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)) 02:50:00 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)) 02:50:00 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)) 02:50:00 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)) 02:50:00 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)) 02:50:00 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)) 02:50:00 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)) 02:50:00 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)) 02:50:00 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)) 02:50:00 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:01 INFO - Downloading marionette_driver-1.4.0.tar.gz 02:50:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 02:50:02 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 02:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:50:02 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)) 02:50:02 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)) 02:50:02 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)) 02:50:02 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)) 02:50:02 INFO - Installing collected packages: marionette-driver, simplejson 02:50:02 INFO - Running setup.py install for marionette-driver 02:50:02 INFO - Running setup.py install for simplejson 02:50:02 INFO - building 'simplejson._speedups' extension 02:50:02 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 02:50:03 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 02:50:03 INFO - Successfully installed marionette-driver simplejson 02:50:03 INFO - Cleaning up... 02:50:03 INFO - Return code: 0 02:50:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:50:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2359a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9eb0>, '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 0x24fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb240>, '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': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', '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 02:50:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:50:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:50:03 INFO - Using env: {'DISPLAY': ':0', 02:50:03 INFO - 'HOME': '/home/cltbld', 02:50:03 INFO - 'LANG': 'en_US.UTF-8', 02:50:03 INFO - 'LANGUAGE': 'en_US:en', 02:50:03 INFO - 'LOGNAME': 'cltbld', 02:50:03 INFO - 'MAIL': '/var/mail/cltbld', 02:50:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:03 INFO - 'MOZ_NO_REMOTE': '1', 02:50:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:50:03 INFO - 'NO_EM_RESTART': '1', 02:50:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:50:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:03 INFO - 'PWD': '/builds/slave/test', 02:50:03 INFO - 'SHELL': '/bin/bash', 02:50:03 INFO - 'SHLVL': '1', 02:50:03 INFO - 'TERM': 'linux', 02:50:03 INFO - 'TMOUT': '86400', 02:50:03 INFO - 'USER': 'cltbld', 02:50:03 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', 02:50:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:03 INFO - '_': '/tools/buildbot/bin/python'} 02:50:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:50:03 INFO - Cleaning up... 02:50:03 INFO - Return code: 0 02:50:03 INFO - Running post-action listener: _resource_record_post_action 02:50:03 INFO - Running post-action listener: _start_resource_monitoring 02:50:03 INFO - Starting resource monitoring. 02:50:03 INFO - [mozharness: 2017-07-27 09:50:03.982926Z] Finished create-virtualenv step (success) 02:50:03 INFO - [mozharness: 2017-07-27 09:50:03.983502Z] Running install step. 02:50:03 INFO - Running pre-action listener: _resource_record_pre_action 02:50:03 INFO - Running main action method: install 02:50:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:50:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:50:04 INFO - Reading from file tmpfile_stdout 02:50:04 INFO - Detecting whether we're running mozinstall >=1.0... 02:50:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:50:04 INFO - Reading from file tmpfile_stdout 02:50:04 INFO - Output received: 02:50:04 INFO - Usage: mozinstall [options] installer 02:50:04 INFO - Options: 02:50:04 INFO - -h, --help show this help message and exit 02:50:04 INFO - -d DEST, --destination=DEST 02:50:04 INFO - Directory to install application into. [default: 02:50:04 INFO - "/builds/slave/test"] 02:50:04 INFO - --app=APP Application being installed. [default: firefox] 02:50:04 INFO - mkdir: /builds/slave/test/build/application 02:50:04 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'] 02:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:50:22 INFO - Reading from file tmpfile_stdout 02:50:22 INFO - Output received: 02:50:22 INFO - /builds/slave/test/build/application/firefox/firefox 02:50:22 INFO - Running post-action listener: _resource_record_post_action 02:50:22 INFO - [mozharness: 2017-07-27 09:50:22.371121Z] Finished install step (success) 02:50:22 INFO - [mozharness: 2017-07-27 09:50:22.371419Z] Running setup-mitmproxy step. 02:50:22 INFO - Running pre-action listener: _resource_record_pre_action 02:50:22 INFO - Running main action method: setup_mitmproxy 02:50:22 INFO - Skipping: mitmproxy is not required 02:50:22 INFO - Running post-action listener: _resource_record_post_action 02:50:22 INFO - [mozharness: 2017-07-27 09:50:22.372578Z] Finished setup-mitmproxy step (success) 02:50:22 INFO - [mozharness: 2017-07-27 09:50:22.372803Z] Running run-tests step. 02:50:22 INFO - Running pre-action listener: _resource_record_pre_action 02:50:22 INFO - Running pre-action listener: _set_gcov_prefix 02:50:22 INFO - Running main action method: run_tests 02:50:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:50:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:50:22 INFO - Python 2.7.3 02:50:22 INFO - Return code: 0 02:50:22 INFO - grabbing minidump binary from tooltool 02:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:22 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 0x24f9eb0>, '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 0x24fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:50:22 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 02:50:22 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 02:50:22 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 02:50:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:50:22 INFO - Return code: 0 02:50:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:50:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:50:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:50:22 INFO - ENV: RUST_BACKTRACE is now 1 02:50:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:50:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:50:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 02:50:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 02:50:22 INFO - Using env: {'DISPLAY': ':0', 02:50:22 INFO - 'HOME': '/home/cltbld', 02:50:22 INFO - 'LANG': 'en_US.UTF-8', 02:50:22 INFO - 'LANGUAGE': 'en_US:en', 02:50:22 INFO - 'LOGNAME': 'cltbld', 02:50:22 INFO - 'MAIL': '/var/mail/cltbld', 02:50:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:50:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:22 INFO - 'MOZ_NO_REMOTE': '1', 02:50:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:50:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:50:22 INFO - 'NO_EM_RESTART': '1', 02:50:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:50:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:22 INFO - 'PWD': '/builds/slave/test', 02:50:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:50:22 INFO - 'RUST_BACKTRACE': '1', 02:50:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:50:22 INFO - 'SHELL': '/bin/bash', 02:50:22 INFO - 'SHLVL': '1', 02:50:22 INFO - 'STYLO_FORCE_ENABLED': '1', 02:50:22 INFO - 'TERM': 'linux', 02:50:22 INFO - 'TMOUT': '86400', 02:50:22 INFO - 'USER': 'cltbld', 02:50:22 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1501144016.829062-1971585993', 02:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:22 INFO - '_': '/tools/buildbot/bin/python'} 02:50:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 02:50:22 INFO - mozversion application_buildid: 20170727085754 02:50:22 INFO - mozversion application_changeset: e5693cea1ec944ca077c7a46c5f127c828a90f1b 02:50:22 INFO - mozversion application_display_name: Nightly 02:50:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:50:22 INFO - mozversion application_name: Firefox 02:50:22 INFO - mozversion application_remotingname: firefox 02:50:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:50:22 INFO - mozversion application_vendor: Mozilla 02:50:22 INFO - mozversion application_version: 56.0a1 02:50:22 INFO - mozversion platform_buildid: 20170727085754 02:50:22 INFO - mozversion platform_changeset: e5693cea1ec944ca077c7a46c5f127c828a90f1b 02:50:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:50:22 INFO - mozversion platform_version: 56.0a1 02:50:22 INFO - using testdate: 1501149022 02:50:22 INFO - actual date: 1501149022 02:50:22 INFO - starting webserver on 'localhost:58051' 02:50:22 INFO - SUITE-START | Running 2 tests 02:50:22 INFO - TEST-START | dromaeo_css 02:50:22 INFO - Initialising browser for dromaeo_css test... 02:50:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58051/getInfo.html -profile /tmp/tmpKkvqKr/profile 02:50:22 INFO - TEST-INFO | started process 10779 (/builds/slave/test/build/application/firefox/firefox http://localhost:58051/getInfo.html) 02:50:28 INFO - TEST-INFO | 10779: exit 0 02:50:28 INFO - Browser initialized. 02:50:28 INFO - Running cycle 1/1 for dromaeo_css test... 02:50:28 INFO - TEST-INFO | started process 11006 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKkvqKr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:50:29 INFO - PID 11006 | 02:50:29 INFO - PID 11006 | (/builds/slave/test/build/application/firefox/firefox:11061): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:50:29 INFO - PID 11006 | 02:50:40 INFO - PID 11006 | RSS: Main: 173772800 02:50:40 INFO - PID 11006 | 02:53:40 INFO - PID 11006 | Cycle 1(1): loaded http://localhost:58051/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58051/tests/dromaeo/cssquery-ext.html) 02:53:41 INFO - PID 11006 | RSS: Main: 173481984 02:53:41 INFO - PID 11006 | 02:56:42 INFO - PID 11006 | Cycle 1(1): loaded http://localhost:58051/tests/dromaeo/cssquery-ext.html (next: http://localhost:58051/tests/dromaeo/cssquery-jquery.html) 02:56:43 INFO - PID 11006 | RSS: Main: 170946560 02:56:43 INFO - PID 11006 | 02:59:45 INFO - PID 11006 | Cycle 1(1): loaded http://localhost:58051/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58051/tests/dromaeo/cssquery-mootools.html) 02:59:46 INFO - PID 11006 | RSS: Main: 175685632 02:59:46 INFO - PID 11006 | 03:02:47 INFO - PID 11006 | Cycle 1(1): loaded http://localhost:58051/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58051/tests/dromaeo/cssquery-prototype.html) 03:02:48 INFO - PID 11006 | RSS: Main: 171450368 03:02:48 INFO - PID 11006 | 03:05:52 INFO - PID 11006 | Cycle 1(1): loaded http://localhost:58051/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58051/tests/dromaeo/cssquery-yui.html) 03:05:53 INFO - PID 11006 | RSS: Main: 175083520 03:05:53 INFO - PID 11006 | 03:08:55 INFO - PID 11006 | Cycle 1(1): loaded http://localhost:58051/tests/dromaeo/cssquery-yui.html (next: http://localhost:58051/tests/dromaeo/cssquery-dojo.html) 03:08:55 INFO - PID 11006 | RSS: Main: 177397760 03:08:55 INFO - PID 11006 | 03:08:55 INFO - PID 11006 | __start_tp_report 03:08:55 INFO - PID 11006 | _x_x_mozilla_page_load 03:08:55 INFO - PID 11006 | _x_x_mozilla_page_load_details 03:08:55 INFO - PID 11006 | |i|pagename|runs| 03:08:55 INFO - PID 11006 | |0;dojo.html;3120.500719884819;3158.099941516668;3173.825439600821;3187.952180717289;3198.472252078407;6100.23747031621;6191.318954914956;6205.348438413964;6260.5930614510025;6276.184096067511;6062.727177277022;6160.59956102853;6173.197484327031;6182.938170618292;6215.09881067245;5673.546116310706;5682.26130603021;5688.288963879515;5697.515711164535;5719.857003574903;5653.547716182716;5696;5713.914291285634;5768.711564421783;5817.1565123057135;5632.408597097307;5709.0009248381575;5712.857178570527;5746.540276777869;5746.885062298751;5514.6139770216105;5708.343540492855;5716.114002329646;5776.451237132465;5779.797707080253;5416.945830541684;5567.331920169606;5609.775608975636;5653.519450846683;5673.971630141864;6080.087986801971;6082.8783424331705;6087.1173679816175;6117.908231376533;6185.84535386619;6456.031595260702;6577.6711164441585;6636.435902948255;6651.102101216371;6738;6642.402183803432;6699.464042876558;6705.027770973182;6710.463162947001;6716.429103526206;6180.5673602848265;6181.289151747562;6187.597806142631;6192.473639740628;6193.907091393633;6792.728290868409;6860.176778786577;6880.036794848726;6893.241743408221;6899.448044156456;6491.318411566812;6575.145231119924;6585.604863708097;6597.637129957802;6600.900986485207;6666.400023997863;6673.099131617267;6675.6662166891465;6682.164729408824;6688.7324507019175;6584.572002819801;6641.501887358468;6660.467162626978;6719.193696756421;6722.126123603998;6589.17635295588;6612.7354905804195;6636.800895973129;6727.831804204836;6731.2932142125355;5304.071787437183;5373.194020896896;5377;5379.300690910235;5394.298741163623;4639.721616702941;4641.930371044437;4681.625469962395;4681.976590117028;4703.670743047954;5726.9713651431475;5831.096180092092;5832.504237139806;5836.328822185418;5842.298924129132;6614.173228346457;6629.834254143685;6685.933140668531;6713.597184168868;6713.959336302881;5605.579581531401;5711.971440142773;5753.654780713171;5846.444587764156;5850.180974663551;5904.822855314347;5909.556783241274;5915.319738230072;5926.67403292823;5935.910961335583;6460.224773027173;6506.154199953976;6530.085787989685;6540.738370465128;6576.736930522821;6655.035019922181;6655.633940133339;6743.426808721314;6757.290484499057;6767.120274364301;6339.302676705651;6454.257760357619;6462.353764623688;6476.06097115925;6561.967190164019;6528.314526974599;6546.378094081055;6548.476121910331;6553.311902250386;6558.081868538504;6592.472602191909;6688.899666504909;6695.631740254333;6696.363845434676;6699.196096468455;6088.178095956989;6420.518461115528;6447.70985305653;6449.4840412765925;6531.281559028408;6496.902546461902;6526.282108967915;6537.673116344069;6555.737770489127;6571.507136964842;6286.057091436322;6392.9360706392345;6396.71214795326;6478.352164783484;6511.837204069936;6455.515836312202;6457.418832305115;6527.934720652923;6538.1827271591055;6550.6069635822005;6301.149344838388;6302.243730752339;6380.138681277968;6399.8080057598345;6399.936000639934 03:08:55 INFO - PID 11006 | |1;ext.html;826.7354446577098;871.4292138649186;890.1587999340621;899.0155779421535;1184.0290961411647;30159.698403015962;40211.39682904759;40960.79519602405;41035;41131.38302925458;40991.79504102475;41325.586744132546;41329.99999999993;41389;41481.58518414815;7645.044369453818;7773.650185741655;7775.028121484815;7776.766696999099;7777.300042996129;7392.112946446434;7434.925650743505;7461.552306861606;7471.887921681179;7473.476856620039;7664.156942736295;7747.535147891116;7779.572123533203;7797.064352277706;7805.219478052206;7678.500897441649;7733.45865789395;7742.651580678883;7743.8838417423785;7744.612769361537;7681.23187681233;7705.075390953121;7708.57602831844;7724.420668449832;7774.378049756006;36787.264254715024;37182;37187.628123718685;37245.255094898224;37247.441288380694;3393.202597389636;3446.483027545863;3453.6028356739043;3456.3087382523595;3458.515807786912;31039.844800776078;31131.844340778378;31170.220744481343;31176.37647247065;31194.532082018788;4739.052189562067;4811.518848115195;4840.322354870321;4842.782074806642;4901.852944411673;177781;178756;178915;179580;179804;47859.28211076836;47950;48025.03949920982;48056.759716201195;48079.278810817865;49531.25703114456;49830;49844.750776245884;49848;49862.50137498651;16171.838281617032;16319;16320.347186112422;16321.91839040821;16337.346506139858;13388.129771564818;13436.19382837033;13496.527621533396;13509;13521.121127126706;12528.561500347445;12675.619731408073;14342.641433964234;14366.209858457676;14396.928015359856;5500.642458240201;5660.377358490563;5700.629459085146;5715.828525144252;5793.478586927113;973.454865275448;992.7964767222702;996.496769131584;1002.5037606384886;1011.2920955331298;22024.889875550518;22213.11147554112;22240.221592244194;22268.82216174163;22771.88614056919;1046.4506134279563;1079.341601623025;1089.624079692505;1110.228391268061;1118.3681220110611;11518.251313664585;11622.302661840316;11775.941120294343;11787.88212117885;11816.291022538675;227196;228243;228311;228839;230148;49009;49227;49441.25838112431;49612.50387496151;49820;1446.8697817196503;1487.7545205041263;1494.5909566778048;1498.2059508460534;1522.5584580471761;21723.805190714662;21788.600334620412;22396;22593.887030564743;22724.545509090058;35903.640963591075;35994.64005359913;36322.27355452929;36336.818315908255;36352.81823590865;179327;180256;180848;180891;180980;31735.523967140973;32015.67984320127;32182.839085804422;32228.19429514281;32679.836600816845;40678.79660601678;40806.59193408028;40885;41043.179136417704;41489.37765933453;27286.454270914866;28259.293517662223;29068.56397154002;29338.853305733333;29711.405771884874;28763.712362876104;29057.273568160967;29157.562636560044;29472.263193420335;29640.258993525335;32425.189370265933;33570.16074598057;33800.830995844866;33941.660583394834;34126.82936585301;28214.294642634097;28949.42101158007;29781.851090744407;30198.245043874078;32910.670893290764;23323.41691457659;23605.452015580668;23724.644130337714;23903.880480597487;24208.273751787474 03:08:55 INFO - PID 11006 | |2;jquery.html;574.6925394913721;575.4360726488042;576.7721749908786;582.5252419278288;582.6271186440677;21861.125554977803;22380.552388952212;22713.54572908543;22826.88586557069;22853.77146228537;26177.86911065442;26648.33379165522;26756.732432675668;26780.464390712175;26786.59820102695;6438.839029024277;6691.297413771557;6692;6694.531382803181;6696.765613203539;5940.673262970557;6545.93454065458;6552.705128269239;6556.803295901131;6621.106150669671;6508.511861610375;6516.967415162918;6518.641474718888;6520.152380190569;6523.771667991621;6493.577917435398;6504;6508.511861610375;6508.9023664645065;6527.510436717242;6009.399060094;6070.544709146831;6070.6357618543025;6076.058210977306;6078.088286757022;28309.858450707616;29167.41665166706;29749.851250743824;30299;30512.389752205054;1857.3870622694478;1883.1714045819933;1884.6513395021964;1885.5474686075381;1887.4337698690476;18801.90599046996;19318.613627727507;19365.806341936543;19375;19419.126139323766;2292.977070229293;2297.9540409191723;2306.3888069661384;2342.61346877765;2425.2845410603913;126134;128925.35537322347;129061.35469322593;129316.3534182323;129617;13939.372728227254;14788.926055369655;14862.479813206703;15124.882516447706;15379.384824607114;23175.188868389527;23181.304560863202;23184.18855340055;23198;23210.3036908893;10050.09549140581;10087.092161705481;10143.492825358702;10188.694339169837;10246.948765256126;8005.759827205193;9239.076092390842;9244.075592440837;9385;9535.90464095364;8227.382946279053;9130.771730706785;9266.351355405191;9286.210672092813;9677.612895484102;2786.916392508228;2799.566067259578;2817.1266907258814;2836.1633318170775;2857.414230082828;949.3591825517748;972.6595691508057;979.4612962870393;994.9303548751518;996.5864166371019;19544.70682939757;19702.605947880962;19989.200431982557;20547.4863128423;24547;1029.3103620574234;1040.8386700061503;1041.5156952017348;1057.3391630231106;1064.7497838008062;10625.9468702656;10706.839397409045;11179.888201118047;11387.259828111146;11490;552818;554501;558357;563818;568829;123669;124055.3797231008;127337;127603;128338;1248.7502499500135;1266.3288457117742;1277.552856500236;1294.58573256557;1305.321232958875;18935.621287573897;21114.7888521119;21478.892605536872;21676;21685.56628867382;57406.425935741776;57453.13820292615;58274;58660;59974.10038849334;68977.65511172412;68980;69449.30550694428;69505.65247173731;70228.64885675539;17118.40085596998;17282.74075888888;17725;17867.910660446614;20602.07290671953;19312.71030934509;24081.277561673178;24339;24555.50888982246;24618.50762984766;18875.716864247323;18906.43280701581;19085.71371429402;19161.425157245307;19187.90406047961;15719.21403929776;15919.840801591836;16706.49880503587;17362.5659358512;17368.39210627622;19290.71063934014;19335.51661208481;19787.208511659373;20370.898145509178;20564;21054.684179737622;21309.254176104383;21365.786342137002;21379.25172618951;21437.892810535846;18055.368062117763;19010.809891900903;19054.142563584395;19055.047247637838;19138.904305478383 03:08:55 INFO - PID 11006 | |3;mootools.html;1340.7921772125321;1370.7669696151654;1390.5272207449468;1392.617030316662;1398.3707331700737;13552.186868787867;13878.653033674163;13959.651008724786;14057.92971035146;14069.718605627893;14524.782128268085;14677.853221467782;14698.63253418665;14768.483103091394;14790.482333118343;2502.3493891588137;2583.5091332646784;2610.595357719547;2619.8821053052657;2640.749128832758;2591.585346344585;2704.594310853368;2706.7563919247264;2708.6072519484665;2708.756211940925;2635.2489540480965;2637.103384849151;2684.5302072137397;2686.6373039639693;2700.9189724308303;2539.0986199898953;2542.8601426921514;2550.60465627828;2551.6682831232006;2555.1440267510407;2475.331660451668;2500.762427569378;2529.8102642301715;2531.962020569693;2537.7057700572655;13752.31238438074;13851.792223116661;13875.722485550232;13936.512222072179;13959;7331.523450975669;7814;7821.2960833524685;7826.17825128359;7854;4767.404074490689;4868.878278043042;4879.170541008037;4882.7558622068755;4917.803287868481;1720.9053502057382;1725.6203635200236;1749.2915369275463;1781.3943259291902;1783.0015191492805;22893.88553057224;23026.884865575732;23132;23188.76811231883;23196.072157113696;6442.420182183626;6470.5794123382775;6512.5441219115155;6687.665616719145;6711.295313992058;15663.530094097196;15762;15774.76337854933;15784.763228551581;15793.21033948321;7370.705171793175;7501.512401693873;7536.434767392468;7543.170251272356;7545.924540754632;6887.621180835002;7004.299570042955;7047.647617619098;7052.541584796972;7062.646867656596;4998.775055122528;5096.745162741848;5116.130257856174;5156.74216289184;5175.663581867167;1544.1352842408062;1548.41934274647;1559.9766003509956;1592.6177717347985;1612.5243053299305;785.6896525872248;791.1297572670132;810.1979040749616;835.4486039214089;835.4861760017545;7030.437564994788;7039.683214255371;7164.92526121086;7175.780117380058;7185.173705023989;937.6671281695036;955.5795450001976;972.6188811887138;981.1316984468797;988.1699372527112;4546.681732278708;4548.249538826057;4625.560571745679;4701.106789710013;4719.1741445247635;17356.91321543384;17497;17772.46682599524;18792.906035469736;19677.704834427495;18985.810141898404;19097.522561936064;19284.168004039595;19310.227590896207;19310.613787724447;10254.692359229235;10774.461276936121;10824;10930.617428389969;10932.999999999842;7063.964680176567;7495.775126746206;7604.885926710993;7957;7985.760427187193;16447.013179209287;16602.086885220946;16648.750268745735;16873.409430669868;17067.06131162798;16349.673006539564;17138.571535711708;17169.141542923055;17238.569035774206;17339;11304.208705390543;11424.086073114297;11435.542578296774;11493.712657183638;11507.194496385173;12883.162594431333;13048.217106973612;13124.278164701032;13128.803067954179;13136.802947955597;8849.646014159362;9911.851322230028;10573.154147668321;10741.516631751747;10750.892491074988;9177.128172823572;9881.604735810488;9960.352577082467;10063.295569310078;10091.545880435546;7853.882191767242;7954.164812694584;7956.124826269222;7970.601469926596;7994.360451163777;6428.903566446414;6474.1907261592305;6612.471002319707;6613.470922326299;6770.458363330823;6496.967515162394;6745.325467453412;6767.526273160832;6886.139232595926;6953.4089602384365 03:08:55 INFO - PID 11006 | |4;prototype.html;493.9087824370703;499.21872269897597;509.7807942584687;520.8073012985194;525.4719007397565;6115.663638499881;6180.578466952604;6240.15757872687;6247.562670613059;6260.624362538251;5765.827025189241;5988.550858685594;6022.187004754357;6042.395760423956;6111.541634377417;4816.4461086974925;4889.144399730051;4910.337104490884;4921.409430868301;4957.5042495750495;4848.6848354857;4908.950910490903;4931.5315045070665;4932.5807306379;4966.528179822911;4674.275487299457;4822.156122678535;4846.127697014536;4875.024995000997;4881.414230292352;4875.58557522611;4879.804807807684;4908.656394052417;4910.877228069291;4935.519344196727;4369.4319738434115;4426.579474949875;4434.512203657595;4442.3336499525;4447.755373454459;5923.318818335861;6036.909446358308;6045.486133678643;6054.455099041107;6058.969975104243;5684.914726279109;5698.862926402599;5702.800401985911;5711.143328500758;5725.055365864628;6501.804945851632;6515.446187074104;6530.2816690164045;6537.515744017243;6545.967270163666;4791.592714619262;4812.181929072031;4821.63837712173;4830.492798256168;4861.416630004387;6658.700358483879;6788.947713104477;6788.999999999951;6798.796036118924;6800.455963522906;4895.143349913751;4906.9263961040615;4951.306817045616;4974.1543937530705;4987.202047672355;4972.179590367621;4993.27597498368;5015.724135172601;5018.172001619692;5031.144705400091;5585.888282234332;5622.24099746529;5623.297087864017;5649.830505084854;5660.943390566124;6385.680715964183;6443.613383197023;6462.159919210472;6550.574212676161;6612.07430959666;1674.472541149534;1698.9235484403232;1709.8033726121407;1710.4697543761474;1744.624905645267;1161.4378660701343;1203.2539825308365;1205.662414523917;1210.9515619375302;1226.3316492511603;5620.398186516823;5632;5650.66096034231;5702.287214098238;5737.3688894221605;6393.296737358887;6398.040293955851;6472.967635161888;6530.151080359523;6676.165479315086;5708.229389032511;5711.771529138871;5717.571182161355;5735.11105778605;5739.110437882135;3426.725861931089;3480.2343484433386;3483.860645574199;3502.4414135709367;3526.717862570988;6327.936720632735;6415.839604009937;6440.645764482904;6449.290578036413;6490.286068532458;6768.037269191879;6783.932160678429;6823.112995310677;6839.521233513607;6886.380225779704;6317.431431171217;6328.240611126695;6363.140975968277;6429.58207716497;6489.3510648934725;6625.1511849905;6665.2334981475815;6680.962306496371;6686.2281495402785;6712.362325579063;6562.77030303937;6588.637624930675;6601.801945941629;6622.933770662232;6624.56940298879;6505.902411463737;6512;6513.8697226056165;6537.8692426152165;6562.3109573496;4821.855344339676;4845.6608037437045;4888.58447031992;4889.413270407574;4927.384076990376;6615.338466153346;6645.534812563074;6696.263411024686;6717.664116794239;6746.797596072126;6410.743570257137;6425.421712045938;6476.967615161894;6487;6495.740170393131;6350.841228969312;6421.261554921057;6424.967875160594;6430.614163150226;6435.839104022437;6048.697565121638;6049.274087109575;6214.223222097238;6303.6532990685955;6325.96837015812;6359.332270111571;6359.8092057238355;6461.741530338733;6504.479641628753;6516.543841931019;6200.162977998093;6202.751889924352;6216.222972128484;6248.812535623939;6278.6860656966055 03:08:55 INFO - PID 11006 | |5;yui.html;885.5527958381012;891.1934699097317;903.0337538833447;907.0476000199789;910.6084383715004;18182.454526364214;19066.523336916584;19089.23643054278;19117.904410477946;21142.682859757115;20118.195272189132;20946.685799713017;21147.682784758243;21193.152273909065;21279.04244309006;986.6990567876795;992.811365840488;992.8113658404916;993.061556828797;1009.6516701737906;977.3500622086304;1011.8633984412121;1053.441675911768;1060.6605886116445;1061.1881910338598;1109.4120116338313;1117.9664610061711;1118.3010618363523;1118.5637601335445;1119.0152665654214;1099.285464448111;1104.0725790336076;1104.6244276945797;1104.8232282834708;1105.5633024955139;809.1908091908092;811.4482152136545;811.6793866422789;813.6379311206515;814.9022117345897;18449.815501844943;19340.80659193404;19531.90234048835;19702.901485492483;19774.20903163872;19551.413457596296;19973.800261997338;20203.19187232509;20233.089510971895;20305.695414568792;14610.415583376653;15079.698406031928;15146.394144234217;15271.160086195256;15276.465323713617;3444.4144495435835;3482.1990942083207;3489.59869614995;3493.8777142800136;3496.6328535503662;115591.42204288924;118313;120086.39956800247;120307.398463008;120862.39568802188;15740.212989350486;16474;16648.3340666372;16648.583785405463;16664.50006499807;30564.54153187704;30569.38861222763;30633.540496892565;30642.38715225683;30654.54018189729;7925.167857375008;8259.95870020646;8345.582720863933;8356.331493480508;8433.072362040171;4074.572169922175;4188.602082802129;4191.224623444611;4217.40956266123;4224.408582798411;5101.69389836611;5105.2342148678;5161.948380516147;5253.842384728464;5260.605454590921;916.5554705967601;934.3693007220235;939.1688355805162;941.6798288581915;947.6304241345881;551.1457241276087;552.1248820619284;575.6776205325032;660.5376236634376;667.2260178193272;11869.28784272929;12070.336131512851;12094.516219351128;12116.39418029095;12202.962748166328;651.5764752910646;655.0370954696589;671.7850287907822;699.5452955578914;702.6943279673359;5287.947120528823;5347.780671021483;5427.150745858456;5427.837164884981;5521.944780552143;95042.0495795047;95763.52118239504;99215.50392248137;100140.49929750305;100516;84706;88840.5557972206;89606.10393896108;90666;93989;77980.61009694915;79440;80630;81747.59126204331;81955;10836.783264334827;10886.782264354826;11144.275622084862;11258.21192516516;11378.601748939078;31526.684733152375;36123.458148127276;37300.6269937308;37811;38479;62339;68148.45921570885;68384;69868;70017.29982700107;18498.16758245909;18677.813221867607;19253.133608987348;19553.60892782165;19579.902100489406;19442.611147777247;20514.897425513373;20564.69152962677;20748.273810416562;21029.263975760776;16714.749278760584;17574.20916058755;18175;18590.907045464686;19238.903805480884;19482;19489.805101948798;19516.121774519896;19681.60636787285;19743.210271588974;17638.118094095502;17693.55766105806;17737.556561086076;18104.547386315448;18754.249830006647;11185.217034807485;12265.448054837376;12312.445939932546;12903.483860645469;12923.030772691898;13404.798928016282;13789.448422063006;14141.858581414053;14304.213268269928;14398.640033999234 03:08:55 INFO - PID 11006 | __end_tp_report 03:08:55 INFO - PID 11006 | __start_cc_report 03:08:55 INFO - PID 11006 | _x_x_mozilla_cycle_collect,370 03:08:55 INFO - PID 11006 | __end_cc_report 03:08:55 INFO - PID 11006 | __startTimestamp1501150135312__endTimestamp 03:08:55 INFO - PID 11006 | 03:08:55 INFO - PID 11006 | ------- Summary: start ------- 03:08:55 INFO - PID 11006 | Number of tests: 6 03:08:55 INFO - PID 11006 | 03:08:55 INFO - PID 11006 | [#0] dojo.html Cycles:165 Average:6130.39 Median:6396.71 stddev:718.57 (11.2%) stddev-sans-first:680.87 03:08:55 INFO - PID 11006 | Values: 3120.5 3158.1 3173.8 3188.0 3198.5 6100.2 6191.3 6205.3 6260.6 6276.2 6062.7 6160.6 6173.2 6182.9 6215.1 5673.5 5682.3 5688.3 5697.5 5719.9 5653.5 5696.0 5713.9 5768.7 5817.2 5632.4 5709.0 5712.9 5746.5 5746.9 5514.6 5708.3 5716.1 5776.5 5779.8 5416.9 5567.3 5609.8 5653.5 5674.0 6080.1 6082.9 6087.1 6117.9 6185.8 6456.0 6577.7 6636.4 6651.1 6738.0 6642.4 6699.5 6705.0 6710.5 6716.4 6180.6 6181.3 6187.6 6192.5 6193.9 6792.7 6860.2 6880.0 6893.2 6899.4 6491.3 6575.1 6585.6 6597.6 6600.9 6666.4 6673.1 6675.7 6682.2 6688.7 6584.6 6641.5 6660.5 6719.2 6722.1 6589.2 6612.7 6636.8 6727.8 6731.3 5304.1 5373.2 5377.0 5379.3 5394.3 4639.7 4641.9 4681.6 4682.0 4703.7 5727.0 5831.1 5832.5 5836.3 5842.3 6614.2 6629.8 6685.9 6713.6 6714.0 5605.6 5712.0 5753.7 5846.4 5850.2 5904.8 5909.6 5915.3 5926.7 5935.9 6460.2 6506.2 6530.1 6540.7 6576.7 6655.0 6655.6 6743.4 6757.3 6767.1 6339.3 6454.3 6462.4 6476.1 6562.0 6528.3 6546.4 6548.5 6553.3 6558.1 6592.5 6688.9 6695.6 6696.4 6699.2 6088.2 6420.5 6447.7 6449.5 6531.3 6496.9 6526.3 6537.7 6555.7 6571.5 6286.1 6392.9 6396.7 6478.4 6511.8 6455.5 6457.4 6527.9 6538.2 6550.6 6301.1 6302.2 6380.1 6399.8 6399.9 03:08:55 INFO - PID 11006 | 03:08:55 INFO - PID 11006 | [#1] ext.html Cycles:180 Average:35961.18 Median:33685.50 stddev:51105.32 (151.7%) stddev-sans-first:51180.59 03:08:55 INFO - PID 11006 | Values: 826.7 871.4 890.2 899.0 1184.0 30159.7 40211.4 40960.8 41035.0 41131.4 40991.8 41325.6 41330.0 41389.0 41481.6 7645.0 7773.7 7775.0 7776.8 7777.3 7392.1 7434.9 7461.6 7471.9 7473.5 7664.2 7747.5 7779.6 7797.1 7805.2 7678.5 7733.5 7742.7 7743.9 7744.6 7681.2 7705.1 7708.6 7724.4 7774.4 36787.3 37182.0 37187.6 37245.3 37247.4 3393.2 3446.5 3453.6 3456.3 3458.5 31039.8 31131.8 31170.2 31176.4 31194.5 4739.1 4811.5 4840.3 4842.8 4901.9 177781.0 178756.0 178915.0 179580.0 179804.0 47859.3 47950.0 48025.0 48056.8 48079.3 49531.3 49830.0 49844.8 49848.0 49862.5 16171.8 16319.0 16320.3 16321.9 16337.3 13388.1 13436.2 13496.5 13509.0 13521.1 12528.6 12675.6 14342.6 14366.2 14396.9 5500.6 5660.4 5700.6 5715.8 5793.5 973.5 992.8 996.5 1002.5 1011.3 22024.9 22213.1 22240.2 22268.8 22771.9 1046.5 1079.3 1089.6 1110.2 1118.4 11518.3 11622.3 11775.9 11787.9 11816.3 227196.0 228243.0 228311.0 228839.0 230148.0 49009.0 49227.0 49441.3 49612.5 49820.0 1446.9 1487.8 1494.6 1498.2 1522.6 21723.8 21788.6 22396.0 22593.9 22724.5 35903.6 35994.6 36322.3 36336.8 36352.8 179327.0 180256.0 180848.0 180891.0 180980.0 31735.5 32015.7 32182.8 32228.2 32679.8 40678.8 40806.6 40885.0 41043.2 41489.4 27286.5 28259.3 29068.6 29338.9 29711.4 28763.7 29057.3 29157.6 29472.3 29640.3 32425.2 33570.2 33800.8 33941.7 34126.8 28214.3 28949.4 29781.9 30198.2 32910.7 23323.4 23605.5 23724.6 23903.9 24208.3 03:08:55 INFO - PID 11006 | 03:08:55 INFO - PID 11006 | [#2] jquery.html Cycles:180 Average:37217.82 Median:2295.47 stddev:93285.90 (4063.9%) stddev-sans-first:93507.02 03:08:55 INFO - PID 11006 | Values: 574.7 575.4 576.8 582.5 582.6 21861.1 22380.6 22713.5 22826.9 22853.8 26177.9 26648.3 26756.7 26780.5 26786.6 6438.8 6691.3 6692.0 6694.5 6696.8 5940.7 6545.9 6552.7 6556.8 6621.1 6508.5 6517.0 6518.6 6520.2 6523.8 6493.6 6504.0 6508.5 6508.9 6527.5 6009.4 6070.5 6070.6 6076.1 6078.1 28309.9 29167.4 29749.9 30299.0 30512.4 1857.4 1883.2 1884.7 1885.5 1887.4 18801.9 19318.6 19365.8 19375.0 19419.1 2293.0 2298.0 2306.4 2342.6 2425.3 126134.0 128925.4 129061.4 129316.4 129617.0 13939.4 14788.9 14862.5 15124.9 15379.4 23175.2 23181.3 23184.2 23198.0 23210.3 10050.1 10087.1 10143.5 10188.7 10246.9 8005.8 9239.1 9244.1 9385.0 9535.9 8227.4 9130.8 9266.4 9286.2 9677.6 2786.9 2799.6 2817.1 2836.2 2857.4 949.4 972.7 979.5 994.9 996.6 19544.7 19702.6 19989.2 20547.5 24547.0 1029.3 1040.8 1041.5 1057.3 1064.7 10625.9 10706.8 11179.9 11387.3 11490.0 552818.0 554501.0 558357.0 563818.0 568829.0 123669.0 124055.4 127337.0 127603.0 128338.0 1248.8 1266.3 1277.6 1294.6 1305.3 18935.6 21114.8 21478.9 21676.0 21685.6 57406.4 57453.1 58274.0 58660.0 59974.1 68977.7 68980.0 69449.3 69505.7 70228.6 17118.4 17282.7 17725.0 17867.9 20602.1 19312.7 24081.3 24339.0 24555.5 24618.5 18875.7 18906.4 19085.7 19161.4 19187.9 15719.2 15919.8 16706.5 17362.6 17368.4 19290.7 19335.5 19787.2 20370.9 20564.0 21054.7 21309.3 21365.8 21379.3 21437.9 18055.4 19010.8 19054.1 19055.0 19138.9 03:08:55 INFO - PID 11006 | 03:08:55 INFO - PID 11006 | [#3] mootools.html Cycles:180 Average:8518.77 Median:2569.33 stddev:5893.78 (229.4%) stddev-sans-first:5885.63 03:08:55 INFO - PID 11006 | Values: 1340.8 1370.8 1390.5 1392.6 1398.4 13552.2 13878.7 13959.7 14057.9 14069.7 14524.8 14677.9 14698.6 14768.5 14790.5 2502.3 2583.5 2610.6 2619.9 2640.7 2591.6 2704.6 2706.8 2708.6 2708.8 2635.2 2637.1 2684.5 2686.6 2700.9 2539.1 2542.9 2550.6 2551.7 2555.1 2475.3 2500.8 2529.8 2532.0 2537.7 13752.3 13851.8 13875.7 13936.5 13959.0 7331.5 7814.0 7821.3 7826.2 7854.0 4767.4 4868.9 4879.2 4882.8 4917.8 1720.9 1725.6 1749.3 1781.4 1783.0 22893.9 23026.9 23132.0 23188.8 23196.1 6442.4 6470.6 6512.5 6687.7 6711.3 15663.5 15762.0 15774.8 15784.8 15793.2 7370.7 7501.5 7536.4 7543.2 7545.9 6887.6 7004.3 7047.6 7052.5 7062.6 4998.8 5096.7 5116.1 5156.7 5175.7 1544.1 1548.4 1560.0 1592.6 1612.5 785.7 791.1 810.2 835.4 835.5 7030.4 7039.7 7164.9 7175.8 7185.2 937.7 955.6 972.6 981.1 988.2 4546.7 4548.2 4625.6 4701.1 4719.2 17356.9 17497.0 17772.5 18792.9 19677.7 18985.8 19097.5 19284.2 19310.2 19310.6 10254.7 10774.5 10824.0 10930.6 10933.0 7064.0 7495.8 7604.9 7957.0 7985.8 16447.0 16602.1 16648.8 16873.4 17067.1 16349.7 17138.6 17169.1 17238.6 17339.0 11304.2 11424.1 11435.5 11493.7 11507.2 12883.2 13048.2 13124.3 13128.8 13136.8 8849.6 9911.9 10573.2 10741.5 10750.9 9177.1 9881.6 9960.4 10063.3 10091.5 7853.9 7954.2 7956.1 7970.6 7994.4 6428.9 6474.2 6612.5 6613.5 6770.5 6497.0 6745.3 6767.5 6886.1 6953.4 03:08:55 INFO - PID 11006 | 03:08:55 INFO - PID 11006 | [#4] prototype.html Cycles:180 Average:5432.75 Median:6043.94 stddev:1528.92 (25.3%) stddev-sans-first:1487.59 03:08:55 INFO - PID 11006 | Values: 493.9 499.2 509.8 520.8 525.5 6115.7 6180.6 6240.2 6247.6 6260.6 5765.8 5988.6 6022.2 6042.4 6111.5 4816.4 4889.1 4910.3 4921.4 4957.5 4848.7 4909.0 4931.5 4932.6 4966.5 4674.3 4822.2 4846.1 4875.0 4881.4 4875.6 4879.8 4908.7 4910.9 4935.5 4369.4 4426.6 4434.5 4442.3 4447.8 5923.3 6036.9 6045.5 6054.5 6059.0 5684.9 5698.9 5702.8 5711.1 5725.1 6501.8 6515.4 6530.3 6537.5 6546.0 4791.6 4812.2 4821.6 4830.5 4861.4 6658.7 6788.9 6789.0 6798.8 6800.5 4895.1 4906.9 4951.3 4974.2 4987.2 4972.2 4993.3 5015.7 5018.2 5031.1 5585.9 5622.2 5623.3 5649.8 5660.9 6385.7 6443.6 6462.2 6550.6 6612.1 1674.5 1698.9 1709.8 1710.5 1744.6 1161.4 1203.3 1205.7 1211.0 1226.3 5620.4 5632.0 5650.7 5702.3 5737.4 6393.3 6398.0 6473.0 6530.2 6676.2 5708.2 5711.8 5717.6 5735.1 5739.1 3426.7 3480.2 3483.9 3502.4 3526.7 6327.9 6415.8 6440.6 6449.3 6490.3 6768.0 6783.9 6823.1 6839.5 6886.4 6317.4 6328.2 6363.1 6429.6 6489.4 6625.2 6665.2 6681.0 6686.2 6712.4 6562.8 6588.6 6601.8 6622.9 6624.6 6505.9 6512.0 6513.9 6537.9 6562.3 4821.9 4845.7 4888.6 4889.4 4927.4 6615.3 6645.5 6696.3 6717.7 6746.8 6410.7 6425.4 6477.0 6487.0 6495.7 6350.8 6421.3 6425.0 6430.6 6435.8 6048.7 6049.3 6214.2 6303.7 6326.0 6359.3 6359.8 6461.7 6504.5 6516.5 6200.2 6202.8 6216.2 6248.8 6278.7 03:08:55 INFO - PID 11006 | 03:08:55 INFO - PID 11006 | [#5] yui.html Cycles:180 Average:22562.44 Median:20847.48 stddev:29668.08 (142.3%) stddev-sans-first:29706.65 03:08:55 INFO - PID 11006 | Values: 885.6 891.2 903.0 907.0 910.6 18182.5 19066.5 19089.2 19117.9 21142.7 20118.2 20946.7 21147.7 21193.2 21279.0 986.7 992.8 992.8 993.1 1009.7 977.4 1011.9 1053.4 1060.7 1061.2 1109.4 1118.0 1118.3 1118.6 1119.0 1099.3 1104.1 1104.6 1104.8 1105.6 809.2 811.4 811.7 813.6 814.9 18449.8 19340.8 19531.9 19702.9 19774.2 19551.4 19973.8 20203.2 20233.1 20305.7 14610.4 15079.7 15146.4 15271.2 15276.5 3444.4 3482.2 3489.6 3493.9 3496.6 115591.4 118313.0 120086.4 120307.4 120862.4 15740.2 16474.0 16648.3 16648.6 16664.5 30564.5 30569.4 30633.5 30642.4 30654.5 7925.2 8260.0 8345.6 8356.3 8433.1 4074.6 4188.6 4191.2 4217.4 4224.4 5101.7 5105.2 5161.9 5253.8 5260.6 916.6 934.4 939.2 941.7 947.6 551.1 552.1 575.7 660.5 667.2 11869.3 12070.3 12094.5 12116.4 12203.0 651.6 655.0 671.8 699.5 702.7 5287.9 5347.8 5427.2 5427.8 5521.9 95042.0 95763.5 99215.5 100140.5 100516.0 84706.0 88840.6 89606.1 90666.0 93989.0 77980.6 79440.0 80630.0 81747.6 81955.0 10836.8 10886.8 11144.3 11258.2 11378.6 31526.7 36123.5 37300.6 37811.0 38479.0 62339.0 68148.5 68384.0 69868.0 70017.3 18498.2 18677.8 19253.1 19553.6 19579.9 19442.6 20514.9 20564.7 20748.3 21029.3 16714.7 17574.2 18175.0 18590.9 19238.9 19482.0 19489.8 19516.1 19681.6 19743.2 17638.1 17693.6 17737.6 18104.5 18754.2 11185.2 12265.4 12312.4 12903.5 12923.0 13404.8 13789.4 14141.9 14304.2 14398.6 03:08:55 INFO - PID 11006 | -------- Summary: end -------- 03:08:55 INFO - PID 11006 | 03:08:56 INFO - TEST-INFO | 11006: exit 0 03:08:56 INFO - TEST-OK | dromaeo_css | took 1113383ms 03:08:56 INFO - TEST-START | kraken 03:08:56 INFO - Initialising browser for kraken test... 03:08:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:58051/getInfo.html -profile /tmp/tmp3ZOoj8/profile 03:08:56 INFO - TEST-INFO | started process 11373 (/builds/slave/test/build/application/firefox/firefox http://localhost:58051/getInfo.html) 03:09:02 INFO - TEST-INFO | 11373: exit 0 03:09:02 INFO - Browser initialized. 03:09:02 INFO - Running cycle 1/1 for kraken test... 03:09:02 INFO - TEST-INFO | started process 11598 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3ZOoj8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:09:03 INFO - PID 11598 | 03:09:03 INFO - PID 11598 | (/builds/slave/test/build/application/firefox/firefox:11653): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:09:03 INFO - PID 11598 | 03:09:13 INFO - PID 11598 | RSS: Main: 170733568 03:09:13 INFO - PID 11598 | 03:09:16 INFO - PID 11598 | Cycle 1(1): loaded http://localhost:58051/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58051/tests/kraken/driver.html?testName=audio-beat-detection) 03:09:17 INFO - PID 11598 | RSS: Main: 177143808 03:09:17 INFO - PID 11598 | 03:09:20 INFO - PID 11598 | Cycle 1(1): loaded http://localhost:58051/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58051/tests/kraken/driver.html?testName=audio-dft) 03:09:20 INFO - PID 11598 | RSS: Main: 177516544 03:09:20 INFO - PID 11598 | 03:09:23 INFO - PID 11598 | Cycle 1(1): loaded http://localhost:58051/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58051/tests/kraken/driver.html?testName=audio-fft) 03:09:24 INFO - PID 11598 | RSS: Main: 172773376 03:09:24 INFO - PID 11598 | 03:09:26 INFO - PID 11598 | Cycle 1(1): loaded http://localhost:58051/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58051/tests/kraken/driver.html?testName=audio-oscillator) 03:09:27 INFO - PID 11598 | RSS: Main: 173264896 03:09:27 INFO - PID 11598 | 03:09:29 INFO - PID 11598 | Cycle 1(1): loaded http://localhost:58051/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58051/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:09:30 INFO - PID 11598 | RSS: Main: 173395968 03:09:30 INFO - PID 11598 | 03:09:33 INFO - PID 11598 | Cycle 1(1): loaded http://localhost:58051/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58051/tests/kraken/driver.html?testName=imaging-darkroom) 03:09:33 INFO - PID 11598 | RSS: Main: 176197632 03:09:33 INFO - PID 11598 | 03:09:37 INFO - PID 11598 | Cycle 1(1): loaded http://localhost:58051/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58051/tests/kraken/driver.html?testName=imaging-desaturate) 03:09:38 INFO - PID 11598 | RSS: Main: 175853568 03:09:38 INFO - PID 11598 | 03:09:40 INFO - PID 11598 | Cycle 1(1): loaded http://localhost:58051/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58051/tests/kraken/driver.html?testName=json-parse-financial) 03:09:41 INFO - PID 11598 | RSS: Main: 176537600 03:09:41 INFO - PID 11598 | 03:09:43 INFO - PID 11598 | Cycle 1(1): loaded http://localhost:58051/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58051/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:09:43 INFO - PID 11598 | RSS: Main: 176623616 03:09:43 INFO - PID 11598 | 03:09:45 INFO - PID 11598 | Cycle 1(1): loaded http://localhost:58051/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58051/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:09:46 INFO - PID 11598 | RSS: Main: 174133248 03:09:46 INFO - PID 11598 | 03:09:48 INFO - PID 11598 | Cycle 1(1): loaded http://localhost:58051/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58051/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:09:49 INFO - PID 11598 | RSS: Main: 174514176 03:09:49 INFO - PID 11598 | 03:09:52 INFO - PID 11598 | Cycle 1(1): loaded http://localhost:58051/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58051/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:09:52 INFO - PID 11598 | RSS: Main: 174415872 03:09:52 INFO - PID 11598 | 03:09:55 INFO - PID 11598 | Cycle 1(1): loaded http://localhost:58051/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58051/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:09:56 INFO - PID 11598 | RSS: Main: 174477312 03:09:56 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | Cycle 1(1): loaded http://localhost:58051/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58051/tests/kraken/driver.html?testName=ai-astar) 03:09:58 INFO - PID 11598 | RSS: Main: 176144384 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | __start_tp_report 03:09:58 INFO - PID 11598 | _x_x_mozilla_page_load 03:09:58 INFO - PID 11598 | _x_x_mozilla_page_load_details 03:09:58 INFO - PID 11598 | |i|pagename|runs| 03:09:58 INFO - PID 11598 | |0;ai-astar;100;108;110;107;114;119;111;102;111;112 03:09:58 INFO - PID 11598 | |1;audio-beat-detection;149;120;147;180;118;145;165;121;173;117 03:09:58 INFO - PID 11598 | |2;audio-dft;164;167;162;164;164;165;168;162;163;162 03:09:58 INFO - PID 11598 | |3;audio-fft;76;75;78;167;76;75;74;86;154;76 03:09:58 INFO - PID 11598 | |4;audio-oscillator;78;86;134;78;83;85;125;79;79;129 03:09:58 INFO - PID 11598 | |5;imaging-gaussian-blur;114;114;122;113;114;114;113;119;113;114 03:09:58 INFO - PID 11598 | |6;imaging-darkroom;150;150;149;149;150;149;150;153;150;155 03:09:58 INFO - PID 11598 | |7;imaging-desaturate;96;95;95;94;94;94;94;95;93;94 03:09:58 INFO - PID 11598 | |8;json-parse-financial;57;62;59;64;56;62;58;56;56;55 03:09:58 INFO - PID 11598 | |9;json-stringify-tinderbox;54;53;53;54;55;56;54;54;54;58 03:09:58 INFO - PID 11598 | |10;stanford-crypto-aes;82;99;83;81;83;102;88;88;82;80 03:09:58 INFO - PID 11598 | |11;stanford-crypto-ccm;133;135;126;127;135;137;126;132;128;179 03:09:58 INFO - PID 11598 | |12;stanford-crypto-pbkdf2;181;180;179;179;178;177;186;178;177;174 03:09:58 INFO - PID 11598 | |13;stanford-crypto-sha256-iterative;69;77;75;69;68;68;72;69;75;68 03:09:58 INFO - PID 11598 | __end_tp_report 03:09:58 INFO - PID 11598 | __start_cc_report 03:09:58 INFO - PID 11598 | _x_x_mozilla_cycle_collect,940 03:09:58 INFO - PID 11598 | __end_cc_report 03:09:58 INFO - PID 11598 | __startTimestamp1501150198703__endTimestamp 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | ------- Summary: start ------- 03:09:58 INFO - PID 11598 | Number of tests: 14 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | [#0] ai-astar Cycles:10 Average:109.40 Median:111.00 stddev:5.54 (5.0%) stddev-sans-first:4.72 03:09:58 INFO - PID 11598 | Values: 100.0 108.0 110.0 107.0 114.0 119.0 111.0 102.0 111.0 112.0 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:148.00 stddev:23.85 (16.1%) stddev-sans-first:25.22 03:09:58 INFO - PID 11598 | Values: 149.0 120.0 147.0 180.0 118.0 145.0 165.0 121.0 173.0 117.0 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:2.08 (1.3%) stddev-sans-first:2.20 03:09:58 INFO - PID 11598 | Values: 164.0 167.0 162.0 164.0 164.0 165.0 168.0 162.0 163.0 162.0 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | [#3] audio-fft Cycles:10 Average:93.70 Median:76.00 stddev:35.50 (46.7%) stddev-sans-first:37.07 03:09:58 INFO - PID 11598 | Values: 76.0 75.0 78.0 167.0 76.0 75.0 74.0 86.0 154.0 76.0 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:79.00 stddev:23.54 (29.8%) stddev-sans-first:24.09 03:09:58 INFO - PID 11598 | Values: 78.0 86.0 134.0 78.0 83.0 85.0 125.0 79.0 79.0 129.0 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | [#5] imaging-gaussian-blur Cycles:10 Average:115.00 Median:114.00 stddev:3.02 (2.6%) stddev-sans-first:3.18 03:09:58 INFO - PID 11598 | Values: 114.0 114.0 122.0 113.0 114.0 114.0 113.0 119.0 113.0 114.0 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | [#6] imaging-darkroom Cycles:10 Average:150.50 Median:150.00 stddev:1.96 (1.3%) stddev-sans-first:2.07 03:09:58 INFO - PID 11598 | Values: 150.0 150.0 149.0 149.0 150.0 149.0 150.0 153.0 150.0 155.0 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.84 (0.9%) stddev-sans-first:0.67 03:09:58 INFO - PID 11598 | Values: 96.0 95.0 95.0 94.0 94.0 94.0 94.0 95.0 93.0 94.0 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | [#8] json-parse-financial Cycles:10 Average:58.50 Median:58.50 stddev:3.14 (5.4%) stddev-sans-first:3.28 03:09:58 INFO - PID 11598 | Values: 57.0 62.0 59.0 64.0 56.0 62.0 58.0 56.0 56.0 55.0 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | [#9] json-stringify-tinderbox Cycles:10 Average:54.50 Median:54.00 stddev:1.51 (2.8%) stddev-sans-first:1.59 03:09:58 INFO - PID 11598 | Values: 54.0 53.0 53.0 54.0 55.0 56.0 54.0 54.0 54.0 58.0 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:83.00 stddev:7.73 (9.3%) stddev-sans-first:8.00 03:09:58 INFO - PID 11598 | Values: 82.0 99.0 83.0 81.0 83.0 102.0 88.0 88.0 82.0 80.0 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | [#11] stanford-crypto-ccm Cycles:10 Average:135.80 Median:134.00 stddev:15.71 (11.7%) stddev-sans-first:16.63 03:09:58 INFO - PID 11598 | Values: 133.0 135.0 126.0 127.0 135.0 137.0 126.0 132.0 128.0 179.0 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.90 Median:179.00 stddev:3.14 (1.8%) stddev-sans-first:3.24 03:09:58 INFO - PID 11598 | Values: 181.0 180.0 179.0 179.0 178.0 177.0 186.0 178.0 177.0 174.0 03:09:58 INFO - PID 11598 | 03:09:58 INFO - PID 11598 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:70.50 stddev:3.46 (4.9%) stddev-sans-first:3.60 03:09:58 INFO - PID 11598 | Values: 69.0 77.0 75.0 69.0 68.0 68.0 72.0 69.0 75.0 68.0 03:09:58 INFO - PID 11598 | -------- Summary: end -------- 03:09:58 INFO - PID 11598 | 03:09:59 INFO - PID 11598 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:09:59 INFO - TEST-INFO | 11598: exit 0 03:09:59 INFO - TEST-OK | kraken | took 63357ms 03:09:59 INFO - SUITE-END | took 1176s 03:09:59 INFO - Completed test suite (00:19:37) 03:09:59 INFO - javascript benchmark 03:09:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8377.267285672739, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3120.500719884819, 3158.099941516668, 3173.825439600821, 3187.952180717289, 3198.472252078407, 6100.23747031621, 6191.318954914956, 6205.348438413964, 6260.5930614510025, 6276.184096067511, 6062.727177277022, 6160.59956102853, 6173.197484327031, 6182.938170618292, 6215.09881067245, 5673.546116310706, 5682.26130603021, 5688.288963879515, 5697.515711164535, 5719.857003574903, 5653.547716182716, 5696.0, 5713.914291285634, 5768.711564421783, 5817.1565123057135, 5632.408597097307, 5709.0009248381575, 5712.857178570527, 5746.540276777869, 5746.885062298751, 5514.6139770216105, 5708.343540492855, 5716.114002329646, 5776.451237132465, 5779.797707080253, 5416.945830541684, 5567.331920169606, 5609.775608975636, 5653.519450846683, 5673.971630141864, 6080.087986801971, 6082.8783424331705, 6087.1173679816175, 6117.908231376533, 6185.84535386619, 6456.031595260702, 6577.6711164441585, 6636.435902948255, 6651.102101216371, 6738.0, 6642.402183803432, 6699.464042876558, 6705.027770973182, 6710.463162947001, 6716.429103526206, 6180.5673602848265, 6181.289151747562, 6187.597806142631, 6192.473639740628, 6193.907091393633, 6792.728290868409, 6860.176778786577, 6880.036794848726, 6893.241743408221, 6899.448044156456, 6491.318411566812, 6575.145231119924, 6585.604863708097, 6597.637129957802, 6600.900986485207, 6666.400023997863, 6673.099131617267, 6675.6662166891465, 6682.164729408824, 6688.7324507019175, 6584.572002819801, 6641.501887358468, 6660.467162626978, 6719.193696756421, 6722.126123603998, 6589.17635295588, 6612.7354905804195, 6636.800895973129, 6727.831804204836, 6731.2932142125355, 5304.071787437183, 5373.194020896896, 5377.0, 5379.300690910235, 5394.298741163623, 4639.721616702941, 4641.930371044437, 4681.625469962395, 4681.976590117028, 4703.670743047954, 5726.9713651431475, 5831.096180092092, 5832.504237139806, 5836.328822185418, 5842.298924129132, 6614.173228346457, 6629.834254143685, 6685.933140668531, 6713.597184168868, 6713.959336302881, 5605.579581531401, 5711.971440142773, 5753.654780713171, 5846.444587764156, 5850.180974663551, 5904.822855314347, 5909.556783241274, 5915.319738230072, 5926.67403292823, 5935.910961335583, 6460.224773027173, 6506.154199953976, 6530.085787989685, 6540.738370465128, 6576.736930522821, 6655.035019922181, 6655.633940133339, 6743.426808721314, 6757.290484499057, 6767.120274364301, 6339.302676705651, 6454.257760357619, 6462.353764623688, 6476.06097115925, 6561.967190164019, 6528.314526974599, 6546.378094081055, 6548.476121910331, 6553.311902250386, 6558.081868538504, 6592.472602191909, 6688.899666504909, 6695.631740254333, 6696.363845434676, 6699.196096468455, 6088.178095956989, 6420.518461115528, 6447.70985305653, 6449.4840412765925, 6531.281559028408, 6496.902546461902, 6526.282108967915, 6537.673116344069, 6555.737770489127, 6571.507136964842, 6286.057091436322, 6392.9360706392345, 6396.71214795326, 6478.352164783484, 6511.837204069936, 6455.515836312202, 6457.418832305115, 6527.934720652923, 6538.1827271591055, 6550.6069635822005, 6301.149344838388, 6302.243730752339, 6380.138681277968, 6399.8080057598345, 6399.936000639934], "value": 6077.080605880724, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [826.7354446577098, 871.4292138649186, 890.1587999340621, 899.0155779421535, 1184.0290961411647, 30159.698403015962, 40211.39682904759, 40960.79519602405, 41035.0, 41131.38302925458, 40991.79504102475, 41325.586744132546, 41329.99999999993, 41389.0, 41481.58518414815, 7645.044369453818, 7773.650185741655, 7775.028121484815, 7776.766696999099, 7777.300042996129, 7392.112946446434, 7434.925650743505, 7461.552306861606, 7471.887921681179, 7473.476856620039, 7664.156942736295, 7747.535147891116, 7779.572123533203, 7797.064352277706, 7805.219478052206, 7678.500897441649, 7733.45865789395, 7742.651580678883, 7743.8838417423785, 7744.612769361537, 7681.23187681233, 7705.075390953121, 7708.57602831844, 7724.420668449832, 7774.378049756006, 36787.264254715024, 37182.0, 37187.628123718685, 37245.255094898224, 37247.441288380694, 3393.202597389636, 3446.483027545863, 3453.6028356739043, 3456.3087382523595, 3458.515807786912, 31039.844800776078, 31131.844340778378, 31170.220744481343, 31176.37647247065, 31194.532082018788, 4739.052189562067, 4811.518848115195, 4840.322354870321, 4842.782074806642, 4901.852944411673, 177781.0, 178756.0, 178915.0, 179580.0, 179804.0, 47859.28211076836, 47950.0, 48025.03949920982, 48056.759716201195, 48079.278810817865, 49531.25703114456, 49830.0, 49844.750776245884, 49848.0, 49862.50137498651, 16171.838281617032, 16319.0, 16320.347186112422, 16321.91839040821, 16337.346506139858, 13388.129771564818, 13436.19382837033, 13496.527621533396, 13509.0, 13521.121127126706, 12528.561500347445, 12675.619731408073, 14342.641433964234, 14366.209858457676, 14396.928015359856, 5500.642458240201, 5660.377358490563, 5700.629459085146, 5715.828525144252, 5793.478586927113, 973.454865275448, 992.7964767222702, 996.496769131584, 1002.5037606384886, 1011.2920955331298, 22024.889875550518, 22213.11147554112, 22240.221592244194, 22268.82216174163, 22771.88614056919, 1046.4506134279563, 1079.341601623025, 1089.624079692505, 1110.228391268061, 1118.3681220110611, 11518.251313664585, 11622.302661840316, 11775.941120294343, 11787.88212117885, 11816.291022538675, 227196.0, 228243.0, 228311.0, 228839.0, 230148.0, 49009.0, 49227.0, 49441.25838112431, 49612.50387496151, 49820.0, 1446.8697817196503, 1487.7545205041263, 1494.5909566778048, 1498.2059508460534, 1522.5584580471761, 21723.805190714662, 21788.600334620412, 22396.0, 22593.887030564743, 22724.545509090058, 35903.640963591075, 35994.64005359913, 36322.27355452929, 36336.818315908255, 36352.81823590865, 179327.0, 180256.0, 180848.0, 180891.0, 180980.0, 31735.523967140973, 32015.67984320127, 32182.839085804422, 32228.19429514281, 32679.836600816845, 40678.79660601678, 40806.59193408028, 40885.0, 41043.179136417704, 41489.37765933453, 27286.454270914866, 28259.293517662223, 29068.56397154002, 29338.853305733333, 29711.405771884874, 28763.712362876104, 29057.273568160967, 29157.562636560044, 29472.263193420335, 29640.258993525335, 32425.189370265933, 33570.16074598057, 33800.830995844866, 33941.660583394834, 34126.82936585301, 28214.294642634097, 28949.42101158007, 29781.851090744407, 30198.245043874078, 32910.670893290764, 23323.41691457659, 23605.452015580668, 23724.644130337714, 23903.880480597487, 24208.273751787474], "value": 16692.41244016295, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.6925394913721, 575.4360726488042, 576.7721749908786, 582.5252419278288, 582.6271186440677, 21861.125554977803, 22380.552388952212, 22713.54572908543, 22826.88586557069, 22853.77146228537, 26177.86911065442, 26648.33379165522, 26756.732432675668, 26780.464390712175, 26786.59820102695, 6438.839029024277, 6691.297413771557, 6692.0, 6694.531382803181, 6696.765613203539, 5940.673262970557, 6545.93454065458, 6552.705128269239, 6556.803295901131, 6621.106150669671, 6508.511861610375, 6516.967415162918, 6518.641474718888, 6520.152380190569, 6523.771667991621, 6493.577917435398, 6504.0, 6508.511861610375, 6508.9023664645065, 6527.510436717242, 6009.399060094, 6070.544709146831, 6070.6357618543025, 6076.058210977306, 6078.088286757022, 28309.858450707616, 29167.41665166706, 29749.851250743824, 30299.0, 30512.389752205054, 1857.3870622694478, 1883.1714045819933, 1884.6513395021964, 1885.5474686075381, 1887.4337698690476, 18801.90599046996, 19318.613627727507, 19365.806341936543, 19375.0, 19419.126139323766, 2292.977070229293, 2297.9540409191723, 2306.3888069661384, 2342.61346877765, 2425.2845410603913, 126134.0, 128925.35537322347, 129061.35469322593, 129316.3534182323, 129617.0, 13939.372728227254, 14788.926055369655, 14862.479813206703, 15124.882516447706, 15379.384824607114, 23175.188868389527, 23181.304560863202, 23184.18855340055, 23198.0, 23210.3036908893, 10050.09549140581, 10087.092161705481, 10143.492825358702, 10188.694339169837, 10246.948765256126, 8005.759827205193, 9239.076092390842, 9244.075592440837, 9385.0, 9535.90464095364, 8227.382946279053, 9130.771730706785, 9266.351355405191, 9286.210672092813, 9677.612895484102, 2786.916392508228, 2799.566067259578, 2817.1266907258814, 2836.1633318170775, 2857.414230082828, 949.3591825517748, 972.6595691508057, 979.4612962870393, 994.9303548751518, 996.5864166371019, 19544.70682939757, 19702.605947880962, 19989.200431982557, 20547.4863128423, 24547.0, 1029.3103620574234, 1040.8386700061503, 1041.5156952017348, 1057.3391630231106, 1064.7497838008062, 10625.9468702656, 10706.839397409045, 11179.888201118047, 11387.259828111146, 11490.0, 552818.0, 554501.0, 558357.0, 563818.0, 568829.0, 123669.0, 124055.3797231008, 127337.0, 127603.0, 128338.0, 1248.7502499500135, 1266.3288457117742, 1277.552856500236, 1294.58573256557, 1305.321232958875, 18935.621287573897, 21114.7888521119, 21478.892605536872, 21676.0, 21685.56628867382, 57406.425935741776, 57453.13820292615, 58274.0, 58660.0, 59974.10038849334, 68977.65511172412, 68980.0, 69449.30550694428, 69505.65247173731, 70228.64885675539, 17118.40085596998, 17282.74075888888, 17725.0, 17867.910660446614, 20602.07290671953, 19312.71030934509, 24081.277561673178, 24339.0, 24555.50888982246, 24618.50762984766, 18875.716864247323, 18906.43280701581, 19085.71371429402, 19161.425157245307, 19187.90406047961, 15719.21403929776, 15919.840801591836, 16706.49880503587, 17362.5659358512, 17368.39210627622, 19290.71063934014, 19335.51661208481, 19787.208511659373, 20370.898145509178, 20564.0, 21054.684179737622, 21309.254176104383, 21365.786342137002, 21379.25172618951, 21437.892810535846, 18055.368062117763, 19010.809891900903, 19054.142563584395, 19055.047247637838, 19138.904305478383], "value": 12342.733794124864, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.7921772125321, 1370.7669696151654, 1390.5272207449468, 1392.617030316662, 1398.3707331700737, 13552.186868787867, 13878.653033674163, 13959.651008724786, 14057.92971035146, 14069.718605627893, 14524.782128268085, 14677.853221467782, 14698.63253418665, 14768.483103091394, 14790.482333118343, 2502.3493891588137, 2583.5091332646784, 2610.595357719547, 2619.8821053052657, 2640.749128832758, 2591.585346344585, 2704.594310853368, 2706.7563919247264, 2708.6072519484665, 2708.756211940925, 2635.2489540480965, 2637.103384849151, 2684.5302072137397, 2686.6373039639693, 2700.9189724308303, 2539.0986199898953, 2542.8601426921514, 2550.60465627828, 2551.6682831232006, 2555.1440267510407, 2475.331660451668, 2500.762427569378, 2529.8102642301715, 2531.962020569693, 2537.7057700572655, 13752.31238438074, 13851.792223116661, 13875.722485550232, 13936.512222072179, 13959.0, 7331.523450975669, 7814.0, 7821.2960833524685, 7826.17825128359, 7854.0, 4767.404074490689, 4868.878278043042, 4879.170541008037, 4882.7558622068755, 4917.803287868481, 1720.9053502057382, 1725.6203635200236, 1749.2915369275463, 1781.3943259291902, 1783.0015191492805, 22893.88553057224, 23026.884865575732, 23132.0, 23188.76811231883, 23196.072157113696, 6442.420182183626, 6470.5794123382775, 6512.5441219115155, 6687.665616719145, 6711.295313992058, 15663.530094097196, 15762.0, 15774.76337854933, 15784.763228551581, 15793.21033948321, 7370.705171793175, 7501.512401693873, 7536.434767392468, 7543.170251272356, 7545.924540754632, 6887.621180835002, 7004.299570042955, 7047.647617619098, 7052.541584796972, 7062.646867656596, 4998.775055122528, 5096.745162741848, 5116.130257856174, 5156.74216289184, 5175.663581867167, 1544.1352842408062, 1548.41934274647, 1559.9766003509956, 1592.6177717347985, 1612.5243053299305, 785.6896525872248, 791.1297572670132, 810.1979040749616, 835.4486039214089, 835.4861760017545, 7030.437564994788, 7039.683214255371, 7164.92526121086, 7175.780117380058, 7185.173705023989, 937.6671281695036, 955.5795450001976, 972.6188811887138, 981.1316984468797, 988.1699372527112, 4546.681732278708, 4548.249538826057, 4625.560571745679, 4701.106789710013, 4719.1741445247635, 17356.91321543384, 17497.0, 17772.46682599524, 18792.906035469736, 19677.704834427495, 18985.810141898404, 19097.522561936064, 19284.168004039595, 19310.227590896207, 19310.613787724447, 10254.692359229235, 10774.461276936121, 10824.0, 10930.617428389969, 10932.999999999842, 7063.964680176567, 7495.775126746206, 7604.885926710993, 7957.0, 7985.760427187193, 16447.013179209287, 16602.086885220946, 16648.750268745735, 16873.409430669868, 17067.06131162798, 16349.673006539564, 17138.571535711708, 17169.141542923055, 17238.569035774206, 17339.0, 11304.208705390543, 11424.086073114297, 11435.542578296774, 11493.712657183638, 11507.194496385173, 12883.162594431333, 13048.217106973612, 13124.278164701032, 13128.803067954179, 13136.802947955597, 8849.646014159362, 9911.851322230028, 10573.154147668321, 10741.516631751747, 10750.892491074988, 9177.128172823572, 9881.604735810488, 9960.352577082467, 10063.295569310078, 10091.545880435546, 7853.882191767242, 7954.164812694584, 7956.124826269222, 7970.601469926596, 7994.360451163777, 6428.903566446414, 6474.1907261592305, 6612.471002319707, 6613.470922326299, 6770.458363330823, 6496.967515162394, 6745.325467453412, 6767.526273160832, 6886.139232595926, 6953.4089602384365], "value": 6204.924737559165, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.9087824370703, 499.21872269897597, 509.7807942584687, 520.8073012985194, 525.4719007397565, 6115.663638499881, 6180.578466952604, 6240.15757872687, 6247.562670613059, 6260.624362538251, 5765.827025189241, 5988.550858685594, 6022.187004754357, 6042.395760423956, 6111.541634377417, 4816.4461086974925, 4889.144399730051, 4910.337104490884, 4921.409430868301, 4957.5042495750495, 4848.6848354857, 4908.950910490903, 4931.5315045070665, 4932.5807306379, 4966.528179822911, 4674.275487299457, 4822.156122678535, 4846.127697014536, 4875.024995000997, 4881.414230292352, 4875.58557522611, 4879.804807807684, 4908.656394052417, 4910.877228069291, 4935.519344196727, 4369.4319738434115, 4426.579474949875, 4434.512203657595, 4442.3336499525, 4447.755373454459, 5923.318818335861, 6036.909446358308, 6045.486133678643, 6054.455099041107, 6058.969975104243, 5684.914726279109, 5698.862926402599, 5702.800401985911, 5711.143328500758, 5725.055365864628, 6501.804945851632, 6515.446187074104, 6530.2816690164045, 6537.515744017243, 6545.967270163666, 4791.592714619262, 4812.181929072031, 4821.63837712173, 4830.492798256168, 4861.416630004387, 6658.700358483879, 6788.947713104477, 6788.999999999951, 6798.796036118924, 6800.455963522906, 4895.143349913751, 4906.9263961040615, 4951.306817045616, 4974.1543937530705, 4987.202047672355, 4972.179590367621, 4993.27597498368, 5015.724135172601, 5018.172001619692, 5031.144705400091, 5585.888282234332, 5622.24099746529, 5623.297087864017, 5649.830505084854, 5660.943390566124, 6385.680715964183, 6443.613383197023, 6462.159919210472, 6550.574212676161, 6612.07430959666, 1674.472541149534, 1698.9235484403232, 1709.8033726121407, 1710.4697543761474, 1744.624905645267, 1161.4378660701343, 1203.2539825308365, 1205.662414523917, 1210.9515619375302, 1226.3316492511603, 5620.398186516823, 5632.0, 5650.66096034231, 5702.287214098238, 5737.3688894221605, 6393.296737358887, 6398.040293955851, 6472.967635161888, 6530.151080359523, 6676.165479315086, 5708.229389032511, 5711.771529138871, 5717.571182161355, 5735.11105778605, 5739.110437882135, 3426.725861931089, 3480.2343484433386, 3483.860645574199, 3502.4414135709367, 3526.717862570988, 6327.936720632735, 6415.839604009937, 6440.645764482904, 6449.290578036413, 6490.286068532458, 6768.037269191879, 6783.932160678429, 6823.112995310677, 6839.521233513607, 6886.380225779704, 6317.431431171217, 6328.240611126695, 6363.140975968277, 6429.58207716497, 6489.3510648934725, 6625.1511849905, 6665.2334981475815, 6680.962306496371, 6686.2281495402785, 6712.362325579063, 6562.77030303937, 6588.637624930675, 6601.801945941629, 6622.933770662232, 6624.56940298879, 6505.902411463737, 6512.0, 6513.8697226056165, 6537.8692426152165, 6562.3109573496, 4821.855344339676, 4845.6608037437045, 4888.58447031992, 4889.413270407574, 4927.384076990376, 6615.338466153346, 6645.534812563074, 6696.263411024686, 6717.664116794239, 6746.797596072126, 6410.743570257137, 6425.421712045938, 6476.967615161894, 6487.0, 6495.740170393131, 6350.841228969312, 6421.261554921057, 6424.967875160594, 6430.614163150226, 6435.839104022437, 6048.697565121638, 6049.274087109575, 6214.223222097238, 6303.6532990685955, 6325.96837015812, 6359.332270111571, 6359.8092057238355, 6461.741530338733, 6504.479641628753, 6516.543841931019, 6200.162977998093, 6202.751889924352, 6216.222972128484, 6248.812535623939, 6278.6860656966055], "value": 4982.665909137615, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [885.5527958381012, 891.1934699097317, 903.0337538833447, 907.0476000199789, 910.6084383715004, 18182.454526364214, 19066.523336916584, 19089.23643054278, 19117.904410477946, 21142.682859757115, 20118.195272189132, 20946.685799713017, 21147.682784758243, 21193.152273909065, 21279.04244309006, 986.6990567876795, 992.811365840488, 992.8113658404916, 993.061556828797, 1009.6516701737906, 977.3500622086304, 1011.8633984412121, 1053.441675911768, 1060.6605886116445, 1061.1881910338598, 1109.4120116338313, 1117.9664610061711, 1118.3010618363523, 1118.5637601335445, 1119.0152665654214, 1099.285464448111, 1104.0725790336076, 1104.6244276945797, 1104.8232282834708, 1105.5633024955139, 809.1908091908092, 811.4482152136545, 811.6793866422789, 813.6379311206515, 814.9022117345897, 18449.815501844943, 19340.80659193404, 19531.90234048835, 19702.901485492483, 19774.20903163872, 19551.413457596296, 19973.800261997338, 20203.19187232509, 20233.089510971895, 20305.695414568792, 14610.415583376653, 15079.698406031928, 15146.394144234217, 15271.160086195256, 15276.465323713617, 3444.4144495435835, 3482.1990942083207, 3489.59869614995, 3493.8777142800136, 3496.6328535503662, 115591.42204288924, 118313.0, 120086.39956800247, 120307.398463008, 120862.39568802188, 15740.212989350486, 16474.0, 16648.3340666372, 16648.583785405463, 16664.50006499807, 30564.54153187704, 30569.38861222763, 30633.540496892565, 30642.38715225683, 30654.54018189729, 7925.167857375008, 8259.95870020646, 8345.582720863933, 8356.331493480508, 8433.072362040171, 4074.572169922175, 4188.602082802129, 4191.224623444611, 4217.40956266123, 4224.408582798411, 5101.69389836611, 5105.2342148678, 5161.948380516147, 5253.842384728464, 5260.605454590921, 916.5554705967601, 934.3693007220235, 939.1688355805162, 941.6798288581915, 947.6304241345881, 551.1457241276087, 552.1248820619284, 575.6776205325032, 660.5376236634376, 667.2260178193272, 11869.28784272929, 12070.336131512851, 12094.516219351128, 12116.39418029095, 12202.962748166328, 651.5764752910646, 655.0370954696589, 671.7850287907822, 699.5452955578914, 702.6943279673359, 5287.947120528823, 5347.780671021483, 5427.150745858456, 5427.837164884981, 5521.944780552143, 95042.0495795047, 95763.52118239504, 99215.50392248137, 100140.49929750305, 100516.0, 84706.0, 88840.5557972206, 89606.10393896108, 90666.0, 93989.0, 77980.61009694915, 79440.0, 80630.0, 81747.59126204331, 81955.0, 10836.783264334827, 10886.782264354826, 11144.275622084862, 11258.21192516516, 11378.601748939078, 31526.684733152375, 36123.458148127276, 37300.6269937308, 37811.0, 38479.0, 62339.0, 68148.45921570885, 68384.0, 69868.0, 70017.29982700107, 18498.16758245909, 18677.813221867607, 19253.133608987348, 19553.60892782165, 19579.902100489406, 19442.611147777247, 20514.897425513373, 20564.69152962677, 20748.273810416562, 21029.263975760776, 16714.749278760584, 17574.20916058755, 18175.0, 18590.907045464686, 19238.903805480884, 19482.0, 19489.805101948798, 19516.121774519896, 19681.60636787285, 19743.210271588974, 17638.118094095502, 17693.55766105806, 17737.556561086076, 18104.547386315448, 18754.249830006647, 11185.217034807485, 12265.448054837376, 12312.445939932546, 12903.483860645469, 12923.030772691898, 13404.798928016282, 13789.448422063006, 14141.858581414053, 14304.213268269928, 14398.640033999234], "value": 8928.182846485377, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1551.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 108.0, 110.0, 107.0, 114.0, 119.0, 111.0, 102.0, 111.0, 112.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 120.0, 147.0, 180.0, 118.0, 145.0, 165.0, 121.0, 173.0, 117.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 162.0, 164.0, 164.0, 165.0, 168.0, 162.0, 163.0, 162.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 78.0, 167.0, 76.0, 75.0, 74.0, 86.0, 154.0, 76.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 86.0, 134.0, 78.0, 83.0, 85.0, 125.0, 79.0, 79.0, 129.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 122.0, 113.0, 114.0, 114.0, 113.0, 119.0, 113.0, 114.0], "value": 115.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 149.0, 149.0, 150.0, 149.0, 150.0, 153.0, 150.0, 155.0], "value": 150.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 93.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 59.0, 64.0, 56.0, 62.0, 58.0, 56.0, 56.0, 55.0], "value": 58.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 54.0, 55.0, 56.0, 54.0, 54.0, 54.0, 58.0], "value": 54.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 99.0, 83.0, 81.0, 83.0, 102.0, 88.0, 88.0, 82.0, 80.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 135.0, 126.0, 127.0, 135.0, 137.0, 126.0, 132.0, 128.0, 179.0], "value": 135.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 180.0, 179.0, 179.0, 178.0, 177.0, 186.0, 178.0, 177.0, 174.0], "value": 178.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 75.0, 69.0, 68.0, 68.0, 72.0, 69.0, 75.0, 68.0], "value": 71.0, "unit": "score"}]}]} 03:09:59 INFO - Return code: 0 03:09:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:09:59 INFO - # TBPL SUCCESS # 03:09:59 INFO - Running post-action listener: _package_coverage_data 03:09:59 INFO - Running post-action listener: _resource_record_post_action 03:09:59 INFO - [mozharness: 2017-07-27 10:09:59.999883Z] Finished run-tests step (success) 03:09:59 INFO - Running post-run listener: _resource_record_post_run 03:10:00 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 12288; Write bytes: 443486208; Read time: 92; Write time: 310360 03:10:00 INFO - TinderboxPrint: CPU usage
13.1% 03:10:00 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 92 03:10:00 INFO - TinderboxPrint: I/O write bytes / time
443,486,208 / 310,360 03:10:00 INFO - TinderboxPrint: CPU idle
8,274.4 (86.8%) 03:10:00 INFO - TinderboxPrint: CPU user
1,203.7 (12.6%) 03:10:00 INFO - TinderboxPrint: Swap in / out
0 / 0 03:10:00 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 119230464; Read time: 16; Write time: 66276 03:10:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:10:00 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 322641920; Read time: 36; Write time: 243996 03:10:00 INFO - Running post-run listener: _upload_blobber_files 03:10:00 INFO - Blob upload gear active. 03:10:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:10:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:10:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:10:01 INFO - (blobuploader) - INFO - Open directory for files ... 03:10:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:10:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:10:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:10:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:10:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:10:01 INFO - (blobuploader) - INFO - Done attempting. 03:10:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:10:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:10:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:10:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:10:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:10:02 INFO - (blobuploader) - INFO - Done attempting. 03:10:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:10:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:10:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:10:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:10:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:10:03 INFO - (blobuploader) - INFO - Done attempting. 03:10:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:10:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:10:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:10:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:10:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:10:06 INFO - (blobuploader) - INFO - Done attempting. 03:10:06 INFO - (blobuploader) - INFO - Iteration through files over. 03:10:06 INFO - Return code: 0 03:10:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:10:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:10:06 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29089a18d5e1275321ce97be90a5e2a44feb713084c666990040e5ffd37600e21a98ca72fc50bca66db12c615518ef8986a2fcd70674c39f2bd7fbd4e1875728", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26dba5382e74997ed5eca7ddce0c390e44a963797212b528f03410df48f0f1015f195d92425225672dd56cc3e0f0de7e144fcd921b7ca581bebfab161a297f46", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52d3106c657b1a74ff9fbcd6f7f0794df6100b08a103e91749f905d174dde761a551cdb5b1678593a17b1ec4a7188367e5f2830c52450d01ae1441e7df4af845", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/609822cb9e9541fdad91daaf7be29a364ccb8ec68b5a9763ab7c1be3e0de6c25ab97093f8d307fa77ff63647c9458c9cdd840671ad51837dbaa18aa2764ace43"} 03:10:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:10:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:10:06 INFO - Contents: 03:10:06 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29089a18d5e1275321ce97be90a5e2a44feb713084c666990040e5ffd37600e21a98ca72fc50bca66db12c615518ef8986a2fcd70674c39f2bd7fbd4e1875728", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26dba5382e74997ed5eca7ddce0c390e44a963797212b528f03410df48f0f1015f195d92425225672dd56cc3e0f0de7e144fcd921b7ca581bebfab161a297f46", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52d3106c657b1a74ff9fbcd6f7f0794df6100b08a103e91749f905d174dde761a551cdb5b1678593a17b1ec4a7188367e5f2830c52450d01ae1441e7df4af845", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/609822cb9e9541fdad91daaf7be29a364ccb8ec68b5a9763ab7c1be3e0de6c25ab97093f8d307fa77ff63647c9458c9cdd840671ad51837dbaa18aa2764ace43"} 03:10:06 INFO - Running post-run listener: copy_logs_to_upload_dir 03:10:06 INFO - Copying logs to upload dir... 03:10:06 INFO - mkdir: /builds/slave/test/build/upload/logs 03:10:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.170000 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-07-27 03:10:06.370055) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-27 03:10:06.381030) ========= 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=7de08804244eb36560b44971000001c3-1501144016.829062-1971585993 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29089a18d5e1275321ce97be90a5e2a44feb713084c666990040e5ffd37600e21a98ca72fc50bca66db12c615518ef8986a2fcd70674c39f2bd7fbd4e1875728", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26dba5382e74997ed5eca7ddce0c390e44a963797212b528f03410df48f0f1015f195d92425225672dd56cc3e0f0de7e144fcd921b7ca581bebfab161a297f46", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52d3106c657b1a74ff9fbcd6f7f0794df6100b08a103e91749f905d174dde761a551cdb5b1678593a17b1ec4a7188367e5f2830c52450d01ae1441e7df4af845", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/609822cb9e9541fdad91daaf7be29a364ccb8ec68b5a9763ab7c1be3e0de6c25ab97093f8d307fa77ff63647c9458c9cdd840671ad51837dbaa18aa2764ace43"} build_url:https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011360 build_url: 'https://queue.taskcluster.net/v1/task/Nxl8AhfPQiqKvJ1nVywyHg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29089a18d5e1275321ce97be90a5e2a44feb713084c666990040e5ffd37600e21a98ca72fc50bca66db12c615518ef8986a2fcd70674c39f2bd7fbd4e1875728", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26dba5382e74997ed5eca7ddce0c390e44a963797212b528f03410df48f0f1015f195d92425225672dd56cc3e0f0de7e144fcd921b7ca581bebfab161a297f46", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52d3106c657b1a74ff9fbcd6f7f0794df6100b08a103e91749f905d174dde761a551cdb5b1678593a17b1ec4a7188367e5f2830c52450d01ae1441e7df4af845", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/609822cb9e9541fdad91daaf7be29a364ccb8ec68b5a9763ab7c1be3e0de6c25ab97093f8d307fa77ff63647c9458c9cdd840671ad51837dbaa18aa2764ace43"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-27 03:10:06.406137) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 03:10:06.406441) ========= 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=7de08804244eb36560b44971000001c3-1501144016.829062-1971585993 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004395 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 03:10:06.490496) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-27 03:10:06.490811) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-27 03:10:06.491091) ========= ========= Total master_lag: 0.24 =========