builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-062 starttime: 1501519703.78 results: success (0) revision: 1be0c1da06076f85c69cd8a9d244e0164ec544d9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-31 09:48:23.777680) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-31 09:48:23.778266) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-31 09:48:23.778811) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-31 09:48:23.798193) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 09:48:23.798484) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-31 09:48:23-- 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% 37.5M=0s 2017-07-31 09:48:24 (37.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.071796 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 09:48:23.888512) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 09:48:23.888846) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014232 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 09:48:23.939965) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 09:48:23.940319) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1be0c1da06076f85c69cd8a9d244e0164ec544d9 --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 1be0c1da06076f85c69cd8a9d244e0164ec544d9 --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=a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-31 09:48:24,137 truncating revision to first 12 chars 2017-07-31 09:48:24,137 Setting DEBUG logging. 2017-07-31 09:48:24,137 attempt 1/10 2017-07-31 09:48:24,137 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1be0c1da0607?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-31 09:48:24,703 unpacking tar archive at: mozilla-central-1be0c1da0607/testing/mozharness/ program finished with exit code 0 elapsedTime=0.854169 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 09:48:24.807684) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-31 09:48:24.808091) ========= script_repo_revision: 1be0c1da06076f85c69cd8a9d244e0164ec544d9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-31 09:48:24.808550) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-31 09:48:24.808866) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-31 09:48:24.817838) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-07-31 09:48:24.818183) ========= /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=a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:48:25 INFO - MultiFileLogger online at 20170731 09:48:25 in /builds/slave/test 09:48:25 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 09:48:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:48:25 INFO - {'append_to_log': False, 09:48:25 INFO - 'base_work_dir': '/builds/slave/test', 09:48:25 INFO - 'blob_upload_branch': 'Firefox', 09:48:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:48:25 INFO - 'branch': 'Firefox', 09:48:25 INFO - 'buildbot_json_path': 'buildprops.json', 09:48:25 INFO - 'code_coverage': False, 09:48:25 INFO - 'config_files': ('talos/linux_config.py',), 09:48:25 INFO - 'default_actions': ('clobber', 09:48:25 INFO - 'read-buildbot-config', 09:48:25 INFO - 'download-and-extract', 09:48:25 INFO - 'populate-webroot', 09:48:25 INFO - 'create-virtualenv', 09:48:25 INFO - 'install', 09:48:25 INFO - 'setup-mitmproxy', 09:48:25 INFO - 'run-tests'), 09:48:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:48:25 INFO - 'disable_ccov_upload': False, 09:48:25 INFO - 'download_minidump_stackwalk': True, 09:48:25 INFO - 'download_symbols': 'ondemand', 09:48:25 INFO - 'enable_stylo': False, 09:48:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:48:25 INFO - 'tooltool.py': '/tools/tooltool.py', 09:48:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:48:25 INFO - '/tools/misc-python/virtualenv.py')}, 09:48:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:48:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:48:25 INFO - 'gecko_profile': False, 09:48:25 INFO - 'gecko_profile_interval': 0, 09:48:25 INFO - 'installer_path': 'installer.exe', 09:48:25 INFO - 'log_level': 'info', 09:48:25 INFO - 'log_name': 'talos', 09:48:25 INFO - 'log_to_console': True, 09:48:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:48:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:48:25 INFO - 'opt_config_files': (), 09:48:25 INFO - 'pip_index': False, 09:48:25 INFO - 'suite': 'dromaeojs-e10s', 09:48:25 INFO - 'system_bits': '32', 09:48:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:48:25 INFO - 'title': 'talos-linux64-ix-062', 09:48:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:48:25 INFO - 'use_talos_json': True, 09:48:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:48:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:48:25 INFO - 'work_dir': 'build'} 09:48:25 INFO - [mozharness: 2017-07-31 16:48:25.121341Z] Running clobber step. 09:48:25 INFO - Running pre-action listener: _resource_record_pre_action 09:48:25 INFO - Running main action method: clobber 09:48:25 INFO - rmtree: /builds/slave/test/build 09:48:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:48:26 INFO - Running post-action listener: _resource_record_post_action 09:48:26 INFO - [mozharness: 2017-07-31 16:48:26.670794Z] Finished clobber step (success) 09:48:26 INFO - [mozharness: 2017-07-31 16:48:26.670896Z] Running read-buildbot-config step. 09:48:26 INFO - Running pre-action listener: _resource_record_pre_action 09:48:26 INFO - Running main action method: read_buildbot_config 09:48:26 INFO - Using buildbot properties: 09:48:26 INFO - { 09:48:26 INFO - "project": "", 09:48:26 INFO - "product": "firefox", 09:48:26 INFO - "who": "dmitchell@mozilla.com", 09:48:26 INFO - "installer_path": "public/build/target.tar.bz2", 09:48:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 09:48:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 09:48:26 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 09:48:26 INFO - "stage_platform": "linux64-stylo", 09:48:26 INFO - "basedir": "/builds/slave/test", 09:48:26 INFO - "buildnumber": 247, 09:48:26 INFO - "platform": "ubuntu64_hw_stylo", 09:48:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:48:26 INFO - "slavebuilddir": "test", 09:48:26 INFO - "taskId": "fxF1OAGxRZCPxoqyEFEjmQ", 09:48:26 INFO - "branch": "mozilla-central", 09:48:26 INFO - "script_repo_revision": "production", 09:48:26 INFO - "revision": "1be0c1da06076f85c69cd8a9d244e0164ec544d9", 09:48:26 INFO - "slavename": "talos-linux64-ix-062", 09:48:26 INFO - "repo_path": "mozilla-central" 09:48:26 INFO - } 09:48:26 INFO - Finding installer, test and symbols from parent task. 09:48:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fxF1OAGxRZCPxoqyEFEjmQ'}, attempt #1 09:48:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fxF1OAGxRZCPxoqyEFEjmQ'}, attempt #1 09:48:27 INFO - Task dependencies: GJIFbv4yTaKDTYEmSbl0jw 09:48:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw'}, attempt #1 09:48:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.tar.bz2 09:48:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.test_packages.json 09:48:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.crashreporter-symbols.zip 09:48:28 INFO - Running post-action listener: _resource_record_post_action 09:48:28 INFO - [mozharness: 2017-07-31 16:48:28.176204Z] Finished read-buildbot-config step (success) 09:48:28 INFO - [mozharness: 2017-07-31 16:48:28.176427Z] Running download-and-extract step. 09:48:28 INFO - Running pre-action listener: _resource_record_pre_action 09:48:28 INFO - Running main action method: download_and_extract 09:48:28 INFO - mkdir: /builds/slave/test/build/tests 09:48:28 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')]} 09:48:28 INFO - trying https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.test_packages.json 09:48:28 INFO - Downloading https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:48:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:48:28 INFO - Downloaded 989 bytes. 09:48:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:48:28 INFO - Using the following test package requirements: 09:48:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:48:28 INFO - u'common': [u'target.common.tests.zip'], 09:48:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:48:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:48:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:48:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:48:28 INFO - u'mozbase': [u'target.common.tests.zip'], 09:48:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:48:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:48:28 INFO - u'web-platform': [u'target.common.tests.zip', 09:48:28 INFO - u'target.web-platform.tests.tar.gz'], 09:48:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:48:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:48:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.common.tests.zip 09:48:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:48:28 INFO - Fetch https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.common.tests.zip into memory 09:48:31 INFO - Content-Length response header: 72396228 09:48:31 INFO - Bytes received: 72396228 09:48:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:48:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.common.tests.zip 09:48:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:48:42 INFO - Fetch https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.common.tests.zip into memory 09:48:44 INFO - Content-Length response header: 72396228 09:48:44 INFO - Bytes received: 72396228 09:48:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.talos.tests.zip 09:48:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:48:57 INFO - Fetch https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.talos.tests.zip into memory 09:48:57 INFO - Content-Length response header: 14496686 09:48:57 INFO - Bytes received: 14496686 09:48:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:58 INFO - trying https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.tar.bz2 09:48:58 INFO - Downloading https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:48:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:49:00 INFO - Downloaded 62275901 bytes. 09:49:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.tar.bz2 09:49:00 INFO - mkdir: /builds/slave/test/properties 09:49:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:49:00 INFO - Writing to file /builds/slave/test/properties/build_url 09:49:00 INFO - Contents: 09:49:00 INFO - build_url:https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.tar.bz2 09:49:00 INFO - Running post-action listener: _resource_record_post_action 09:49:00 INFO - Running post-action listener: set_extra_try_arguments 09:49:00 INFO - [mozharness: 2017-07-31 16:49:00.543266Z] Finished download-and-extract step (success) 09:49:00 INFO - [mozharness: 2017-07-31 16:49:00.543474Z] Running populate-webroot step. 09:49:00 INFO - Running pre-action listener: _resource_record_pre_action 09:49:00 INFO - Running main action method: populate_webroot 09:49:00 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:49:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:49:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:49:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:49:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:49:00 INFO - 'tests': ['damp', 'tps']}, 09:49:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:49:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:49:00 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:49:00 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:49:00 INFO - 'other-e10s': {'tests': ['a11yr', 09:49:00 INFO - 'ts_paint', 09:49:00 INFO - 'tpaint', 09:49:00 INFO - 'sessionrestore', 09:49:00 INFO - 'sessionrestore_many_windows', 09:49:00 INFO - 'sessionrestore_no_auto_restore', 09:49:00 INFO - 'tabpaint']}, 09:49:00 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:49:00 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 09:49:00 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:49:00 INFO - 'talos_options': ['--mitmproxy', 09:49:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:49:00 INFO - '--firstNonBlankPaint'], 09:49:00 INFO - 'tests': ['quantum_pageload_google', 09:49:00 INFO - 'quantum_pageload_youtube', 09:49:00 INFO - 'quantum_pageload_amazon', 09:49:00 INFO - 'quantum_pageload_facebook']}, 09:49:00 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:49:00 INFO - 'talos_options': ['--stylo', 09:49:00 INFO - '--mitmproxy', 09:49:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:49:00 INFO - '--firstNonBlankPaint'], 09:49:00 INFO - 'tests': ['quantum_pageload_google', 09:49:00 INFO - 'quantum_pageload_youtube', 09:49:00 INFO - 'quantum_pageload_amazon', 09:49:00 INFO - 'quantum_pageload_facebook']}, 09:49:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:49:00 INFO - 'tsvgr_opacity', 09:49:00 INFO - 'tart', 09:49:00 INFO - 'tscrollx', 09:49:00 INFO - 'cart', 09:49:00 INFO - 'tsvg_static']}, 09:49:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:49:00 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:49:00 INFO - 'talos_options': ['--stylo', 09:49:00 INFO - '--stylo-threads=1', 09:49:00 INFO - '--mitmproxy', 09:49:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:49:00 INFO - '--firstNonBlankPaint'], 09:49:00 INFO - 'tests': ['tp6_google', 09:49:00 INFO - 'tp6_youtube', 09:49:00 INFO - 'tp6_amazon', 09:49:00 INFO - 'tp6_facebook']}, 09:49:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:49:00 INFO - 'talos_options': ['--xperf_path', 09:49:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:49:00 INFO - 'tests': ['tp5n']}}} 09:49:00 INFO - Running post-action listener: _resource_record_post_action 09:49:00 INFO - [mozharness: 2017-07-31 16:49:00.557727Z] Finished populate-webroot step (success) 09:49:00 INFO - [mozharness: 2017-07-31 16:49:00.557818Z] Running create-virtualenv step. 09:49:00 INFO - Running pre-action listener: _resource_record_pre_action 09:49:00 INFO - Running main action method: create_virtualenv 09:49:00 INFO - Creating virtualenv /builds/slave/test/build/venv 09:49:00 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 09:49:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:49:00 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:49:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:49:00 INFO - Using real prefix '/usr' 09:49:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:49:01 INFO - Installing distribute.............................................................................................................................................................................................done. 09:49:03 INFO - Installing pip.................done. 09:49:03 INFO - Return code: 0 09:49:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:49:03 INFO - Reading from file tmpfile_stdout 09:49:03 INFO - Output received: 09:49:03 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) 09:49:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:49: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')]} 09:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49: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')]} 09:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x256e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ddd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28daeb0>, '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 0x28dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28dc240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', '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 09:49: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', 'pip>=1.5'] in /builds/slave/test/build 09:49: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 pip>=1.5 09:49:03 INFO - Using env: {'DISPLAY': ':0', 09:49:03 INFO - 'HOME': '/home/cltbld', 09:49:03 INFO - 'LANG': 'en_US.UTF-8', 09:49:03 INFO - 'LANGUAGE': 'en_US:en', 09:49:03 INFO - 'LOGNAME': 'cltbld', 09:49:03 INFO - 'MAIL': '/var/mail/cltbld', 09:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:03 INFO - 'MOZ_NO_REMOTE': '1', 09:49:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:03 INFO - 'NO_EM_RESTART': '1', 09:49:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:03 INFO - 'PWD': '/builds/slave/test', 09:49:03 INFO - 'SHELL': '/bin/bash', 09:49:03 INFO - 'SHLVL': '1', 09:49:03 INFO - 'TERM': 'linux', 09:49:03 INFO - 'TMOUT': '86400', 09:49:03 INFO - 'USER': 'cltbld', 09:49:03 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', 09:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:03 INFO - '_': '/tools/buildbot/bin/python'} 09:49:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:03 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 09:49:03 INFO - Cleaning up... 09:49:03 INFO - Return code: 0 09:49:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:49: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')]} 09:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49: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')]} 09:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49: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', '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 0x256e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ddd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28daeb0>, '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 0x28dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28dc240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', '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 09:49: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:49: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 psutil>=3.1.1 09:49:03 INFO - Using env: {'DISPLAY': ':0', 09:49:03 INFO - 'HOME': '/home/cltbld', 09:49:03 INFO - 'LANG': 'en_US.UTF-8', 09:49:03 INFO - 'LANGUAGE': 'en_US:en', 09:49:03 INFO - 'LOGNAME': 'cltbld', 09:49:03 INFO - 'MAIL': '/var/mail/cltbld', 09:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:03 INFO - 'MOZ_NO_REMOTE': '1', 09:49:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:03 INFO - 'NO_EM_RESTART': '1', 09:49:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:03 INFO - 'PWD': '/builds/slave/test', 09:49:03 INFO - 'SHELL': '/bin/bash', 09:49:03 INFO - 'SHLVL': '1', 09:49:03 INFO - 'TERM': 'linux', 09:49:03 INFO - 'TMOUT': '86400', 09:49:03 INFO - 'USER': 'cltbld', 09:49:03 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', 09:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:03 INFO - '_': '/tools/buildbot/bin/python'} 09:49:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:03 INFO - Downloading/unpacking psutil>=3.1.1 09:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:49:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:49:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:49:05 INFO - Installing collected packages: psutil 09:49:05 INFO - Running setup.py install for psutil 09:49:05 INFO - building 'psutil._psutil_linux' extension 09:49:05 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 09:49:05 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 09:49:05 INFO - building 'psutil._psutil_posix' extension 09:49:05 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 09:49:05 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 09:49:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:49:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:49:05 INFO - Successfully installed psutil 09:49:05 INFO - Cleaning up... 09:49:05 INFO - Return code: 0 09:49:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:05 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 0x256e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ddd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28daeb0>, '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 0x28dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28dc240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', '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 09:49:05 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 09:49:05 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 09:49:05 INFO - Using env: {'DISPLAY': ':0', 09:49:05 INFO - 'HOME': '/home/cltbld', 09:49:05 INFO - 'LANG': 'en_US.UTF-8', 09:49:05 INFO - 'LANGUAGE': 'en_US:en', 09:49:05 INFO - 'LOGNAME': 'cltbld', 09:49:05 INFO - 'MAIL': '/var/mail/cltbld', 09:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:05 INFO - 'MOZ_NO_REMOTE': '1', 09:49:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:05 INFO - 'NO_EM_RESTART': '1', 09:49:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:05 INFO - 'PWD': '/builds/slave/test', 09:49:05 INFO - 'SHELL': '/bin/bash', 09:49:05 INFO - 'SHLVL': '1', 09:49:05 INFO - 'TERM': 'linux', 09:49:05 INFO - 'TMOUT': '86400', 09:49:05 INFO - 'USER': 'cltbld', 09:49:05 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', 09:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:05 INFO - '_': '/tools/buildbot/bin/python'} 09:49:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:49:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:49:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:49:06 INFO - Installing collected packages: mozsystemmonitor 09:49:06 INFO - Running setup.py install for mozsystemmonitor 09:49:07 INFO - Successfully installed mozsystemmonitor 09:49:07 INFO - Cleaning up... 09:49:07 INFO - Return code: 0 09:49:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x256e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ddd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28daeb0>, '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 0x28dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28dc240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', '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 09:49:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:49:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:49:07 INFO - Using env: {'DISPLAY': ':0', 09:49:07 INFO - 'HOME': '/home/cltbld', 09:49:07 INFO - 'LANG': 'en_US.UTF-8', 09:49:07 INFO - 'LANGUAGE': 'en_US:en', 09:49:07 INFO - 'LOGNAME': 'cltbld', 09:49:07 INFO - 'MAIL': '/var/mail/cltbld', 09:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:07 INFO - 'MOZ_NO_REMOTE': '1', 09:49:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:07 INFO - 'NO_EM_RESTART': '1', 09:49:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:07 INFO - 'PWD': '/builds/slave/test', 09:49:07 INFO - 'SHELL': '/bin/bash', 09:49:07 INFO - 'SHLVL': '1', 09:49:07 INFO - 'TERM': 'linux', 09:49:07 INFO - 'TMOUT': '86400', 09:49:07 INFO - 'USER': 'cltbld', 09:49:07 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', 09:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:07 INFO - '_': '/tools/buildbot/bin/python'} 09:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:07 INFO - Downloading/unpacking jsonschema==2.5.1 09:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:49:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:08 INFO - Downloading functools32-3.2.3-2.tar.gz 09:49:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:49:08 INFO - warning: no files found matching '*.txt' 09:49:08 INFO - no previously-included directories found matching 'build' 09:49:08 INFO - no previously-included directories found matching 'dist' 09:49:08 INFO - no previously-included directories found matching '.git*' 09:49:08 INFO - Installing collected packages: jsonschema, functools32 09:49:08 INFO - Running setup.py install for functools32 09:49:08 INFO - warning: no files found matching '*.txt' 09:49:08 INFO - no previously-included directories found matching 'build' 09:49:08 INFO - no previously-included directories found matching 'dist' 09:49:08 INFO - no previously-included directories found matching '.git*' 09:49:08 INFO - Successfully installed jsonschema functools32 09:49:08 INFO - Cleaning up... 09:49:08 INFO - Return code: 0 09:49:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:08 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 0x256e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ddd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28daeb0>, '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 0x28dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28dc240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', '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 09:49:08 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 09:49:08 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 09:49:08 INFO - Using env: {'DISPLAY': ':0', 09:49:08 INFO - 'HOME': '/home/cltbld', 09:49:08 INFO - 'LANG': 'en_US.UTF-8', 09:49:08 INFO - 'LANGUAGE': 'en_US:en', 09:49:08 INFO - 'LOGNAME': 'cltbld', 09:49:08 INFO - 'MAIL': '/var/mail/cltbld', 09:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:08 INFO - 'MOZ_NO_REMOTE': '1', 09:49:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:08 INFO - 'NO_EM_RESTART': '1', 09:49:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:08 INFO - 'PWD': '/builds/slave/test', 09:49:08 INFO - 'SHELL': '/bin/bash', 09:49:08 INFO - 'SHLVL': '1', 09:49:08 INFO - 'TERM': 'linux', 09:49:08 INFO - 'TMOUT': '86400', 09:49:08 INFO - 'USER': 'cltbld', 09:49:08 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', 09:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:08 INFO - '_': '/tools/buildbot/bin/python'} 09:49:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:49:08 INFO - Cleaning up... 09:49:08 INFO - Return code: 0 09:49:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:08 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 0x256e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ddd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28daeb0>, '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 0x28dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28dc240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', '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 09:49:08 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 09:49:08 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 09:49:08 INFO - Using env: {'DISPLAY': ':0', 09:49:08 INFO - 'HOME': '/home/cltbld', 09:49:08 INFO - 'LANG': 'en_US.UTF-8', 09:49:08 INFO - 'LANGUAGE': 'en_US:en', 09:49:08 INFO - 'LOGNAME': 'cltbld', 09:49:08 INFO - 'MAIL': '/var/mail/cltbld', 09:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:08 INFO - 'MOZ_NO_REMOTE': '1', 09:49:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:08 INFO - 'NO_EM_RESTART': '1', 09:49:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:08 INFO - 'PWD': '/builds/slave/test', 09:49:08 INFO - 'SHELL': '/bin/bash', 09:49:08 INFO - 'SHLVL': '1', 09:49:08 INFO - 'TERM': 'linux', 09:49:08 INFO - 'TMOUT': '86400', 09:49:08 INFO - 'USER': 'cltbld', 09:49:08 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', 09:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:08 INFO - '_': '/tools/buildbot/bin/python'} 09:49:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:09 INFO - Downloading/unpacking blobuploader==1.2.4 09:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:10 INFO - Downloading blobuploader-1.2.4.tar.gz 09:49:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:49:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:49:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:10 INFO - Downloading docopt-0.6.1.tar.gz 09:49:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:49:10 INFO - Installing collected packages: blobuploader, requests, docopt 09:49:10 INFO - Running setup.py install for blobuploader 09:49:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:49:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:49:10 INFO - Running setup.py install for requests 09:49:11 INFO - Running setup.py install for docopt 09:49:11 INFO - Successfully installed blobuploader requests docopt 09:49:11 INFO - Cleaning up... 09:49:11 INFO - Return code: 0 09:49:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:11 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')]} 09:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:11 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')]} 09:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:11 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 0x256e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ddd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28daeb0>, '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 0x28dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28dc240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', '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 09:49:11 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 09:49:11 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 09:49:11 INFO - Using env: {'DISPLAY': ':0', 09:49:11 INFO - 'HOME': '/home/cltbld', 09:49:11 INFO - 'LANG': 'en_US.UTF-8', 09:49:11 INFO - 'LANGUAGE': 'en_US:en', 09:49:11 INFO - 'LOGNAME': 'cltbld', 09:49:11 INFO - 'MAIL': '/var/mail/cltbld', 09:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:11 INFO - 'MOZ_NO_REMOTE': '1', 09:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:11 INFO - 'NO_EM_RESTART': '1', 09:49:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:11 INFO - 'PWD': '/builds/slave/test', 09:49:11 INFO - 'SHELL': '/bin/bash', 09:49:11 INFO - 'SHLVL': '1', 09:49:11 INFO - 'TERM': 'linux', 09:49:11 INFO - 'TMOUT': '86400', 09:49:11 INFO - 'USER': 'cltbld', 09:49:11 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', 09:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:11 INFO - '_': '/tools/buildbot/bin/python'} 09:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:49:11 INFO - Running setup.py (path:/tmp/pip-_HEKPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:49:11 INFO - Running setup.py (path:/tmp/pip-4QNCC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:49:11 INFO - Running setup.py (path:/tmp/pip-nv1nzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:49:11 INFO - Running setup.py (path:/tmp/pip-sZBNRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:49:11 INFO - Running setup.py (path:/tmp/pip-WELYub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:49:12 INFO - Running setup.py (path:/tmp/pip-9d5f3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:49:12 INFO - Running setup.py (path:/tmp/pip-uEwxJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:49:12 INFO - Running setup.py (path:/tmp/pip-n4Eb5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:49:12 INFO - Running setup.py (path:/tmp/pip-dNjJh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:49:12 INFO - Running setup.py (path:/tmp/pip-wYuvgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:49:12 INFO - Running setup.py (path:/tmp/pip-5jdTua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:49:12 INFO - Running setup.py (path:/tmp/pip-kQc3tQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:49:12 INFO - Running setup.py (path:/tmp/pip-7Hkmcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:49:12 INFO - Running setup.py (path:/tmp/pip-ttbhkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:12 INFO - Running setup.py (path:/tmp/pip-X76XJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:49:12 INFO - Running setup.py (path:/tmp/pip-NKWYeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:49:12 INFO - Running setup.py (path:/tmp/pip-YZvtNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:49:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:49:12 INFO - Running setup.py install for manifestparser 09:49:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:49:13 INFO - Running setup.py install for mozcrash 09:49:13 INFO - Running setup.py install for mozdebug 09:49:13 INFO - Running setup.py install for mozdevice 09:49:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:49:13 INFO - Running setup.py install for mozfile 09:49:13 INFO - Running setup.py install for mozhttpd 09:49:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:49:13 INFO - Running setup.py install for mozinfo 09:49:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:49:13 INFO - Running setup.py install for mozInstall 09:49:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:49:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:49:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:49:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:49:13 INFO - Running setup.py install for mozleak 09:49:13 INFO - Running setup.py install for mozlog 09:49:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:49:13 INFO - Running setup.py install for moznetwork 09:49:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:49:14 INFO - Running setup.py install for mozprocess 09:49:14 INFO - Running setup.py install for mozprofile 09:49:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:49:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:49:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:49:14 INFO - Running setup.py install for mozrunner 09:49:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:49:14 INFO - Running setup.py install for mozscreenshot 09:49:14 INFO - Running setup.py install for moztest 09:49:14 INFO - Running setup.py install for mozversion 09:49:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:49:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:49:14 INFO - Cleaning up... 09:49:14 INFO - Return code: 0 09:49:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:14 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 0x256e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ddd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28daeb0>, '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 0x28dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28dc240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', '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 09:49:14 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 09:49:14 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 09:49:14 INFO - Using env: {'DISPLAY': ':0', 09:49:14 INFO - 'HOME': '/home/cltbld', 09:49:14 INFO - 'LANG': 'en_US.UTF-8', 09:49:14 INFO - 'LANGUAGE': 'en_US:en', 09:49:14 INFO - 'LOGNAME': 'cltbld', 09:49:14 INFO - 'MAIL': '/var/mail/cltbld', 09:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:14 INFO - 'MOZ_NO_REMOTE': '1', 09:49:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:14 INFO - 'NO_EM_RESTART': '1', 09:49:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:14 INFO - 'PWD': '/builds/slave/test', 09:49:14 INFO - 'SHELL': '/bin/bash', 09:49:14 INFO - 'SHLVL': '1', 09:49:14 INFO - 'TERM': 'linux', 09:49:14 INFO - 'TMOUT': '86400', 09:49:14 INFO - 'USER': 'cltbld', 09:49:14 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', 09:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:14 INFO - '_': '/tools/buildbot/bin/python'} 09:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:49:14 INFO - Running setup.py (path:/tmp/pip-x3fAOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:49:14 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)) 09:49:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:49:14 INFO - Running setup.py (path:/tmp/pip-kS5M4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:49:15 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)) 09:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:49:15 INFO - Running setup.py (path:/tmp/pip-DXzqVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:49:15 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)) 09:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:49:15 INFO - Running setup.py (path:/tmp/pip-Z2jW6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:49:15 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)) 09:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:49:15 INFO - Running setup.py (path:/tmp/pip-0A8qox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:49:15 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)) 09:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:49:15 INFO - Running setup.py (path:/tmp/pip-8pTMOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:49:15 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)) 09:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:49:15 INFO - Running setup.py (path:/tmp/pip-ckN3pE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:49:15 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)) 09:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:49:15 INFO - Running setup.py (path:/tmp/pip-dCtcD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:49:15 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)) 09:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:49:15 INFO - Running setup.py (path:/tmp/pip-0gdUVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:49:15 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)) 09:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:49:15 INFO - Running setup.py (path:/tmp/pip-ytymUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:49:15 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)) 09:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:49:15 INFO - Running setup.py (path:/tmp/pip-cd_MYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:49:15 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)) 09:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:49:15 INFO - Running setup.py (path:/tmp/pip-bIOnEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:49:15 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)) 09:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:49:15 INFO - Running setup.py (path:/tmp/pip-oxkYaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:49:15 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)) 09:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:49:15 INFO - Running setup.py (path:/tmp/pip-Y95cgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:49:15 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)) 09:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:15 INFO - Running setup.py (path:/tmp/pip-Fj39D2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:16 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)) 09:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:49:16 INFO - Running setup.py (path:/tmp/pip-KEZJcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:49:16 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)) 09:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:49:16 INFO - Running setup.py (path:/tmp/pip-iYOudv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:49:16 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)) 09:49:16 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)) 09:49:16 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)) 09:49:16 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)) 09:49:16 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)) 09:49:16 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)) 09:49:16 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)) 09:49:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:49:16 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 09:49:16 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 09:49:16 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 09:49:16 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 09:49:17 INFO - Downloading blessings-1.6.tar.gz 09:49:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:49:17 INFO - Installing collected packages: blessings 09:49:17 INFO - Running setup.py install for blessings 09:49:17 INFO - Successfully installed blessings 09:49:17 INFO - Cleaning up... 09:49:17 INFO - Return code: 0 09:49:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:49:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:17 INFO - Reading from file tmpfile_stdout 09:49:17 INFO - Current package versions: 09:49:17 INFO - argparse == 1.2.1 09:49:17 INFO - blessings == 1.6 09:49:17 INFO - blobuploader == 1.2.4 09:49:17 INFO - docopt == 0.6.1 09:49:17 INFO - functools32 == 3.2.3-2 09:49:17 INFO - jsonschema == 2.5.1 09:49:17 INFO - manifestparser == 1.1 09:49:17 INFO - mozInstall == 1.13 09:49:17 INFO - mozcrash == 1.0 09:49:17 INFO - mozdebug == 0.1 09:49:17 INFO - mozdevice == 0.51 09:49:17 INFO - mozfile == 1.2 09:49:17 INFO - mozhttpd == 0.7 09:49:17 INFO - mozinfo == 0.10 09:49:17 INFO - mozleak == 0.1 09:49:17 INFO - mozlog == 3.5 09:49:17 INFO - moznetwork == 0.27 09:49:17 INFO - mozprocess == 0.25 09:49:17 INFO - mozprofile == 0.28 09:49:17 INFO - mozrunner == 6.13 09:49:17 INFO - mozscreenshot == 0.1 09:49:17 INFO - mozsystemmonitor == 0.3 09:49:17 INFO - moztest == 0.8 09:49:17 INFO - mozversion == 1.4 09:49:17 INFO - psutil == 3.1.1 09:49:17 INFO - requests == 1.2.3 09:49:17 INFO - wsgiref == 0.1.2 09:49:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:17 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')]} 09:49:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:17 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')]} 09:49:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:17 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 0x256e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ddd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28daeb0>, '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 0x28dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28dc240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', '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 09:49:17 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 09:49:17 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 09:49:17 INFO - Using env: {'DISPLAY': ':0', 09:49:17 INFO - 'HOME': '/home/cltbld', 09:49:17 INFO - 'LANG': 'en_US.UTF-8', 09:49:17 INFO - 'LANGUAGE': 'en_US:en', 09:49:17 INFO - 'LOGNAME': 'cltbld', 09:49:17 INFO - 'MAIL': '/var/mail/cltbld', 09:49:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:17 INFO - 'MOZ_NO_REMOTE': '1', 09:49:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:17 INFO - 'NO_EM_RESTART': '1', 09:49:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:17 INFO - 'PWD': '/builds/slave/test', 09:49:17 INFO - 'SHELL': '/bin/bash', 09:49:17 INFO - 'SHLVL': '1', 09:49:17 INFO - 'TERM': 'linux', 09:49:17 INFO - 'TMOUT': '86400', 09:49:17 INFO - 'USER': 'cltbld', 09:49:17 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', 09:49:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:17 INFO - '_': '/tools/buildbot/bin/python'} 09:49:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:17 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)) 09:49:17 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)) 09:49:17 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)) 09:49:17 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)) 09:49:17 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)) 09:49:17 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)) 09:49:17 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)) 09:49:17 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)) 09:49:17 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)) 09:49:17 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:49:17 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 09:49:17 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 09:49:17 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 09:49:17 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 09:49:18 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:49:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:49:19 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 09:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:49:19 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)) 09:49:19 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)) 09:49:19 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)) 09:49:19 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)) 09:49:19 INFO - Installing collected packages: marionette-driver, simplejson 09:49:19 INFO - Running setup.py install for marionette-driver 09:49:19 INFO - Running setup.py install for simplejson 09:49:19 INFO - building 'simplejson._speedups' extension 09:49:19 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 09:49:20 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 09:49:20 INFO - Successfully installed marionette-driver simplejson 09:49:20 INFO - Cleaning up... 09:49:20 INFO - Return code: 0 09:49:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:49:20 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')]} 09:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:20 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')]} 09:49:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:20 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 0x256e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x273aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ddd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28daeb0>, '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 0x28dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28dc240>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', '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 09:49:20 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 09:49:20 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 09:49:20 INFO - Using env: {'DISPLAY': ':0', 09:49:20 INFO - 'HOME': '/home/cltbld', 09:49:20 INFO - 'LANG': 'en_US.UTF-8', 09:49:20 INFO - 'LANGUAGE': 'en_US:en', 09:49:20 INFO - 'LOGNAME': 'cltbld', 09:49:20 INFO - 'MAIL': '/var/mail/cltbld', 09:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:20 INFO - 'MOZ_NO_REMOTE': '1', 09:49:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:20 INFO - 'NO_EM_RESTART': '1', 09:49:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:20 INFO - 'PWD': '/builds/slave/test', 09:49:20 INFO - 'SHELL': '/bin/bash', 09:49:20 INFO - 'SHLVL': '1', 09:49:20 INFO - 'TERM': 'linux', 09:49:20 INFO - 'TMOUT': '86400', 09:49:20 INFO - 'USER': 'cltbld', 09:49:20 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', 09:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:20 INFO - '_': '/tools/buildbot/bin/python'} 09:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:49:20 INFO - Cleaning up... 09:49:20 INFO - Return code: 0 09:49:20 INFO - Running post-action listener: _resource_record_post_action 09:49:20 INFO - Running post-action listener: _start_resource_monitoring 09:49:20 INFO - Starting resource monitoring. 09:49:20 INFO - [mozharness: 2017-07-31 16:49:20.751648Z] Finished create-virtualenv step (success) 09:49:20 INFO - [mozharness: 2017-07-31 16:49:20.752112Z] Running install step. 09:49:20 INFO - Running pre-action listener: _resource_record_pre_action 09:49:20 INFO - Running main action method: install 09:49:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:20 INFO - Reading from file tmpfile_stdout 09:49:20 INFO - Detecting whether we're running mozinstall >=1.0... 09:49:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:49:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:49:21 INFO - Reading from file tmpfile_stdout 09:49:21 INFO - Output received: 09:49:21 INFO - Usage: mozinstall [options] installer 09:49:21 INFO - Options: 09:49:21 INFO - -h, --help show this help message and exit 09:49:21 INFO - -d DEST, --destination=DEST 09:49:21 INFO - Directory to install application into. [default: 09:49:21 INFO - "/builds/slave/test"] 09:49:21 INFO - --app=APP Application being installed. [default: firefox] 09:49:21 INFO - mkdir: /builds/slave/test/build/application 09:49:21 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'] 09:49:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:49:39 INFO - Reading from file tmpfile_stdout 09:49:39 INFO - Output received: 09:49:39 INFO - /builds/slave/test/build/application/firefox/firefox 09:49:39 INFO - Running post-action listener: _resource_record_post_action 09:49:39 INFO - [mozharness: 2017-07-31 16:49:39.344832Z] Finished install step (success) 09:49:39 INFO - [mozharness: 2017-07-31 16:49:39.344992Z] Running setup-mitmproxy step. 09:49:39 INFO - Running pre-action listener: _resource_record_pre_action 09:49:39 INFO - Running main action method: setup_mitmproxy 09:49:39 INFO - Skipping: mitmproxy is not required 09:49:39 INFO - Running post-action listener: _resource_record_post_action 09:49:39 INFO - [mozharness: 2017-07-31 16:49:39.346130Z] Finished setup-mitmproxy step (success) 09:49:39 INFO - [mozharness: 2017-07-31 16:49:39.346353Z] Running run-tests step. 09:49:39 INFO - Running pre-action listener: _resource_record_pre_action 09:49:39 INFO - Running pre-action listener: _set_gcov_prefix 09:49:39 INFO - Running main action method: run_tests 09:49:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:49:39 INFO - Python 2.7.3 09:49:39 INFO - Return code: 0 09:49:39 INFO - grabbing minidump binary from tooltool 09:49:39 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')]} 09:49:39 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 0x28daeb0>, '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 0x28dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28dc240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:49:39 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 09:49:39 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 09:49:39 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 09:49:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:49:39 INFO - Return code: 0 09:49:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:49:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:49:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:49:39 INFO - ENV: RUST_BACKTRACE is now 1 09:49:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:49:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:49:39 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/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 09:49:39 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/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 09:49:39 INFO - Using env: {'DISPLAY': ':0', 09:49:39 INFO - 'HOME': '/home/cltbld', 09:49:39 INFO - 'LANG': 'en_US.UTF-8', 09:49:39 INFO - 'LANGUAGE': 'en_US:en', 09:49:39 INFO - 'LOGNAME': 'cltbld', 09:49:39 INFO - 'MAIL': '/var/mail/cltbld', 09:49:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:49:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:49:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:39 INFO - 'MOZ_NO_REMOTE': '1', 09:49:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:49:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:49:39 INFO - 'NO_EM_RESTART': '1', 09:49:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:49:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:39 INFO - 'PWD': '/builds/slave/test', 09:49:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:49:39 INFO - 'RUST_BACKTRACE': '1', 09:49:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:49:39 INFO - 'SHELL': '/bin/bash', 09:49:39 INFO - 'SHLVL': '1', 09:49:39 INFO - 'STYLO_FORCE_ENABLED': '1', 09:49:39 INFO - 'TERM': 'linux', 09:49:39 INFO - 'TMOUT': '86400', 09:49:39 INFO - 'USER': 'cltbld', 09:49:39 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545', 09:49:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:39 INFO - '_': '/tools/buildbot/bin/python'} 09:49:39 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/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 09:49:39 INFO - mozversion application_buildid: 20170731160448 09:49:39 INFO - mozversion application_changeset: 1be0c1da06076f85c69cd8a9d244e0164ec544d9 09:49:39 INFO - mozversion application_display_name: Nightly 09:49:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:49:39 INFO - mozversion application_name: Firefox 09:49:39 INFO - mozversion application_remotingname: firefox 09:49:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:49:39 INFO - mozversion application_vendor: Mozilla 09:49:39 INFO - mozversion application_version: 56.0a1 09:49:39 INFO - mozversion platform_buildid: 20170731160448 09:49:39 INFO - mozversion platform_changeset: 1be0c1da06076f85c69cd8a9d244e0164ec544d9 09:49:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:49:39 INFO - mozversion platform_version: 56.0a1 09:49:39 INFO - using testdate: 1501519779 09:49:39 INFO - actual date: 1501519779 09:49:39 INFO - starting webserver on 'localhost:51133' 09:49:39 INFO - SUITE-START | Running 2 tests 09:49:39 INFO - TEST-START | dromaeo_css 09:49:39 INFO - Initialising browser for dromaeo_css test... 09:49:39 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51133/getInfo.html -profile /tmp/tmpxTt1Cn/profile 09:49:39 INFO - TEST-INFO | started process 14856 (/builds/slave/test/build/application/firefox/firefox http://localhost:51133/getInfo.html) 09:49:45 INFO - TEST-INFO | 14856: exit 0 09:49:45 INFO - Browser initialized. 09:49:45 INFO - Running cycle 1/1 for dromaeo_css test... 09:49:45 INFO - TEST-INFO | started process 15083 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxTt1Cn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:46 INFO - PID 15083 | 09:49:46 INFO - PID 15083 | (/builds/slave/test/build/application/firefox/firefox:15137): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:49:46 INFO - PID 15083 | 09:49:57 INFO - PID 15083 | RSS: Main: 173625344 09:49:57 INFO - PID 15083 | 09:52:56 INFO - PID 15083 | Cycle 1(1): loaded http://localhost:51133/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51133/tests/dromaeo/cssquery-ext.html) 09:52:58 INFO - PID 15083 | RSS: Main: 176357376 09:52:58 INFO - PID 15083 | 09:56:00 INFO - PID 15083 | Cycle 1(1): loaded http://localhost:51133/tests/dromaeo/cssquery-ext.html (next: http://localhost:51133/tests/dromaeo/cssquery-jquery.html) 09:56:00 INFO - PID 15083 | RSS: Main: 173473792 09:56:00 INFO - PID 15083 | 09:59:02 INFO - PID 15083 | Cycle 1(1): loaded http://localhost:51133/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51133/tests/dromaeo/cssquery-mootools.html) 09:59:03 INFO - PID 15083 | RSS: Main: 175366144 09:59:03 INFO - PID 15083 | 10:02:05 INFO - PID 15083 | Cycle 1(1): loaded http://localhost:51133/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51133/tests/dromaeo/cssquery-prototype.html) 10:02:05 INFO - PID 15083 | RSS: Main: 172052480 10:02:05 INFO - PID 15083 | 10:05:09 INFO - PID 15083 | Cycle 1(1): loaded http://localhost:51133/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51133/tests/dromaeo/cssquery-yui.html) 10:05:10 INFO - PID 15083 | RSS: Main: 177152000 10:05:10 INFO - PID 15083 | 10:08:12 INFO - PID 15083 | Cycle 1(1): loaded http://localhost:51133/tests/dromaeo/cssquery-yui.html (next: http://localhost:51133/tests/dromaeo/cssquery-dojo.html) 10:08:12 INFO - PID 15083 | RSS: Main: 177922048 10:08:12 INFO - PID 15083 | 10:08:12 INFO - PID 15083 | __start_tp_report 10:08:12 INFO - PID 15083 | _x_x_mozilla_page_load 10:08:12 INFO - PID 15083 | _x_x_mozilla_page_load_details 10:08:12 INFO - PID 15083 | |i|pagename|runs| 10:08:12 INFO - PID 15083 | |0;dojo.html;3096.102130382188;3123.203583086313;3145.7011583899534;3151.038933125396;3186.3149422874076;6159.969200153999;6190.442860142586;6206.224221972254;6207.503399728016;6248.781292654758;6041.154238406615;6150.108234306034;6156.507479401648;6188.566800323979;6205.379462053792;5603;5644.604877658555;5663.575231857607;5666.263385759846;5678.801241956512;5641.435856414346;5659.037963546207;5669.433056694318;5681.857953551153;5730.742116604742;5638.238837756891;5654.632448890829;5687.573431992597;5713.371529131792;5718.256626638531;5554.77780888766;5585.245991791137;5660.037793575102;5667.064934285838;5708.914366284509;5028.446870844205;5496.258005169291;5560.4161563035705;5594.9720251398485;5613.326400831886;6063.514918806485;6095.664738439384;6121.969390153021;6160.2915664698685;6201.6899155042065;6576.243731970789;6595.53831231814;6603.966980165116;6677.599344039325;6682.264950855402;6654.301298363651;6673.299303573152;6740.696668649923;6746.426553742937;6746.987951807219;6227.159333490012;6230.7819226326865;6255.96872015637;6257.561970662101;6258.280297765769;6822.795316140524;6839.692213850389;6915.930840691629;6924.58452492852;6945.444364450832;6478.481721462272;6530.673466326664;6538.0192971053775;6624.337566243434;6627.635480048643;6523.608583485006;6610.206775187008;6614.437772789271;6615.470762339001;6629.138212032405;6526.347365263436;6558.376954189344;6601.636909969901;6609.306022867626;6618.338166183343;6567.211934567882;6591.703373348211;6634.402903738686;6645.568038077509;6646.833829154213;5270;5279.234510995884;5339.252504649352;5343.812966546152;5360.758765855546;4597.95402045982;4664.277037059262;4668.766561671903;4670.276107203389;4688.906221875543;5579.9163012554845;5661.405552417019;5686.2607860977805;5747.339056008529;5751.511121554631;6574.80275591733;6605.801825945229;6612.471002319803;6637.535372523877;6754.93245067543;5659.660420374791;5672.744726487318;5742.770289188469;5783.768649254067;5820.214271073435;5750.281214848144;5759.1937128802;5775.971120144372;5809.651420914759;5822.893650206532;6496.902546461902;6506.544541882117;6521.119648847345;6529.902051469232;6545.8363540910905;6688.966555167193;6696.7656132035145;6719.160104986877;6736.090627765385;6747.190337159573;6368.203974503187;6379.138816259931;6419.614823110628;6466.900626893534;6482.319356467691;6441.3880681335195;6543.901841472477;6551.705173267119;6666.133402657623;6678.666066696548;6577.177852768404;6578.440832529289;6590.538662293593;6590.670466476561;6591.670416479061;6389.456896163692;6430.64631445275;6513.316101809241;6528.086067950586;6530.67346632657;6500.3174666659315;6558.376954189344;6559.508036897157;6569.770058047945;6583.80248592543;6355.269144048493;6370.108184854032;6386.425221729881;6410.647414392253;6482.189726284215;6323.08315294289;6414.390632889868;6521.608703477807;6553.770618028346;6643.933560664332;6253.187085678833;6327.050942358499;6328.651924144032;6341.460975817107;6343.270523889812 10:08:12 INFO - PID 15083 | |1;ext.html;826.6114925984785;876.2902049340036;903.4488961733341;906.9455832650042;1188.1089183112665;30349;31850.68149318506;42521.362179567295;42851.78574107125;43069.35395969063;42190.57809421905;42607.14785704284;42645.78677106618;42875.785621071926;43126.353104703456;7716.382689384835;7806.375489960804;7874.212578742137;7901.09137449195;7935.444518883681;7676.501027433227;7790.610469476504;7795.766127016199;7804.063512378522;7806.60966951653;7766.145723970359;7813.609319534029;7832.451728378987;7859.764207073768;7864.095629002681;7805.180456052118;7832.334251588622;7838.451308408414;7845.215478452138;7849.45053846231;7751.573663448508;7780.961095194544;7796.298333149987;7808.726694565663;7810.453268271223;37360.43959340612;37455.81272093649;37563.81218093892;37692;37721.62278377209;3391;3427.8971630850865;3429.7770644908;3436.9656303436896;3481.9651803481893;30474.39051218963;30493.08520744359;30528;30799.230019249473;30854.22864428385;4810.278458231258;4873.926891096601;4879.170541008037;4880.267959806022;4912.508749125094;184634;186081;186572.06713966478;187193;187526;48661;48697.756511217216;48842.848357889976;49053;49603;51156.48843511592;51402;51474;51477.48522514802;51588.48411515837;13769.931150344184;15198.696026079637;15478.690426191639;15499.070055796688;15515.689686206213;11424.200305978668;12118.212316199419;12251.509939602316;12256.44845981933;12455.377231138407;14293.85706142946;14353.712925741427;14382.137071775727;14480.493182738554;14620.634484137981;5862.501687356623;5897.911531327033;5975;6036;6056.757729690851;979.588572799426;983.4246965525248;989.2679417231341;1012.6101450941441;1023.1456733627356;21919.67120493194;21942.122315107215;22065.227717029826;22276.777232227796;22362.55274894493;1086.3617823496031;1092.7978324010085;1108.224243029866;1112.3993043756282;1120.3669926491507;11483.196176267747;11486.540538378538;11699.415029248503;11760.941195293968;11779.234349767408;224089;225078;226347;227647;228314;53388;53455;53558.196627050624;53570.46429535655;53832.73083634557;1468.221842423496;1472.2859413184597;1472.8968972171845;1485.5469081930114;1502.2413681091198;21793.4551636204;21900.45248868791;22100.66848997234;22170.44573885666;26538.469230614894;50298;50765;50847;50913;50998.745006276215;199683;201341;206858;212731;213023;32053.51919721253;32343;35015.47476787901;35295.64704353026;35859.10352241215;47005.764971174925;47453.76273118612;47461.762691186326;47731;47950;26974.865125674245;28261.717382825907;28529.42941141124;28652.14043578696;29295.85352073226;25512.617310740727;27421.725782742717;27422.45155096927;27562.173134805988;27927.720722792514;35295;36199.63800361963;36416.81791591025;36520.45219321659;36666.63333366632;30955.845220774652;31440.685593143775;32427.67572324341;32862.83568582142;33051.83474082614;23877.04491820308;23896.283111506684;24076.03695852146;24237.63643545313;24276.393090172885 10:08:12 INFO - PID 15083 | |2;jquery.html;565.3780841074815;571.3857603076693;579.5624303650745;593.8634114153745;601.608954179783;21684.89157554212;22238.332850014485;22883.19908803192;22887.771122288752;22897.313080607608;24863.25410237696;25793.61309580358;26812.731872681266;26868.193954181355;26977.190684279452;7034.6482675866255;7169.46229032822;7210.927890721105;7223.02489163964;7226.494145409823;6723.462123030145;6819.897701534481;6850.212225594046;7015.298470152995;7104.715811367539;7011.404030657375;7056.294370562954;7087.89368159478;7089.858202835941;7091.503594748369;7085.149782026138;7094.822629434279;7111.4666400020205;7112;7119.181294151187;6861.828454288633;6880.346367095168;6891.793246202572;6894.069300644449;6953.860922781567;30435.69564304351;31090;32452.675473245203;32932.83533582341;33055.83472082648;1915.789263181049;1954.3648314297768;1959.8628096033283;1961.9803801961941;1969.8227159555565;19956.600867982703;20468.181272749072;20616.38150855477;20640.69038964417;20715.89642051795;2460.3726049857173;2480.672840030575;2551.0561092395933;2577.252596746941;2578.1492107604445;138906;140712;142963.2851835734;143209;145103;19598.510037248896;21419.357419277447;21636.45908852291;22358.55282894333;22511;22211.66682499764;23891.16380926694;23902.88048559746;23947;43636.12727745491;10270.897291026995;10366.637167699095;10485.423301718478;10553.525091370906;10699.304545204537;9763.658271960447;9819.116279534876;9891.851622225673;10003.599856005678;10016.64941727036;9232.230330900837;9256.398334108397;9382.62469501226;9584.233261339075;9760.170385517169;2552.7579973433635;2589.5468293048643;2653.389720364327;2669.7864170866674;2760.3651160232857;933.7245512573807;938.957746901391;939.6429356844355;960.9471479068578;966.8018056298442;18844.246230150642;19052.047397630064;19419.2232310706;20157.193712251345;23594;979.7844474215661;997.6332424578374;1015.4567306490992;1026.7381817636604;1030.7062487191115;10600.522976466078;10718.73203169927;10870.945645271722;11156.721081973015;12833.358332083359;506327;515164;516573;518744;529247;123976;125490;126268;127568.36215819047;127780;1213.4963989944251;1221.1512998466158;1255.5856567332942;1257.867923867999;1266.322517453158;19953.401397958085;20955.580888381843;21101.155953761678;21353.6796948049;22273.777262227817;56791;57097.42902571087;57511.7124414392;60177.69911150416;60411;63730.68134659297;67719;68286.65856670684;68349.65825170842;69026.65486672535;15112;15291.847081529488;15381.3078411474;15402.075875447508;15421.922890385476;23294.65058024097;23688.763112369346;23955.281341559083;23991.880040600383;25244.990200391778;18601.906990464962;19577.608447831248;19578.314758983935;19693.507662308555;19867.801321986593;15065.548033558573;15520.689586207987;15780.92109539491;15975.8402415979;16703.413175748283;19973.600527989067;20119.899400502905;20194.29319973794;20350;20419.59160816805;14426.278686065865;14640.853591464374;14668.046576972463;14704.485343012942;14729.92635036818;18084.819151808315;18513;18890.6221875559;19093.33173338982;19094.61810763805 10:08:12 INFO - PID 15083 | |3;mootools.html;1344.7041650836816;1368.9794653080205;1381.28173349858;1384.750744865925;1403.151093588379;13475.932620336896;13757;14093.647658808535;14127.434902603896;14144.434222631082;14131.717365652681;14380.137191768501;14412.639684007905;14477.348519316629;14604.780928286084;2615.8299710518804;2673.104509989146;2676.745709157627;2681.048227879104;2681.919542413721;2496.5755821510297;2652.1910817200746;2698.8245764025273;2704.62135301058;2706.052881491472;2608.5304645163865;2630.8421494710283;2634.157069737675;2671.1719366996194;2674.745899139579;2560.2063360358256;2571.305747448197;2574.652421923035;2576.6135079738096;2579.1359894435386;2488.1664642344836;2509.372656835791;2530.1523989463553;2545.516351893153;2545.5672535668796;13196.14225075367;13452.596422107352;13489;13521.121127126706;13522.391492382769;7064.894026589605;7354.44841636874;7356.043714317158;7379.88930166048;7395.223501532316;4832.1543729847135;4860.3681521401995;4898.387701537308;5036.118679231156;5046.243063540462;1678.5048410718866;1692.3738216859717;1710.6835235481476;1751.098184435017;1758.5515693498205;22548.21081262148;22736.31791046255;22770.20304289342;22836.4290892729;22896.65655015176;6173.228346456693;6280.654563998933;6360.459360954371;6376.39424254695;6537;15232.466863659718;15240.618984525254;15250.847491525165;15274.770878436831;15279.541613751606;6893.034975103489;6961.40828029613;7044.471664625174;7076.575405475688;7091.822704432328;5488.313960754906;6428.325025872309;6745.08941292929;6804.455643548505;6804.965975170093;4934.703917764946;4993.226049962189;5010.1800471077095;5023.824166154167;5070.923936140961;1418.1491105336922;1426.2369632246698;1429.7783843504274;1436.289036926728;1457.883369330451;819.5533434278333;823.9052508961546;840.2941529115573;856.9400141990001;877.8507653698758;6518.804435866837;6704.597724136567;6843.3840954314355;6889.104416425933;7022.54353467023;928.6146249306597;940.1350757303297;955.1022039283051;965.9613615455444;978.4325091446943;4424.7123936944;4536.5236650151255;4550.13547425988;4551.840685575989;4662.813487460464;18182.363617273597;18620.255189792257;18786.342478013205;18910.338138165098;18961.336353227573;17067.231974561175;18364.632707346045;18668.906655466635;18857.717134242997;18898.244070237037;10767.6231331903;11045.723856903642;11055.281406708538;11115.221934464671;11117.166212534092;7054.118235220598;7064.682089305891;7179.676914538963;7291.453141014551;7369.152547456896;16415.91792041032;16637.750433743746;16641.41755038568;16661.666766664843;16798.58003549921;15522.611934701723;16621.916890415472;16756.916215419333;17090.2309396075;17462.47612571625;10398.168146548409;10652.360858348524;10790.946045269724;10859.565617375216;11099.223054386439;12119.151659384112;12217.633470995885;12233.816492752794;12302.815457767962;12310.322932238454;9669.516524173903;9680.661176858775;9845.753856153653;10113.64602238918;10137.391756494633;9121.908780912107;9297.651840482957;9357.906420935977;9593;9691.2731545133;8844.73465796027;8970.506622135845;8973.237274831712;8980.775480613036;8988.146126118008;6191.659458729633;6235.220597425322;6581.308962558863;6600.2409722882485;6644.767433139817;6249;6627.436667883091;6640.900386494303;6685.331466853276;6812.284710105368 10:08:12 INFO - PID 15083 | |4;prototype.html;495.4253066442927;497.6441844081482;521.0854949563516;527.4172039895915;528.7858417340973;6174.289956654986;6180.443760061594;6188.319284878659;6198.132261483396;6200.658963756991;5794.68129252891;5976.402359764033;5992.370801065891;6029.69851507425;6047.667378294192;4795.664303498756;4801.743180705081;4826.734529600871;4835.758212089399;4862.465128835825;4681.485036645983;4790.784414701329;4813;4858.319835223072;4895.681780684244;4747.8338258160975;4781.163296423129;4801.711897286156;4817.0365926814775;4841.2496063110275;4728.432588089434;4776.617870570346;4780.80876764929;4813.66304358695;4868.14807408702;4334.176506463793;4338.2191205582985;4374.956250437487;4397.714148580332;4402.537733537996;5940.37626049263;5948.910766338508;5990.610610310316;5997.421108923161;6042.335343112254;5599.132134519156;5624.831255062355;5627.493525582676;5647.124695672177;5676.943230567724;6285.937140628581;6423.582467139668;6460.709268082947;6489.870202595922;6493.93506064938;4762.499937506546;4817.132916075105;4822.445418776851;4851.466338702739;4855.174620314555;6743.966280168617;6767.661616919135;6818.965905170492;6820.181578210547;6830.652980039745;4839.782209800497;4858.392700912386;4891;4904.337914381585;4934.555889969848;4906.901861962812;4912.312276281252;4924.285978533093;4955;4958.330625365602;5581.274434323594;5597.244372009808;5598.356189038232;5612.1581762735095;5656.802011929645;6368.203974503187;6396.392342727433;6419.775307864203;6464.612123272619;6477.902831457532;1650.1336798180812;1697.9660406791795;1699.7450382442732;1716.6824137534595;1752.7029997801535;1176.805827038529;1210.2073142091945;1213.6177104212145;1222.9299363057326;1229.9877001229872;5601.859953501195;5620.887582248414;5696.946064977992;5732.484076433057;5737.77048918047;6432.64914367988;6451.9354806452275;6483.3516648335735;6548.574342667712;6570.193196870803;5673.971630141823;5674;5692.829215123553;5699.145128230715;5756.65460072397;3481.5473988381855;3488.8430020648625;3508.4035713928693;3509.9298014039578;3551.3430015446706;6381.649009304533;6421.422072013448;6455.9677201613695;6456.322086180883;6486.902696459557;6718.294579069225;6770;6828.760993365208;6846.349596788297;6874.9656251718425;6337.334579869047;6359.618422894456;6392;6430.164078669743;6461.25695545009;6547.770828020996;6648.468122550281;6652.700628471827;6659.234188068434;6679.298673639392;6497.317781632861;6560.212774467094;6586.47308215332;6598.769043083469;6640.136782218281;6440.876175055001;6443.903341449788;6456.322086180883;6457.644829534421;6490.253620833664;4871.19625261842;4873.4882837301575;4891.682040667345;4903.803847846046;4908.533689299511;6620.8344791380605;6648.800535983928;6690.531662783558;6697.263301036979;6708.765193218214;6425.293217746138;6492.707828147839;6498.480121590167;6528.151340325728;6551.344865513411;6233.657148856675;6298.149749783721;6324.272708638566;6331.335209803088;6338.429541341301;6022.939770602238;6196.366544218779;6276.089966954857;6324.367563243639;6342.809715708536;6407.302064952698;6471.091028146614;6482.7634466111;6485;6501.3823686751575;6287.811365659038;6317.483803887126;6320.178376810985;6344.492440604648;6365.458935990493 10:08:12 INFO - PID 15083 | |5;yui.html;876.9912300876989;906;919.4621146629222;919.862020696895;923.6859467780953;18084.909575452122;19062.332818351362;19251.711224331648;19321.71017434736;20256.594868102628;18955;18979.33572324969;19063;19075.236990520363;19083.141258643358;951.1154626197633;1007.9848802267971;1036.9585216591327;1054.2514814481726;1060.2472244706269;1050.1756121444666;1050.5167622893478;1051.2956319266073;1056.6037735849056;1061.2836335473523;1009.1633227525906;1064.7604289034982;1065.2702898514503;1070.9357438553711;1086.5816660585692;1034.4207243943415;1040.8126537223297;1040.885502594714;1043.57213542448;1053.5364439646569;772.0619447371471;774.4346626962349;774.8217909880759;776.5263189454429;782.3858271257093;19004.904975475034;19228.71156932647;19319.227230910747;19331.710024349646;19987.90006049975;19661.705074423753;19961.201551937906;20309;20381.286654967153;20429.79570204294;15420.306086226146;16240.431584894472;16331.18344082803;16388.508344749556;16393.83606163947;3469.5316132322064;3484.6863782259466;3490.127468132967;3492.3539145257955;3497.2131270464197;123526.38236808848;126436;128364.35817820851;129067;131933;15360.385584576716;16125;16190.838091619169;16221.675566488604;16227.350905963865;27489.17532474029;29149.125526234246;29166.854165729037;29202.707972920423;29240.85379573131;7863.960680196562;7942.920570794334;8036.6785328587375;8044.839103217902;8059.395545334123;3570.285942811448;3703.3519134151416;3708.313961917054;4059.5331536873637;4167.208230436268;4935.457099719028;5038.168702164176;5043.142665746832;5045.369328833895;5063.113955057924;937.381328323317;966.7873067925045;975.317277905469;980.3235767320526;983.331334692416;626.2516293029877;643.3116565275204;643.4659232836734;666.6633350158139;683.8119517132728;11381.772364552828;11611.245269057485;11632.243904146204;12021.819672704916;12210.450529726006;659.9043138744855;686.7802303263007;688.1948120698794;694.0838093716347;702.2029995954629;5203.635745497779;5267.920981185285;5286.735663216824;5319.494648008433;5527.695976721319;92188.53905730562;93697.53151234337;96405.5179724111;98749;99732;86292;87918.56040719755;88212;89458.55270723604;93990;70717;72995;73783;76414.61792691112;79356.6032169847;10368.688939331832;10695.839562406414;10795.352278863293;11113.277636953571;11134.888651113386;32264.35471290608;33171.83414082914;34403.65596344005;35040.12399689926;35432;42199.15601687888;43317.566824331356;43459;44046.559534404245;44548.33177502275;13730.176189428666;13824.723505530033;13861.653458663613;13918.58244252674;14115.717685646436;15732.60668483297;15780.210989450712;15846.049237045814;15988.520344389686;17483.475495735147;15208.391664333303;17931.910340448216;18155.092245387412;18239.452816415527;18549.81450185481;16313.673726525165;16527.421540246032;16530.66938661244;16657.50027499177;16849.15754212309;17886.195121219836;18153.727694084864;18640.533986650444;19022.04889755479;19243.326483573008;12462.813057803958;12556;12776.48894044265;12901.032422568158;13150.21098734079;14006.929965350108;14528;14716.19060951615;14810.851891481378;14815.25923703832 10:08:12 INFO - PID 15083 | __end_tp_report 10:08:12 INFO - PID 15083 | __start_cc_report 10:08:12 INFO - PID 15083 | _x_x_mozilla_cycle_collect,362 10:08:12 INFO - PID 15083 | __end_cc_report 10:08:12 INFO - PID 15083 | __startTimestamp1501520892359__endTimestamp 10:08:12 INFO - PID 15083 | 10:08:12 INFO - PID 15083 | ------- Summary: start ------- 10:08:12 INFO - PID 15083 | Number of tests: 6 10:08:12 INFO - PID 15083 | 10:08:12 INFO - PID 15083 | [#0] dojo.html Cycles:165 Average:6104.33 Median:6368.20 stddev:730.05 (11.5%) stddev-sans-first:693.10 10:08:12 INFO - PID 15083 | Values: 3096.1 3123.2 3145.7 3151.0 3186.3 6160.0 6190.4 6206.2 6207.5 6248.8 6041.2 6150.1 6156.5 6188.6 6205.4 5603.0 5644.6 5663.6 5666.3 5678.8 5641.4 5659.0 5669.4 5681.9 5730.7 5638.2 5654.6 5687.6 5713.4 5718.3 5554.8 5585.2 5660.0 5667.1 5708.9 5028.4 5496.3 5560.4 5595.0 5613.3 6063.5 6095.7 6122.0 6160.3 6201.7 6576.2 6595.5 6604.0 6677.6 6682.3 6654.3 6673.3 6740.7 6746.4 6747.0 6227.2 6230.8 6256.0 6257.6 6258.3 6822.8 6839.7 6915.9 6924.6 6945.4 6478.5 6530.7 6538.0 6624.3 6627.6 6523.6 6610.2 6614.4 6615.5 6629.1 6526.3 6558.4 6601.6 6609.3 6618.3 6567.2 6591.7 6634.4 6645.6 6646.8 5270.0 5279.2 5339.3 5343.8 5360.8 4598.0 4664.3 4668.8 4670.3 4688.9 5579.9 5661.4 5686.3 5747.3 5751.5 6574.8 6605.8 6612.5 6637.5 6754.9 5659.7 5672.7 5742.8 5783.8 5820.2 5750.3 5759.2 5776.0 5809.7 5822.9 6496.9 6506.5 6521.1 6529.9 6545.8 6689.0 6696.8 6719.2 6736.1 6747.2 6368.2 6379.1 6419.6 6466.9 6482.3 6441.4 6543.9 6551.7 6666.1 6678.7 6577.2 6578.4 6590.5 6590.7 6591.7 6389.5 6430.6 6513.3 6528.1 6530.7 6500.3 6558.4 6559.5 6569.8 6583.8 6355.3 6370.1 6386.4 6410.6 6482.2 6323.1 6414.4 6521.6 6553.8 6643.9 6253.2 6327.1 6328.7 6341.5 6343.3 10:08:12 INFO - PID 15083 | 10:08:12 INFO - PID 15083 | [#1] ext.html Cycles:180 Average:37746.46 Median:3459.47 stddev:53791.80 (1554.9%) stddev-sans-first:53871.26 10:08:12 INFO - PID 15083 | Values: 826.6 876.3 903.4 906.9 1188.1 30349.0 31850.7 42521.4 42851.8 43069.4 42190.6 42607.1 42645.8 42875.8 43126.4 7716.4 7806.4 7874.2 7901.1 7935.4 7676.5 7790.6 7795.8 7804.1 7806.6 7766.1 7813.6 7832.5 7859.8 7864.1 7805.2 7832.3 7838.5 7845.2 7849.5 7751.6 7781.0 7796.3 7808.7 7810.5 37360.4 37455.8 37563.8 37692.0 37721.6 3391.0 3427.9 3429.8 3437.0 3482.0 30474.4 30493.1 30528.0 30799.2 30854.2 4810.3 4873.9 4879.2 4880.3 4912.5 184634.0 186081.0 186572.1 187193.0 187526.0 48661.0 48697.8 48842.8 49053.0 49603.0 51156.5 51402.0 51474.0 51477.5 51588.5 13769.9 15198.7 15478.7 15499.1 15515.7 11424.2 12118.2 12251.5 12256.4 12455.4 14293.9 14353.7 14382.1 14480.5 14620.6 5862.5 5897.9 5975.0 6036.0 6056.8 979.6 983.4 989.3 1012.6 1023.1 21919.7 21942.1 22065.2 22276.8 22362.6 1086.4 1092.8 1108.2 1112.4 1120.4 11483.2 11486.5 11699.4 11760.9 11779.2 224089.0 225078.0 226347.0 227647.0 228314.0 53388.0 53455.0 53558.2 53570.5 53832.7 1468.2 1472.3 1472.9 1485.5 1502.2 21793.5 21900.5 22100.7 22170.4 26538.5 50298.0 50765.0 50847.0 50913.0 50998.7 199683.0 201341.0 206858.0 212731.0 213023.0 32053.5 32343.0 35015.5 35295.6 35859.1 47005.8 47453.8 47461.8 47731.0 47950.0 26974.9 28261.7 28529.4 28652.1 29295.9 25512.6 27421.7 27422.5 27562.2 27927.7 35295.0 36199.6 36416.8 36520.5 36666.6 30955.8 31440.7 32427.7 32862.8 33051.8 23877.0 23896.3 24076.0 24237.6 24276.4 10:08:12 INFO - PID 15083 | 10:08:12 INFO - PID 15083 | [#2] jquery.html Cycles:180 Average:36621.18 Median:23444.33 stddev:87051.32 (371.3%) stddev-sans-first:87253.43 10:08:12 INFO - PID 15083 | Values: 565.4 571.4 579.6 593.9 601.6 21684.9 22238.3 22883.2 22887.8 22897.3 24863.3 25793.6 26812.7 26868.2 26977.2 7034.6 7169.5 7210.9 7223.0 7226.5 6723.5 6819.9 6850.2 7015.3 7104.7 7011.4 7056.3 7087.9 7089.9 7091.5 7085.1 7094.8 7111.5 7112.0 7119.2 6861.8 6880.3 6891.8 6894.1 6953.9 30435.7 31090.0 32452.7 32932.8 33055.8 1915.8 1954.4 1959.9 1962.0 1969.8 19956.6 20468.2 20616.4 20640.7 20715.9 2460.4 2480.7 2551.1 2577.3 2578.1 138906.0 140712.0 142963.3 143209.0 145103.0 19598.5 21419.4 21636.5 22358.6 22511.0 22211.7 23891.2 23902.9 23947.0 43636.1 10270.9 10366.6 10485.4 10553.5 10699.3 9763.7 9819.1 9891.9 10003.6 10016.6 9232.2 9256.4 9382.6 9584.2 9760.2 2552.8 2589.5 2653.4 2669.8 2760.4 933.7 939.0 939.6 960.9 966.8 18844.2 19052.0 19419.2 20157.2 23594.0 979.8 997.6 1015.5 1026.7 1030.7 10600.5 10718.7 10870.9 11156.7 12833.4 506327.0 515164.0 516573.0 518744.0 529247.0 123976.0 125490.0 126268.0 127568.4 127780.0 1213.5 1221.2 1255.6 1257.9 1266.3 19953.4 20955.6 21101.2 21353.7 22273.8 56791.0 57097.4 57511.7 60177.7 60411.0 63730.7 67719.0 68286.7 68349.7 69026.7 15112.0 15291.8 15381.3 15402.1 15421.9 23294.7 23688.8 23955.3 23991.9 25245.0 18601.9 19577.6 19578.3 19693.5 19867.8 15065.5 15520.7 15780.9 15975.8 16703.4 19973.6 20119.9 20194.3 20350.0 20419.6 14426.3 14640.9 14668.0 14704.5 14729.9 18084.8 18513.0 18890.6 19093.3 19094.6 10:08:12 INFO - PID 15083 | 10:08:12 INFO - PID 15083 | [#3] mootools.html Cycles:180 Average:8346.12 Median:2632.50 stddev:5795.13 (220.1%) stddev-sans-first:5787.51 10:08:12 INFO - PID 15083 | Values: 1344.7 1369.0 1381.3 1384.8 1403.2 13475.9 13757.0 14093.6 14127.4 14144.4 14131.7 14380.1 14412.6 14477.3 14604.8 2615.8 2673.1 2676.7 2681.0 2681.9 2496.6 2652.2 2698.8 2704.6 2706.1 2608.5 2630.8 2634.2 2671.2 2674.7 2560.2 2571.3 2574.7 2576.6 2579.1 2488.2 2509.4 2530.2 2545.5 2545.6 13196.1 13452.6 13489.0 13521.1 13522.4 7064.9 7354.4 7356.0 7379.9 7395.2 4832.2 4860.4 4898.4 5036.1 5046.2 1678.5 1692.4 1710.7 1751.1 1758.6 22548.2 22736.3 22770.2 22836.4 22896.7 6173.2 6280.7 6360.5 6376.4 6537.0 15232.5 15240.6 15250.8 15274.8 15279.5 6893.0 6961.4 7044.5 7076.6 7091.8 5488.3 6428.3 6745.1 6804.5 6805.0 4934.7 4993.2 5010.2 5023.8 5070.9 1418.1 1426.2 1429.8 1436.3 1457.9 819.6 823.9 840.3 856.9 877.9 6518.8 6704.6 6843.4 6889.1 7022.5 928.6 940.1 955.1 966.0 978.4 4424.7 4536.5 4550.1 4551.8 4662.8 18182.4 18620.3 18786.3 18910.3 18961.3 17067.2 18364.6 18668.9 18857.7 18898.2 10767.6 11045.7 11055.3 11115.2 11117.2 7054.1 7064.7 7179.7 7291.5 7369.2 16415.9 16637.8 16641.4 16661.7 16798.6 15522.6 16621.9 16756.9 17090.2 17462.5 10398.2 10652.4 10790.9 10859.6 11099.2 12119.2 12217.6 12233.8 12302.8 12310.3 9669.5 9680.7 9845.8 10113.6 10137.4 9121.9 9297.7 9357.9 9593.0 9691.3 8844.7 8970.5 8973.2 8980.8 8988.1 6191.7 6235.2 6581.3 6600.2 6644.8 6249.0 6627.4 6640.9 6685.3 6812.3 10:08:12 INFO - PID 15083 | 10:08:12 INFO - PID 15083 | [#4] prototype.html Cycles:180 Average:5410.90 Median:5994.90 stddev:1527.61 (25.5%) stddev-sans-first:1486.68 10:08:12 INFO - PID 15083 | Values: 495.4 497.6 521.1 527.4 528.8 6174.3 6180.4 6188.3 6198.1 6200.7 5794.7 5976.4 5992.4 6029.7 6047.7 4795.7 4801.7 4826.7 4835.8 4862.5 4681.5 4790.8 4813.0 4858.3 4895.7 4747.8 4781.2 4801.7 4817.0 4841.2 4728.4 4776.6 4780.8 4813.7 4868.1 4334.2 4338.2 4375.0 4397.7 4402.5 5940.4 5948.9 5990.6 5997.4 6042.3 5599.1 5624.8 5627.5 5647.1 5676.9 6285.9 6423.6 6460.7 6489.9 6493.9 4762.5 4817.1 4822.4 4851.5 4855.2 6744.0 6767.7 6819.0 6820.2 6830.7 4839.8 4858.4 4891.0 4904.3 4934.6 4906.9 4912.3 4924.3 4955.0 4958.3 5581.3 5597.2 5598.4 5612.2 5656.8 6368.2 6396.4 6419.8 6464.6 6477.9 1650.1 1698.0 1699.7 1716.7 1752.7 1176.8 1210.2 1213.6 1222.9 1230.0 5601.9 5620.9 5696.9 5732.5 5737.8 6432.6 6451.9 6483.4 6548.6 6570.2 5674.0 5674.0 5692.8 5699.1 5756.7 3481.5 3488.8 3508.4 3509.9 3551.3 6381.6 6421.4 6456.0 6456.3 6486.9 6718.3 6770.0 6828.8 6846.3 6875.0 6337.3 6359.6 6392.0 6430.2 6461.3 6547.8 6648.5 6652.7 6659.2 6679.3 6497.3 6560.2 6586.5 6598.8 6640.1 6440.9 6443.9 6456.3 6457.6 6490.3 4871.2 4873.5 4891.7 4903.8 4908.5 6620.8 6648.8 6690.5 6697.3 6708.8 6425.3 6492.7 6498.5 6528.2 6551.3 6233.7 6298.1 6324.3 6331.3 6338.4 6022.9 6196.4 6276.1 6324.4 6342.8 6407.3 6471.1 6482.8 6485.0 6501.4 6287.8 6317.5 6320.2 6344.5 6365.5 10:08:12 INFO - PID 15083 | 10:08:12 INFO - PID 15083 | [#5] yui.html Cycles:180 Average:21411.51 Median:19326.71 stddev:29301.82 (151.6%) stddev-sans-first:29343.45 10:08:12 INFO - PID 15083 | Values: 877.0 906.0 919.5 919.9 923.7 18084.9 19062.3 19251.7 19321.7 20256.6 18955.0 18979.3 19063.0 19075.2 19083.1 951.1 1008.0 1037.0 1054.3 1060.2 1050.2 1050.5 1051.3 1056.6 1061.3 1009.2 1064.8 1065.3 1070.9 1086.6 1034.4 1040.8 1040.9 1043.6 1053.5 772.1 774.4 774.8 776.5 782.4 19004.9 19228.7 19319.2 19331.7 19987.9 19661.7 19961.2 20309.0 20381.3 20429.8 15420.3 16240.4 16331.2 16388.5 16393.8 3469.5 3484.7 3490.1 3492.4 3497.2 123526.4 126436.0 128364.4 129067.0 131933.0 15360.4 16125.0 16190.8 16221.7 16227.4 27489.2 29149.1 29166.9 29202.7 29240.9 7864.0 7942.9 8036.7 8044.8 8059.4 3570.3 3703.4 3708.3 4059.5 4167.2 4935.5 5038.2 5043.1 5045.4 5063.1 937.4 966.8 975.3 980.3 983.3 626.3 643.3 643.5 666.7 683.8 11381.8 11611.2 11632.2 12021.8 12210.5 659.9 686.8 688.2 694.1 702.2 5203.6 5267.9 5286.7 5319.5 5527.7 92188.5 93697.5 96405.5 98749.0 99732.0 86292.0 87918.6 88212.0 89458.6 93990.0 70717.0 72995.0 73783.0 76414.6 79356.6 10368.7 10695.8 10795.4 11113.3 11134.9 32264.4 33171.8 34403.7 35040.1 35432.0 42199.2 43317.6 43459.0 44046.6 44548.3 13730.2 13824.7 13861.7 13918.6 14115.7 15732.6 15780.2 15846.0 15988.5 17483.5 15208.4 17931.9 18155.1 18239.5 18549.8 16313.7 16527.4 16530.7 16657.5 16849.2 17886.2 18153.7 18640.5 19022.0 19243.3 12462.8 12556.0 12776.5 12901.0 13150.2 14006.9 14528.0 14716.2 14810.9 14815.3 10:08:12 INFO - PID 15083 | -------- Summary: end -------- 10:08:12 INFO - PID 15083 | 10:08:13 INFO - TEST-INFO | 15083: exit 0 10:08:13 INFO - TEST-OK | dromaeo_css | took 1113345ms 10:08:13 INFO - TEST-START | kraken 10:08:13 INFO - Initialising browser for kraken test... 10:08:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51133/getInfo.html -profile /tmp/tmpce3dL2/profile 10:08:13 INFO - TEST-INFO | started process 15453 (/builds/slave/test/build/application/firefox/firefox http://localhost:51133/getInfo.html) 10:08:18 INFO - TEST-INFO | 15453: exit 0 10:08:18 INFO - Browser initialized. 10:08:18 INFO - Running cycle 1/1 for kraken test... 10:08:18 INFO - TEST-INFO | started process 15680 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpce3dL2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:08:19 INFO - PID 15680 | 10:08:19 INFO - PID 15680 | (/builds/slave/test/build/application/firefox/firefox:15734): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 10:08:19 INFO - PID 15680 | 10:08:29 INFO - PID 15680 | RSS: Main: 174243840 10:08:29 INFO - PID 15680 | 10:08:32 INFO - PID 15680 | Cycle 1(1): loaded http://localhost:51133/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51133/tests/kraken/driver.html?testName=audio-beat-detection) 10:08:33 INFO - PID 15680 | RSS: Main: 178360320 10:08:33 INFO - PID 15680 | 10:08:36 INFO - PID 15680 | Cycle 1(1): loaded http://localhost:51133/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51133/tests/kraken/driver.html?testName=audio-dft) 10:08:36 INFO - PID 15680 | RSS: Main: 179027968 10:08:36 INFO - PID 15680 | 10:08:40 INFO - PID 15680 | Cycle 1(1): loaded http://localhost:51133/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51133/tests/kraken/driver.html?testName=audio-fft) 10:08:40 INFO - PID 15680 | RSS: Main: 174501888 10:08:40 INFO - PID 15680 | 10:08:42 INFO - PID 15680 | Cycle 1(1): loaded http://localhost:51133/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51133/tests/kraken/driver.html?testName=audio-oscillator) 10:08:43 INFO - PID 15680 | RSS: Main: 174428160 10:08:43 INFO - PID 15680 | 10:08:45 INFO - PID 15680 | Cycle 1(1): loaded http://localhost:51133/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51133/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:08:46 INFO - PID 15680 | RSS: Main: 175243264 10:08:46 INFO - PID 15680 | 10:08:49 INFO - PID 15680 | Cycle 1(1): loaded http://localhost:51133/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51133/tests/kraken/driver.html?testName=imaging-darkroom) 10:08:50 INFO - PID 15680 | RSS: Main: 177274880 10:08:50 INFO - PID 15680 | 10:08:53 INFO - PID 15680 | Cycle 1(1): loaded http://localhost:51133/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51133/tests/kraken/driver.html?testName=imaging-desaturate) 10:08:54 INFO - PID 15680 | RSS: Main: 176644096 10:08:54 INFO - PID 15680 | 10:08:57 INFO - PID 15680 | Cycle 1(1): loaded http://localhost:51133/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51133/tests/kraken/driver.html?testName=json-parse-financial) 10:08:58 INFO - PID 15680 | RSS: Main: 177631232 10:08:58 INFO - PID 15680 | 10:08:59 INFO - PID 15680 | Cycle 1(1): loaded http://localhost:51133/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51133/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:09:00 INFO - PID 15680 | RSS: Main: 177864704 10:09:00 INFO - PID 15680 | 10:09:02 INFO - PID 15680 | Cycle 1(1): loaded http://localhost:51133/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51133/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:09:02 INFO - PID 15680 | RSS: Main: 174714880 10:09:02 INFO - PID 15680 | 10:09:05 INFO - PID 15680 | Cycle 1(1): loaded http://localhost:51133/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51133/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:09:05 INFO - PID 15680 | RSS: Main: 174522368 10:09:05 INFO - PID 15680 | 10:09:08 INFO - PID 15680 | Cycle 1(1): loaded http://localhost:51133/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51133/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:09:09 INFO - PID 15680 | RSS: Main: 175116288 10:09:09 INFO - PID 15680 | 10:09:12 INFO - PID 15680 | Cycle 1(1): loaded http://localhost:51133/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51133/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:09:13 INFO - PID 15680 | RSS: Main: 175079424 10:09:13 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | Cycle 1(1): loaded http://localhost:51133/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51133/tests/kraken/driver.html?testName=ai-astar) 10:09:15 INFO - PID 15680 | RSS: Main: 176881664 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | __start_tp_report 10:09:15 INFO - PID 15680 | _x_x_mozilla_page_load 10:09:15 INFO - PID 15680 | _x_x_mozilla_page_load_details 10:09:15 INFO - PID 15680 | |i|pagename|runs| 10:09:15 INFO - PID 15680 | |0;ai-astar;100;109;109;109;110;110;109;102;109;109 10:09:15 INFO - PID 15680 | |1;audio-beat-detection;158;126;153;178;121;150;151;123;168;120 10:09:15 INFO - PID 15680 | |2;audio-dft;171;166;168;165;164;166;168;171;173;168 10:09:15 INFO - PID 15680 | |3;audio-fft;77;77;74;171;77;77;76;95;96;76 10:09:15 INFO - PID 15680 | |4;audio-oscillator;83;81;132;79;78;79;126;79;79;141 10:09:15 INFO - PID 15680 | |5;imaging-gaussian-blur;119;116;114;114;114;114;115;121;115;115 10:09:15 INFO - PID 15680 | |6;imaging-darkroom;152;167;151;158;150;151;155;153;152;152 10:09:15 INFO - PID 15680 | |7;imaging-desaturate;95;96;95;95;95;94;95;94;94;101 10:09:15 INFO - PID 15680 | |8;json-parse-financial;58;58;57;57;58;56;63;57;59;66 10:09:15 INFO - PID 15680 | |9;json-stringify-tinderbox;54;57;61;55;54;55;54;55;54;57 10:09:15 INFO - PID 15680 | |10;stanford-crypto-aes;81;94;80;80;81;96;82;83;84;82 10:09:15 INFO - PID 15680 | |11;stanford-crypto-ccm;135;131;128;140;131;130;130;132;148;180 10:09:15 INFO - PID 15680 | |12;stanford-crypto-pbkdf2;196;187;185;187;184;202;189;189;185;184 10:09:15 INFO - PID 15680 | |13;stanford-crypto-sha256-iterative;70;76;75;69;72;68;69;79;69;67 10:09:15 INFO - PID 15680 | __end_tp_report 10:09:15 INFO - PID 15680 | __start_cc_report 10:09:15 INFO - PID 15680 | _x_x_mozilla_cycle_collect,931 10:09:15 INFO - PID 15680 | __end_cc_report 10:09:15 INFO - PID 15680 | __startTimestamp1501520955386__endTimestamp 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | ------- Summary: start ------- 10:09:15 INFO - PID 15680 | Number of tests: 14 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | [#0] ai-astar Cycles:10 Average:107.60 Median:109.00 stddev:3.53 (3.2%) stddev-sans-first:2.46 10:09:15 INFO - PID 15680 | Values: 100.0 109.0 109.0 109.0 110.0 110.0 109.0 102.0 109.0 109.0 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | [#1] audio-beat-detection Cycles:10 Average:144.80 Median:152.00 stddev:20.97 (13.8%) stddev-sans-first:21.69 10:09:15 INFO - PID 15680 | Values: 158.0 126.0 153.0 178.0 121.0 150.0 151.0 123.0 168.0 120.0 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | [#2] audio-dft Cycles:10 Average:168.00 Median:168.00 stddev:2.91 (1.7%) stddev-sans-first:2.87 10:09:15 INFO - PID 15680 | Values: 171.0 166.0 168.0 165.0 164.0 166.0 168.0 171.0 173.0 168.0 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | [#3] audio-fft Cycles:10 Average:89.60 Median:77.00 stddev:29.71 (38.6%) stddev-sans-first:31.16 10:09:15 INFO - PID 15680 | Values: 77.0 77.0 74.0 171.0 77.0 77.0 76.0 95.0 96.0 76.0 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:79.00 stddev:26.02 (32.9%) stddev-sans-first:27.19 10:09:15 INFO - PID 15680 | Values: 83.0 81.0 132.0 79.0 78.0 79.0 126.0 79.0 79.0 141.0 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | [#5] imaging-gaussian-blur Cycles:10 Average:115.70 Median:115.00 stddev:2.41 (2.1%) stddev-sans-first:2.24 10:09:15 INFO - PID 15680 | Values: 119.0 116.0 114.0 114.0 114.0 114.0 115.0 121.0 115.0 115.0 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | [#6] imaging-darkroom Cycles:10 Average:154.10 Median:152.50 stddev:5.09 (3.3%) stddev-sans-first:5.34 10:09:15 INFO - PID 15680 | Values: 152.0 167.0 151.0 158.0 150.0 151.0 155.0 153.0 152.0 152.0 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:2.07 (2.2%) stddev-sans-first:2.19 10:09:15 INFO - PID 15680 | Values: 95.0 96.0 95.0 95.0 95.0 94.0 95.0 94.0 94.0 101.0 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | [#8] json-parse-financial Cycles:10 Average:58.90 Median:58.00 stddev:3.14 (5.4%) stddev-sans-first:3.32 10:09:15 INFO - PID 15680 | Values: 58.0 58.0 57.0 57.0 58.0 56.0 63.0 57.0 59.0 66.0 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | [#9] json-stringify-tinderbox Cycles:10 Average:55.60 Median:55.00 stddev:2.22 (4.0%) stddev-sans-first:2.28 10:09:15 INFO - PID 15680 | Values: 54.0 57.0 61.0 55.0 54.0 55.0 54.0 55.0 54.0 57.0 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:82.50 stddev:5.79 (7.0%) stddev-sans-first:6.02 10:09:15 INFO - PID 15680 | Values: 81.0 94.0 80.0 80.0 81.0 96.0 82.0 83.0 84.0 82.0 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | [#11] stanford-crypto-ccm Cycles:10 Average:138.50 Median:133.50 stddev:15.76 (11.8%) stddev-sans-first:16.67 10:09:15 INFO - PID 15680 | Values: 135.0 131.0 128.0 140.0 131.0 130.0 130.0 132.0 148.0 180.0 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.80 Median:188.00 stddev:5.85 (3.1%) stddev-sans-first:5.59 10:09:15 INFO - PID 15680 | Values: 196.0 187.0 185.0 187.0 184.0 202.0 189.0 189.0 185.0 184.0 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.40 Median:71.00 stddev:3.98 (5.6%) stddev-sans-first:4.19 10:09:15 INFO - PID 15680 | Values: 70.0 76.0 75.0 69.0 72.0 68.0 69.0 79.0 69.0 67.0 10:09:15 INFO - PID 15680 | -------- Summary: end -------- 10:09:15 INFO - PID 15680 | 10:09:15 INFO - PID 15680 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 10:09:16 INFO - TEST-INFO | 15680: exit 0 10:09:16 INFO - TEST-OK | kraken | took 63110ms 10:09:16 INFO - SUITE-END | took 1176s 10:09:16 INFO - Completed test suite (00:19:36) 10:09:16 INFO - javascript benchmark 10:09:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8347.09724530167, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3096.102130382188, 3123.203583086313, 3145.7011583899534, 3151.038933125396, 3186.3149422874076, 6159.969200153999, 6190.442860142586, 6206.224221972254, 6207.503399728016, 6248.781292654758, 6041.154238406615, 6150.108234306034, 6156.507479401648, 6188.566800323979, 6205.379462053792, 5603.0, 5644.604877658555, 5663.575231857607, 5666.263385759846, 5678.801241956512, 5641.435856414346, 5659.037963546207, 5669.433056694318, 5681.857953551153, 5730.742116604742, 5638.238837756891, 5654.632448890829, 5687.573431992597, 5713.371529131792, 5718.256626638531, 5554.77780888766, 5585.245991791137, 5660.037793575102, 5667.064934285838, 5708.914366284509, 5028.446870844205, 5496.258005169291, 5560.4161563035705, 5594.9720251398485, 5613.326400831886, 6063.514918806485, 6095.664738439384, 6121.969390153021, 6160.2915664698685, 6201.6899155042065, 6576.243731970789, 6595.53831231814, 6603.966980165116, 6677.599344039325, 6682.264950855402, 6654.301298363651, 6673.299303573152, 6740.696668649923, 6746.426553742937, 6746.987951807219, 6227.159333490012, 6230.7819226326865, 6255.96872015637, 6257.561970662101, 6258.280297765769, 6822.795316140524, 6839.692213850389, 6915.930840691629, 6924.58452492852, 6945.444364450832, 6478.481721462272, 6530.673466326664, 6538.0192971053775, 6624.337566243434, 6627.635480048643, 6523.608583485006, 6610.206775187008, 6614.437772789271, 6615.470762339001, 6629.138212032405, 6526.347365263436, 6558.376954189344, 6601.636909969901, 6609.306022867626, 6618.338166183343, 6567.211934567882, 6591.703373348211, 6634.402903738686, 6645.568038077509, 6646.833829154213, 5270.0, 5279.234510995884, 5339.252504649352, 5343.812966546152, 5360.758765855546, 4597.95402045982, 4664.277037059262, 4668.766561671903, 4670.276107203389, 4688.906221875543, 5579.9163012554845, 5661.405552417019, 5686.2607860977805, 5747.339056008529, 5751.511121554631, 6574.80275591733, 6605.801825945229, 6612.471002319803, 6637.535372523877, 6754.93245067543, 5659.660420374791, 5672.744726487318, 5742.770289188469, 5783.768649254067, 5820.214271073435, 5750.281214848144, 5759.1937128802, 5775.971120144372, 5809.651420914759, 5822.893650206532, 6496.902546461902, 6506.544541882117, 6521.119648847345, 6529.902051469232, 6545.8363540910905, 6688.966555167193, 6696.7656132035145, 6719.160104986877, 6736.090627765385, 6747.190337159573, 6368.203974503187, 6379.138816259931, 6419.614823110628, 6466.900626893534, 6482.319356467691, 6441.3880681335195, 6543.901841472477, 6551.705173267119, 6666.133402657623, 6678.666066696548, 6577.177852768404, 6578.440832529289, 6590.538662293593, 6590.670466476561, 6591.670416479061, 6389.456896163692, 6430.64631445275, 6513.316101809241, 6528.086067950586, 6530.67346632657, 6500.3174666659315, 6558.376954189344, 6559.508036897157, 6569.770058047945, 6583.80248592543, 6355.269144048493, 6370.108184854032, 6386.425221729881, 6410.647414392253, 6482.189726284215, 6323.08315294289, 6414.390632889868, 6521.608703477807, 6553.770618028346, 6643.933560664332, 6253.187085678833, 6327.050942358499, 6328.651924144032, 6341.460975817107, 6343.270523889812], "value": 6049.37169209685, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [826.6114925984785, 876.2902049340036, 903.4488961733341, 906.9455832650042, 1188.1089183112665, 30349.0, 31850.68149318506, 42521.362179567295, 42851.78574107125, 43069.35395969063, 42190.57809421905, 42607.14785704284, 42645.78677106618, 42875.785621071926, 43126.353104703456, 7716.382689384835, 7806.375489960804, 7874.212578742137, 7901.09137449195, 7935.444518883681, 7676.501027433227, 7790.610469476504, 7795.766127016199, 7804.063512378522, 7806.60966951653, 7766.145723970359, 7813.609319534029, 7832.451728378987, 7859.764207073768, 7864.095629002681, 7805.180456052118, 7832.334251588622, 7838.451308408414, 7845.215478452138, 7849.45053846231, 7751.573663448508, 7780.961095194544, 7796.298333149987, 7808.726694565663, 7810.453268271223, 37360.43959340612, 37455.81272093649, 37563.81218093892, 37692.0, 37721.62278377209, 3391.0, 3427.8971630850865, 3429.7770644908, 3436.9656303436896, 3481.9651803481893, 30474.39051218963, 30493.08520744359, 30528.0, 30799.230019249473, 30854.22864428385, 4810.278458231258, 4873.926891096601, 4879.170541008037, 4880.267959806022, 4912.508749125094, 184634.0, 186081.0, 186572.06713966478, 187193.0, 187526.0, 48661.0, 48697.756511217216, 48842.848357889976, 49053.0, 49603.0, 51156.48843511592, 51402.0, 51474.0, 51477.48522514802, 51588.48411515837, 13769.931150344184, 15198.696026079637, 15478.690426191639, 15499.070055796688, 15515.689686206213, 11424.200305978668, 12118.212316199419, 12251.509939602316, 12256.44845981933, 12455.377231138407, 14293.85706142946, 14353.712925741427, 14382.137071775727, 14480.493182738554, 14620.634484137981, 5862.501687356623, 5897.911531327033, 5975.0, 6036.0, 6056.757729690851, 979.588572799426, 983.4246965525248, 989.2679417231341, 1012.6101450941441, 1023.1456733627356, 21919.67120493194, 21942.122315107215, 22065.227717029826, 22276.777232227796, 22362.55274894493, 1086.3617823496031, 1092.7978324010085, 1108.224243029866, 1112.3993043756282, 1120.3669926491507, 11483.196176267747, 11486.540538378538, 11699.415029248503, 11760.941195293968, 11779.234349767408, 224089.0, 225078.0, 226347.0, 227647.0, 228314.0, 53388.0, 53455.0, 53558.196627050624, 53570.46429535655, 53832.73083634557, 1468.221842423496, 1472.2859413184597, 1472.8968972171845, 1485.5469081930114, 1502.2413681091198, 21793.4551636204, 21900.45248868791, 22100.66848997234, 22170.44573885666, 26538.469230614894, 50298.0, 50765.0, 50847.0, 50913.0, 50998.745006276215, 199683.0, 201341.0, 206858.0, 212731.0, 213023.0, 32053.51919721253, 32343.0, 35015.47476787901, 35295.64704353026, 35859.10352241215, 47005.764971174925, 47453.76273118612, 47461.762691186326, 47731.0, 47950.0, 26974.865125674245, 28261.717382825907, 28529.42941141124, 28652.14043578696, 29295.85352073226, 25512.617310740727, 27421.725782742717, 27422.45155096927, 27562.173134805988, 27927.720722792514, 35295.0, 36199.63800361963, 36416.81791591025, 36520.45219321659, 36666.63333366632, 30955.845220774652, 31440.685593143775, 32427.67572324341, 32862.83568582142, 33051.83474082614, 23877.04491820308, 23896.283111506684, 24076.03695852146, 24237.63643545313, 24276.393090172885], "value": 17150.837715010803, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.3780841074815, 571.3857603076693, 579.5624303650745, 593.8634114153745, 601.608954179783, 21684.89157554212, 22238.332850014485, 22883.19908803192, 22887.771122288752, 22897.313080607608, 24863.25410237696, 25793.61309580358, 26812.731872681266, 26868.193954181355, 26977.190684279452, 7034.6482675866255, 7169.46229032822, 7210.927890721105, 7223.02489163964, 7226.494145409823, 6723.462123030145, 6819.897701534481, 6850.212225594046, 7015.298470152995, 7104.715811367539, 7011.404030657375, 7056.294370562954, 7087.89368159478, 7089.858202835941, 7091.503594748369, 7085.149782026138, 7094.822629434279, 7111.4666400020205, 7112.0, 7119.181294151187, 6861.828454288633, 6880.346367095168, 6891.793246202572, 6894.069300644449, 6953.860922781567, 30435.69564304351, 31090.0, 32452.675473245203, 32932.83533582341, 33055.83472082648, 1915.789263181049, 1954.3648314297768, 1959.8628096033283, 1961.9803801961941, 1969.8227159555565, 19956.600867982703, 20468.181272749072, 20616.38150855477, 20640.69038964417, 20715.89642051795, 2460.3726049857173, 2480.672840030575, 2551.0561092395933, 2577.252596746941, 2578.1492107604445, 138906.0, 140712.0, 142963.2851835734, 143209.0, 145103.0, 19598.510037248896, 21419.357419277447, 21636.45908852291, 22358.55282894333, 22511.0, 22211.66682499764, 23891.16380926694, 23902.88048559746, 23947.0, 43636.12727745491, 10270.897291026995, 10366.637167699095, 10485.423301718478, 10553.525091370906, 10699.304545204537, 9763.658271960447, 9819.116279534876, 9891.851622225673, 10003.599856005678, 10016.64941727036, 9232.230330900837, 9256.398334108397, 9382.62469501226, 9584.233261339075, 9760.170385517169, 2552.7579973433635, 2589.5468293048643, 2653.389720364327, 2669.7864170866674, 2760.3651160232857, 933.7245512573807, 938.957746901391, 939.6429356844355, 960.9471479068578, 966.8018056298442, 18844.246230150642, 19052.047397630064, 19419.2232310706, 20157.193712251345, 23594.0, 979.7844474215661, 997.6332424578374, 1015.4567306490992, 1026.7381817636604, 1030.7062487191115, 10600.522976466078, 10718.73203169927, 10870.945645271722, 11156.721081973015, 12833.358332083359, 506327.0, 515164.0, 516573.0, 518744.0, 529247.0, 123976.0, 125490.0, 126268.0, 127568.36215819047, 127780.0, 1213.4963989944251, 1221.1512998466158, 1255.5856567332942, 1257.867923867999, 1266.322517453158, 19953.401397958085, 20955.580888381843, 21101.155953761678, 21353.6796948049, 22273.777262227817, 56791.0, 57097.42902571087, 57511.7124414392, 60177.69911150416, 60411.0, 63730.68134659297, 67719.0, 68286.65856670684, 68349.65825170842, 69026.65486672535, 15112.0, 15291.847081529488, 15381.3078411474, 15402.075875447508, 15421.922890385476, 23294.65058024097, 23688.763112369346, 23955.281341559083, 23991.880040600383, 25244.990200391778, 18601.906990464962, 19577.608447831248, 19578.314758983935, 19693.507662308555, 19867.801321986593, 15065.548033558573, 15520.689586207987, 15780.92109539491, 15975.8402415979, 16703.413175748283, 19973.600527989067, 20119.899400502905, 20194.29319973794, 20350.0, 20419.59160816805, 14426.278686065865, 14640.853591464374, 14668.046576972463, 14704.485343012942, 14729.92635036818, 18084.819151808315, 18513.0, 18890.6221875559, 19093.33173338982, 19094.61810763805], "value": 12578.142551258328, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.7041650836816, 1368.9794653080205, 1381.28173349858, 1384.750744865925, 1403.151093588379, 13475.932620336896, 13757.0, 14093.647658808535, 14127.434902603896, 14144.434222631082, 14131.717365652681, 14380.137191768501, 14412.639684007905, 14477.348519316629, 14604.780928286084, 2615.8299710518804, 2673.104509989146, 2676.745709157627, 2681.048227879104, 2681.919542413721, 2496.5755821510297, 2652.1910817200746, 2698.8245764025273, 2704.62135301058, 2706.052881491472, 2608.5304645163865, 2630.8421494710283, 2634.157069737675, 2671.1719366996194, 2674.745899139579, 2560.2063360358256, 2571.305747448197, 2574.652421923035, 2576.6135079738096, 2579.1359894435386, 2488.1664642344836, 2509.372656835791, 2530.1523989463553, 2545.516351893153, 2545.5672535668796, 13196.14225075367, 13452.596422107352, 13489.0, 13521.121127126706, 13522.391492382769, 7064.894026589605, 7354.44841636874, 7356.043714317158, 7379.88930166048, 7395.223501532316, 4832.1543729847135, 4860.3681521401995, 4898.387701537308, 5036.118679231156, 5046.243063540462, 1678.5048410718866, 1692.3738216859717, 1710.6835235481476, 1751.098184435017, 1758.5515693498205, 22548.21081262148, 22736.31791046255, 22770.20304289342, 22836.4290892729, 22896.65655015176, 6173.228346456693, 6280.654563998933, 6360.459360954371, 6376.39424254695, 6537.0, 15232.466863659718, 15240.618984525254, 15250.847491525165, 15274.770878436831, 15279.541613751606, 6893.034975103489, 6961.40828029613, 7044.471664625174, 7076.575405475688, 7091.822704432328, 5488.313960754906, 6428.325025872309, 6745.08941292929, 6804.455643548505, 6804.965975170093, 4934.703917764946, 4993.226049962189, 5010.1800471077095, 5023.824166154167, 5070.923936140961, 1418.1491105336922, 1426.2369632246698, 1429.7783843504274, 1436.289036926728, 1457.883369330451, 819.5533434278333, 823.9052508961546, 840.2941529115573, 856.9400141990001, 877.8507653698758, 6518.804435866837, 6704.597724136567, 6843.3840954314355, 6889.104416425933, 7022.54353467023, 928.6146249306597, 940.1350757303297, 955.1022039283051, 965.9613615455444, 978.4325091446943, 4424.7123936944, 4536.5236650151255, 4550.13547425988, 4551.840685575989, 4662.813487460464, 18182.363617273597, 18620.255189792257, 18786.342478013205, 18910.338138165098, 18961.336353227573, 17067.231974561175, 18364.632707346045, 18668.906655466635, 18857.717134242997, 18898.244070237037, 10767.6231331903, 11045.723856903642, 11055.281406708538, 11115.221934464671, 11117.166212534092, 7054.118235220598, 7064.682089305891, 7179.676914538963, 7291.453141014551, 7369.152547456896, 16415.91792041032, 16637.750433743746, 16641.41755038568, 16661.666766664843, 16798.58003549921, 15522.611934701723, 16621.916890415472, 16756.916215419333, 17090.2309396075, 17462.47612571625, 10398.168146548409, 10652.360858348524, 10790.946045269724, 10859.565617375216, 11099.223054386439, 12119.151659384112, 12217.633470995885, 12233.816492752794, 12302.815457767962, 12310.322932238454, 9669.516524173903, 9680.661176858775, 9845.753856153653, 10113.64602238918, 10137.391756494633, 9121.908780912107, 9297.651840482957, 9357.906420935977, 9593.0, 9691.2731545133, 8844.73465796027, 8970.506622135845, 8973.237274831712, 8980.775480613036, 8988.146126118008, 6191.659458729633, 6235.220597425322, 6581.308962558863, 6600.2409722882485, 6644.767433139817, 6249.0, 6627.436667883091, 6640.900386494303, 6685.331466853276, 6812.284710105368], "value": 6089.402347402368, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.4253066442927, 497.6441844081482, 521.0854949563516, 527.4172039895915, 528.7858417340973, 6174.289956654986, 6180.443760061594, 6188.319284878659, 6198.132261483396, 6200.658963756991, 5794.68129252891, 5976.402359764033, 5992.370801065891, 6029.69851507425, 6047.667378294192, 4795.664303498756, 4801.743180705081, 4826.734529600871, 4835.758212089399, 4862.465128835825, 4681.485036645983, 4790.784414701329, 4813.0, 4858.319835223072, 4895.681780684244, 4747.8338258160975, 4781.163296423129, 4801.711897286156, 4817.0365926814775, 4841.2496063110275, 4728.432588089434, 4776.617870570346, 4780.80876764929, 4813.66304358695, 4868.14807408702, 4334.176506463793, 4338.2191205582985, 4374.956250437487, 4397.714148580332, 4402.537733537996, 5940.37626049263, 5948.910766338508, 5990.610610310316, 5997.421108923161, 6042.335343112254, 5599.132134519156, 5624.831255062355, 5627.493525582676, 5647.124695672177, 5676.943230567724, 6285.937140628581, 6423.582467139668, 6460.709268082947, 6489.870202595922, 6493.93506064938, 4762.499937506546, 4817.132916075105, 4822.445418776851, 4851.466338702739, 4855.174620314555, 6743.966280168617, 6767.661616919135, 6818.965905170492, 6820.181578210547, 6830.652980039745, 4839.782209800497, 4858.392700912386, 4891.0, 4904.337914381585, 4934.555889969848, 4906.901861962812, 4912.312276281252, 4924.285978533093, 4955.0, 4958.330625365602, 5581.274434323594, 5597.244372009808, 5598.356189038232, 5612.1581762735095, 5656.802011929645, 6368.203974503187, 6396.392342727433, 6419.775307864203, 6464.612123272619, 6477.902831457532, 1650.1336798180812, 1697.9660406791795, 1699.7450382442732, 1716.6824137534595, 1752.7029997801535, 1176.805827038529, 1210.2073142091945, 1213.6177104212145, 1222.9299363057326, 1229.9877001229872, 5601.859953501195, 5620.887582248414, 5696.946064977992, 5732.484076433057, 5737.77048918047, 6432.64914367988, 6451.9354806452275, 6483.3516648335735, 6548.574342667712, 6570.193196870803, 5673.971630141823, 5674.0, 5692.829215123553, 5699.145128230715, 5756.65460072397, 3481.5473988381855, 3488.8430020648625, 3508.4035713928693, 3509.9298014039578, 3551.3430015446706, 6381.649009304533, 6421.422072013448, 6455.9677201613695, 6456.322086180883, 6486.902696459557, 6718.294579069225, 6770.0, 6828.760993365208, 6846.349596788297, 6874.9656251718425, 6337.334579869047, 6359.618422894456, 6392.0, 6430.164078669743, 6461.25695545009, 6547.770828020996, 6648.468122550281, 6652.700628471827, 6659.234188068434, 6679.298673639392, 6497.317781632861, 6560.212774467094, 6586.47308215332, 6598.769043083469, 6640.136782218281, 6440.876175055001, 6443.903341449788, 6456.322086180883, 6457.644829534421, 6490.253620833664, 4871.19625261842, 4873.4882837301575, 4891.682040667345, 4903.803847846046, 4908.533689299511, 6620.8344791380605, 6648.800535983928, 6690.531662783558, 6697.263301036979, 6708.765193218214, 6425.293217746138, 6492.707828147839, 6498.480121590167, 6528.151340325728, 6551.344865513411, 6233.657148856675, 6298.149749783721, 6324.272708638566, 6331.335209803088, 6338.429541341301, 6022.939770602238, 6196.366544218779, 6276.089966954857, 6324.367563243639, 6342.809715708536, 6407.302064952698, 6471.091028146614, 6482.7634466111, 6485.0, 6501.3823686751575, 6287.811365659038, 6317.483803887126, 6320.178376810985, 6344.492440604648, 6365.458935990493], "value": 4964.0640048158375, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [876.9912300876989, 906.0, 919.4621146629222, 919.862020696895, 923.6859467780953, 18084.909575452122, 19062.332818351362, 19251.711224331648, 19321.71017434736, 20256.594868102628, 18955.0, 18979.33572324969, 19063.0, 19075.236990520363, 19083.141258643358, 951.1154626197633, 1007.9848802267971, 1036.9585216591327, 1054.2514814481726, 1060.2472244706269, 1050.1756121444666, 1050.5167622893478, 1051.2956319266073, 1056.6037735849056, 1061.2836335473523, 1009.1633227525906, 1064.7604289034982, 1065.2702898514503, 1070.9357438553711, 1086.5816660585692, 1034.4207243943415, 1040.8126537223297, 1040.885502594714, 1043.57213542448, 1053.5364439646569, 772.0619447371471, 774.4346626962349, 774.8217909880759, 776.5263189454429, 782.3858271257093, 19004.904975475034, 19228.71156932647, 19319.227230910747, 19331.710024349646, 19987.90006049975, 19661.705074423753, 19961.201551937906, 20309.0, 20381.286654967153, 20429.79570204294, 15420.306086226146, 16240.431584894472, 16331.18344082803, 16388.508344749556, 16393.83606163947, 3469.5316132322064, 3484.6863782259466, 3490.127468132967, 3492.3539145257955, 3497.2131270464197, 123526.38236808848, 126436.0, 128364.35817820851, 129067.0, 131933.0, 15360.385584576716, 16125.0, 16190.838091619169, 16221.675566488604, 16227.350905963865, 27489.17532474029, 29149.125526234246, 29166.854165729037, 29202.707972920423, 29240.85379573131, 7863.960680196562, 7942.920570794334, 8036.6785328587375, 8044.839103217902, 8059.395545334123, 3570.285942811448, 3703.3519134151416, 3708.313961917054, 4059.5331536873637, 4167.208230436268, 4935.457099719028, 5038.168702164176, 5043.142665746832, 5045.369328833895, 5063.113955057924, 937.381328323317, 966.7873067925045, 975.317277905469, 980.3235767320526, 983.331334692416, 626.2516293029877, 643.3116565275204, 643.4659232836734, 666.6633350158139, 683.8119517132728, 11381.772364552828, 11611.245269057485, 11632.243904146204, 12021.819672704916, 12210.450529726006, 659.9043138744855, 686.7802303263007, 688.1948120698794, 694.0838093716347, 702.2029995954629, 5203.635745497779, 5267.920981185285, 5286.735663216824, 5319.494648008433, 5527.695976721319, 92188.53905730562, 93697.53151234337, 96405.5179724111, 98749.0, 99732.0, 86292.0, 87918.56040719755, 88212.0, 89458.55270723604, 93990.0, 70717.0, 72995.0, 73783.0, 76414.61792691112, 79356.6032169847, 10368.688939331832, 10695.839562406414, 10795.352278863293, 11113.277636953571, 11134.888651113386, 32264.35471290608, 33171.83414082914, 34403.65596344005, 35040.12399689926, 35432.0, 42199.15601687888, 43317.566824331356, 43459.0, 44046.559534404245, 44548.33177502275, 13730.176189428666, 13824.723505530033, 13861.653458663613, 13918.58244252674, 14115.717685646436, 15732.60668483297, 15780.210989450712, 15846.049237045814, 15988.520344389686, 17483.475495735147, 15208.391664333303, 17931.910340448216, 18155.092245387412, 18239.452816415527, 18549.81450185481, 16313.673726525165, 16527.421540246032, 16530.66938661244, 16657.50027499177, 16849.15754212309, 17886.195121219836, 18153.727694084864, 18640.533986650444, 19022.04889755479, 19243.326483573008, 12462.813057803958, 12556.0, 12776.48894044265, 12901.032422568158, 13150.21098734079, 14006.929965350108, 14528.0, 14716.19060951615, 14810.851891481378, 14815.25923703832], "value": 8573.531654692806, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1568.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 109.0, 109.0, 109.0, 110.0, 110.0, 109.0, 102.0, 109.0, 109.0], "value": 107.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 126.0, 153.0, 178.0, 121.0, 150.0, 151.0, 123.0, 168.0, 120.0], "value": 144.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 166.0, 168.0, 165.0, 164.0, 166.0, 168.0, 171.0, 173.0, 168.0], "value": 168.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 74.0, 171.0, 77.0, 77.0, 76.0, 95.0, 96.0, 76.0], "value": 89.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 132.0, 79.0, 78.0, 79.0, 126.0, 79.0, 79.0, 141.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 116.0, 114.0, 114.0, 114.0, 114.0, 115.0, 121.0, 115.0, 115.0], "value": 115.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 167.0, 151.0, 158.0, 150.0, 151.0, 155.0, 153.0, 152.0, 152.0], "value": 154.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 101.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 57.0, 57.0, 58.0, 56.0, 63.0, 57.0, 59.0, 66.0], "value": 58.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 61.0, 55.0, 54.0, 55.0, 54.0, 55.0, 54.0, 57.0], "value": 55.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 94.0, 80.0, 80.0, 81.0, 96.0, 82.0, 83.0, 84.0, 82.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 131.0, 128.0, 140.0, 131.0, 130.0, 130.0, 132.0, 148.0, 180.0], "value": 138.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 187.0, 185.0, 187.0, 184.0, 202.0, 189.0, 189.0, 185.0, 184.0], "value": 188.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 76.0, 75.0, 69.0, 72.0, 68.0, 69.0, 79.0, 69.0, 67.0], "value": 71.4, "unit": "score"}]}]} 10:09:16 INFO - Return code: 0 10:09:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:09:16 INFO - # TBPL SUCCESS # 10:09:16 INFO - Running post-action listener: _package_coverage_data 10:09:16 INFO - Running post-action listener: _resource_record_post_action 10:09:16 INFO - [mozharness: 2017-07-31 17:09:16.699318Z] Finished run-tests step (success) 10:09:16 INFO - Running post-run listener: _resource_record_post_run 10:09:16 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 434860032; Read time: 12; Write time: 342032 10:09:16 INFO - TinderboxPrint: CPU usage
13.2% 10:09:16 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 10:09:16 INFO - TinderboxPrint: I/O write bytes / time
434,860,032 / 342,032 10:09:16 INFO - TinderboxPrint: CPU idle
8,267.7 (86.8%) 10:09:16 INFO - TinderboxPrint: CPU user
1,206.7 (12.7%) 10:09:16 INFO - TinderboxPrint: Swap in / out
0 / 0 10:09:16 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 107192320; Read time: 0; Write time: 85880 10:09:16 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:09:16 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 324743168; Read time: 0; Write time: 255588 10:09:17 INFO - Running post-run listener: _upload_blobber_files 10:09:17 INFO - Blob upload gear active. 10:09:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:09:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:09:17 INFO - (blobuploader) - INFO - Open directory for files ... 10:09:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:09:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:09:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:18 INFO - (blobuploader) - INFO - Done attempting. 10:09:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:09:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:09:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:19 INFO - (blobuploader) - INFO - Done attempting. 10:09:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:09:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:09:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:27 INFO - (blobuploader) - INFO - Done attempting. 10:09:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:09:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:09:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:28 INFO - (blobuploader) - INFO - Done attempting. 10:09:28 INFO - (blobuploader) - INFO - Iteration through files over. 10:09:28 INFO - Return code: 0 10:09:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:09:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:09:28 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4e9a4a6c0915018739712a96feeca8299287b68e7d536ca909b9be3f508a360a1e70abb21fdbc7549c0446557b645bc7cfb61becfdae01d64aca4fcd3020756", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99a0c4183de761d9c44870f0aec141bc778011c4fe91e52bbe93135c359e5cef1a6039a3f1bd07dc45fd0d970679207bedbb7994707902a2183bc77299c97845", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9052d1e9392079909e0d51746fce44dfee1a2954ea267e1b1b7c5880ce2e31cde6c35a2665c92a5ad38258031467289b7979e4f97b2bce2506b05b7abb34f1a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/077c4b6537f93da4bec8762cb51f59e5ed149ed077e2b4ac31e5474178ded612f84e45a995fc73669bf2934028241fb9cecba04ee5490f90e7870a9204261fa0"} 10:09:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:09:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:09:28 INFO - Contents: 10:09:28 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4e9a4a6c0915018739712a96feeca8299287b68e7d536ca909b9be3f508a360a1e70abb21fdbc7549c0446557b645bc7cfb61becfdae01d64aca4fcd3020756", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99a0c4183de761d9c44870f0aec141bc778011c4fe91e52bbe93135c359e5cef1a6039a3f1bd07dc45fd0d970679207bedbb7994707902a2183bc77299c97845", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9052d1e9392079909e0d51746fce44dfee1a2954ea267e1b1b7c5880ce2e31cde6c35a2665c92a5ad38258031467289b7979e4f97b2bce2506b05b7abb34f1a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/077c4b6537f93da4bec8762cb51f59e5ed149ed077e2b4ac31e5474178ded612f84e45a995fc73669bf2934028241fb9cecba04ee5490f90e7870a9204261fa0"} 10:09:28 INFO - Running post-run listener: copy_logs_to_upload_dir 10:09:28 INFO - Copying logs to upload dir... 10:09:28 INFO - mkdir: /builds/slave/test/build/upload/logs 10:09:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.755021 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-07-31 10:09:28.598139) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-31 10:09:28.601613) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545 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/b4e9a4a6c0915018739712a96feeca8299287b68e7d536ca909b9be3f508a360a1e70abb21fdbc7549c0446557b645bc7cfb61becfdae01d64aca4fcd3020756", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99a0c4183de761d9c44870f0aec141bc778011c4fe91e52bbe93135c359e5cef1a6039a3f1bd07dc45fd0d970679207bedbb7994707902a2183bc77299c97845", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9052d1e9392079909e0d51746fce44dfee1a2954ea267e1b1b7c5880ce2e31cde6c35a2665c92a5ad38258031467289b7979e4f97b2bce2506b05b7abb34f1a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/077c4b6537f93da4bec8762cb51f59e5ed149ed077e2b4ac31e5474178ded612f84e45a995fc73669bf2934028241fb9cecba04ee5490f90e7870a9204261fa0"} build_url:https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011310 build_url: 'https://queue.taskcluster.net/v1/task/GJIFbv4yTaKDTYEmSbl0jw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4e9a4a6c0915018739712a96feeca8299287b68e7d536ca909b9be3f508a360a1e70abb21fdbc7549c0446557b645bc7cfb61becfdae01d64aca4fcd3020756", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99a0c4183de761d9c44870f0aec141bc778011c4fe91e52bbe93135c359e5cef1a6039a3f1bd07dc45fd0d970679207bedbb7994707902a2183bc77299c97845", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9052d1e9392079909e0d51746fce44dfee1a2954ea267e1b1b7c5880ce2e31cde6c35a2665c92a5ad38258031467289b7979e4f97b2bce2506b05b7abb34f1a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/077c4b6537f93da4bec8762cb51f59e5ed149ed077e2b4ac31e5474178ded612f84e45a995fc73669bf2934028241fb9cecba04ee5490f90e7870a9204261fa0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-31 10:09:28.629394) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-31 10:09:28.629993) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1501519634.740072-424508545 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-31 10:09:28.713623) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-31 10:09:28.713984) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-31 10:09:28.714328) ========= ========= Total master_lag: 0.19 =========