builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-084 starttime: 1502671640.34 results: success (0) revision: 3bfcbdf5c6c381d5a8febb5c209e27a69fb89f9b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-13 17:47:20.340753) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-13 17:47:20.341614) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-13 17:47:20.341933) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-13 17:47:20.390720) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-13 17:47:20.390993) ========= 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=ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-13 17:47:21-- 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% 92.1M=0s 2017-08-13 17:47:21 (92.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.159256 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-13 17:47:20.563817) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-13 17:47:20.564157) ========= 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=ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019928 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-13 17:47:20.613420) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-13 17:47:20.613737) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3bfcbdf5c6c381d5a8febb5c209e27a69fb89f9b --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 3bfcbdf5c6c381d5a8febb5c209e27a69fb89f9b --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=ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-13 17:47:21,709 truncating revision to first 12 chars 2017-08-13 17:47:21,709 Setting DEBUG logging. 2017-08-13 17:47:21,709 attempt 1/10 2017-08-13 17:47:21,709 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3bfcbdf5c6c3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-13 17:47:22,162 unpacking tar archive at: mozilla-central-3bfcbdf5c6c3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.773515 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-13 17:47:21.401229) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-13 17:47:21.401596) ========= script_repo_revision: 3bfcbdf5c6c381d5a8febb5c209e27a69fb89f9b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-13 17:47:21.402001) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-13 17:47:21.402268) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-13 17:47:21.410635) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-13 17:47:21.410989) ========= /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=ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:47:22 INFO - MultiFileLogger online at 20170813 17:47:22 in /builds/slave/test 17:47:22 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 17:47:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:47:22 INFO - {'append_to_log': False, 17:47:22 INFO - 'base_work_dir': '/builds/slave/test', 17:47:22 INFO - 'blob_upload_branch': 'Firefox', 17:47:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:47:22 INFO - 'branch': 'Firefox', 17:47:22 INFO - 'buildbot_json_path': 'buildprops.json', 17:47:22 INFO - 'code_coverage': False, 17:47:22 INFO - 'config_files': ('talos/linux_config.py',), 17:47:22 INFO - 'default_actions': ('clobber', 17:47:22 INFO - 'read-buildbot-config', 17:47:22 INFO - 'download-and-extract', 17:47:22 INFO - 'populate-webroot', 17:47:22 INFO - 'create-virtualenv', 17:47:22 INFO - 'install', 17:47:22 INFO - 'setup-mitmproxy', 17:47:22 INFO - 'run-tests'), 17:47:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:47:22 INFO - 'disable_ccov_upload': False, 17:47:22 INFO - 'download_minidump_stackwalk': True, 17:47:22 INFO - 'download_symbols': 'ondemand', 17:47:22 INFO - 'enable_stylo': False, 17:47:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:47:22 INFO - 'tooltool.py': '/tools/tooltool.py', 17:47:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:47:22 INFO - '/tools/misc-python/virtualenv.py')}, 17:47:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:47:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:47:22 INFO - 'gecko_profile': False, 17:47:22 INFO - 'gecko_profile_interval': 0, 17:47:22 INFO - 'installer_path': 'installer.exe', 17:47:22 INFO - 'log_level': 'info', 17:47:22 INFO - 'log_name': 'talos', 17:47:22 INFO - 'log_to_console': True, 17:47:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:47:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:47:22 INFO - 'opt_config_files': (), 17:47:22 INFO - 'pip_index': False, 17:47:22 INFO - 'suite': 'dromaeojs-e10s', 17:47:22 INFO - 'system_bits': '32', 17:47:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:47:22 INFO - 'title': 'talos-linux64-ix-084', 17:47:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:47:22 INFO - 'use_talos_json': True, 17:47:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:47:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:47:22 INFO - 'work_dir': 'build'} 17:47:22 INFO - [mozharness: 2017-08-14 00:47:22.603502Z] Running clobber step. 17:47:22 INFO - Running pre-action listener: _resource_record_pre_action 17:47:22 INFO - Running main action method: clobber 17:47:22 INFO - rmtree: /builds/slave/test/build 17:47:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:47:23 INFO - Running post-action listener: _resource_record_post_action 17:47:23 INFO - [mozharness: 2017-08-14 00:47:23.624504Z] Finished clobber step (success) 17:47:23 INFO - [mozharness: 2017-08-14 00:47:23.624606Z] Running read-buildbot-config step. 17:47:23 INFO - Running pre-action listener: _resource_record_pre_action 17:47:23 INFO - Running main action method: read_buildbot_config 17:47:23 INFO - Using buildbot properties: 17:47:23 INFO - { 17:47:23 INFO - "project": "", 17:47:23 INFO - "product": "firefox", 17:47:23 INFO - "who": "archaeopteryx@coole-files.de", 17:47:23 INFO - "installer_path": "public/build/target.tar.bz2", 17:47:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 17:47:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:47:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 17:47:23 INFO - "stage_platform": "linux64-pgo", 17:47:23 INFO - "basedir": "/builds/slave/test", 17:47:23 INFO - "buildnumber": 118, 17:47:23 INFO - "platform": "ubuntu64_hw", 17:47:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:47:23 INFO - "slavebuilddir": "test", 17:47:23 INFO - "taskId": "aKW_DtQtQDWm12M64vkX_g", 17:47:23 INFO - "branch": "mozilla-central", 17:47:23 INFO - "script_repo_revision": "production", 17:47:23 INFO - "revision": "3bfcbdf5c6c381d5a8febb5c209e27a69fb89f9b", 17:47:23 INFO - "slavename": "talos-linux64-ix-084", 17:47:23 INFO - "repo_path": "mozilla-central" 17:47:23 INFO - } 17:47:23 INFO - Finding installer, test and symbols from parent task. 17:47:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aKW_DtQtQDWm12M64vkX_g'}, attempt #1 17:47:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aKW_DtQtQDWm12M64vkX_g'}, attempt #1 17:47:24 INFO - Task dependencies: HWeJi78SRdSP1Ky9h0TZhA 17:47:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA'}, attempt #1 17:47:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.tar.bz2 17:47:24 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.test_packages.json 17:47:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.crashreporter-symbols.zip 17:47:24 INFO - Running post-action listener: _resource_record_post_action 17:47:24 INFO - [mozharness: 2017-08-14 00:47:24.990451Z] Finished read-buildbot-config step (success) 17:47:24 INFO - [mozharness: 2017-08-14 00:47:24.990671Z] Running download-and-extract step. 17:47:24 INFO - Running pre-action listener: _resource_record_pre_action 17:47:24 INFO - Running main action method: download_and_extract 17:47:24 INFO - mkdir: /builds/slave/test/build/tests 17:47:24 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')]} 17:47:24 INFO - trying https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.test_packages.json 17:47:24 INFO - Downloading https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:47:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:47:25 INFO - Downloaded 989 bytes. 17:47:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:47:25 INFO - Using the following test package requirements: 17:47:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:47:25 INFO - u'common': [u'target.common.tests.zip'], 17:47:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:47:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:47:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:47:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:47:25 INFO - u'mozbase': [u'target.common.tests.zip'], 17:47:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:47:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:47:25 INFO - u'web-platform': [u'target.common.tests.zip', 17:47:25 INFO - u'target.web-platform.tests.tar.gz'], 17:47:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:47:25 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:47:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.common.tests.zip 17:47:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:47:25 INFO - Fetch https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.common.tests.zip into memory 17:47:27 INFO - Content-Length response header: 54650557 17:47:27 INFO - Bytes received: 54650557 17:47:38 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:47:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.common.tests.zip 17:47:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:47:38 INFO - Fetch https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.common.tests.zip into memory 17:47:39 INFO - Content-Length response header: 54650557 17:47:39 INFO - Bytes received: 54650557 17:47:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.talos.tests.zip 17:47:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:47:51 INFO - Fetch https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.talos.tests.zip into memory 17:47:52 INFO - Content-Length response header: 14496050 17:47:52 INFO - Bytes received: 14496050 17:47:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:47:53 INFO - trying https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.tar.bz2 17:47:53 INFO - Downloading https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:47:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:47:54 INFO - Downloaded 62760938 bytes. 17:47:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.tar.bz2 17:47:54 INFO - mkdir: /builds/slave/test/properties 17:47:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:47:54 INFO - Writing to file /builds/slave/test/properties/build_url 17:47:54 INFO - Contents: 17:47:54 INFO - build_url:https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.tar.bz2 17:47:54 INFO - Running post-action listener: _resource_record_post_action 17:47:54 INFO - Running post-action listener: set_extra_try_arguments 17:47:54 INFO - [mozharness: 2017-08-14 00:47:54.789685Z] Finished download-and-extract step (success) 17:47:54 INFO - [mozharness: 2017-08-14 00:47:54.789782Z] Running populate-webroot step. 17:47:54 INFO - Running pre-action listener: _resource_record_pre_action 17:47:54 INFO - Running main action method: populate_webroot 17:47:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:47:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:47:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:47:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:47:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:47:54 INFO - 'tests': ['damp', 'tps']}, 17:47:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:47:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:47:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:47:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:47:54 INFO - 'other-e10s': {'tests': ['a11yr', 17:47:54 INFO - 'ts_paint', 17:47:54 INFO - 'tpaint', 17:47:54 INFO - 'sessionrestore', 17:47:54 INFO - 'sessionrestore_many_windows', 17:47:54 INFO - 'sessionrestore_no_auto_restore', 17:47:54 INFO - 'tabpaint']}, 17:47:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 17:47:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 17:47:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:47:54 INFO - 'tsvgr_opacity', 17:47:54 INFO - 'tart', 17:47:54 INFO - 'tscrollx', 17:47:54 INFO - 'tsvg_static']}, 17:47:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:47:54 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:47:54 INFO - 'talos_options': ['--mitmproxy', 17:47:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:47:54 INFO - '--firstNonBlankPaint'], 17:47:54 INFO - 'tests': ['tp6_google', 17:47:54 INFO - 'tp6_youtube', 17:47:54 INFO - 'tp6_amazon', 17:47:54 INFO - 'tp6_facebook']}, 17:47:54 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:47:54 INFO - 'talos_options': ['--stylo', 17:47:54 INFO - '--mitmproxy', 17:47:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:47:54 INFO - '--firstNonBlankPaint'], 17:47:54 INFO - 'tests': ['tp6_google', 17:47:54 INFO - 'tp6_youtube', 17:47:54 INFO - 'tp6_amazon', 17:47:54 INFO - 'tp6_facebook']}, 17:47:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:47:54 INFO - 'talos_options': ['--stylo', 17:47:54 INFO - '--stylo-threads=1', 17:47:54 INFO - '--mitmproxy', 17:47:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:47:54 INFO - '--firstNonBlankPaint'], 17:47:54 INFO - 'tests': ['tp6_google', 17:47:54 INFO - 'tp6_youtube', 17:47:54 INFO - 'tp6_amazon', 17:47:54 INFO - 'tp6_facebook']}, 17:47:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:47:54 INFO - 'talos_options': ['--xperf_path', 17:47:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:47:54 INFO - 'tests': ['tp5n']}}} 17:47:54 INFO - Running post-action listener: _resource_record_post_action 17:47:54 INFO - [mozharness: 2017-08-14 00:47:54.798210Z] Finished populate-webroot step (success) 17:47:54 INFO - [mozharness: 2017-08-14 00:47:54.798300Z] Running create-virtualenv step. 17:47:54 INFO - Running pre-action listener: _resource_record_pre_action 17:47:54 INFO - Running main action method: create_virtualenv 17:47:54 INFO - Creating virtualenv /builds/slave/test/build/venv 17:47:54 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 17:47:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:47:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:47:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:47:54 INFO - Using real prefix '/usr' 17:47:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:47:55 INFO - Installing distribute.............................................................................................................................................................................................done. 17:47:57 INFO - Installing pip.................done. 17:47:57 INFO - Return code: 0 17:47:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:47:57 INFO - Reading from file tmpfile_stdout 17:47:57 INFO - Output received: 17:47:57 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) 17:47:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:47:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:47:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218db20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2190f00>, '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 0x218bba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218c050>, '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': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', '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 17:47:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:47:57 INFO - Using env: {'DISPLAY': ':0', 17:47:57 INFO - 'HOME': '/home/cltbld', 17:47:57 INFO - 'LANG': 'en_US.UTF-8', 17:47:57 INFO - 'LANGUAGE': 'en_US:en', 17:47:57 INFO - 'LOGNAME': 'cltbld', 17:47:57 INFO - 'MAIL': '/var/mail/cltbld', 17:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:57 INFO - 'MOZ_NO_REMOTE': '1', 17:47:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:47:57 INFO - 'NO_EM_RESTART': '1', 17:47:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:47:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:57 INFO - 'PWD': '/builds/slave/test', 17:47:57 INFO - 'SHELL': '/bin/bash', 17:47:57 INFO - 'SHLVL': '1', 17:47:57 INFO - 'TERM': 'linux', 17:47:57 INFO - 'TMOUT': '86400', 17:47:57 INFO - 'USER': 'cltbld', 17:47:57 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', 17:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:57 INFO - '_': '/tools/buildbot/bin/python'} 17:47:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:47:57 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 17:47:57 INFO - Cleaning up... 17:47:57 INFO - Return code: 0 17:47:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:47:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:47:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1e201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218db20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2190f00>, '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 0x218bba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218c050>, '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': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', '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 17:47:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:47:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:47:57 INFO - Using env: {'DISPLAY': ':0', 17:47:57 INFO - 'HOME': '/home/cltbld', 17:47:57 INFO - 'LANG': 'en_US.UTF-8', 17:47:57 INFO - 'LANGUAGE': 'en_US:en', 17:47:57 INFO - 'LOGNAME': 'cltbld', 17:47:57 INFO - 'MAIL': '/var/mail/cltbld', 17:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:57 INFO - 'MOZ_NO_REMOTE': '1', 17:47:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:47:57 INFO - 'NO_EM_RESTART': '1', 17:47:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:47:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:57 INFO - 'PWD': '/builds/slave/test', 17:47:57 INFO - 'SHELL': '/bin/bash', 17:47:57 INFO - 'SHLVL': '1', 17:47:57 INFO - 'TERM': 'linux', 17:47:57 INFO - 'TMOUT': '86400', 17:47:57 INFO - 'USER': 'cltbld', 17:47:57 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', 17:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:57 INFO - '_': '/tools/buildbot/bin/python'} 17:47:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:47:57 INFO - Downloading/unpacking psutil>=3.1.1 17:47:57 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 17:47:57 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 17:47:57 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 17:47:57 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 17:47:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:47:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:47:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:47:59 INFO - Installing collected packages: psutil 17:47:59 INFO - Running setup.py install for psutil 17:47:59 INFO - building 'psutil._psutil_linux' extension 17:47:59 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 17:47:59 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 17:47:59 INFO - building 'psutil._psutil_posix' extension 17:47:59 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 17:47:59 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 17:47:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:47:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:47:59 INFO - Successfully installed psutil 17:47:59 INFO - Cleaning up... 17:47:59 INFO - Return code: 0 17:47:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:47:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:47:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218db20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2190f00>, '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 0x218bba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218c050>, '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': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', '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 17:47:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:47:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:47:59 INFO - Using env: {'DISPLAY': ':0', 17:47:59 INFO - 'HOME': '/home/cltbld', 17:47:59 INFO - 'LANG': 'en_US.UTF-8', 17:47:59 INFO - 'LANGUAGE': 'en_US:en', 17:47:59 INFO - 'LOGNAME': 'cltbld', 17:47:59 INFO - 'MAIL': '/var/mail/cltbld', 17:47:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:59 INFO - 'MOZ_NO_REMOTE': '1', 17:47:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:47:59 INFO - 'NO_EM_RESTART': '1', 17:47:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:47:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:59 INFO - 'PWD': '/builds/slave/test', 17:47:59 INFO - 'SHELL': '/bin/bash', 17:47:59 INFO - 'SHLVL': '1', 17:47:59 INFO - 'TERM': 'linux', 17:47:59 INFO - 'TMOUT': '86400', 17:47:59 INFO - 'USER': 'cltbld', 17:47:59 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', 17:47:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:59 INFO - '_': '/tools/buildbot/bin/python'} 17:47:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:47:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:48:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:48:01 INFO - Installing collected packages: mozsystemmonitor 17:48:01 INFO - Running setup.py install for mozsystemmonitor 17:48:01 INFO - Successfully installed mozsystemmonitor 17:48:01 INFO - Cleaning up... 17:48:01 INFO - Return code: 0 17:48:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:48:01 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')]} 17:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48:01 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')]} 17:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:01 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 0x1e201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218db20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2190f00>, '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 0x218bba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218c050>, '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': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', '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 17:48:01 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 17:48:01 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 17:48:01 INFO - Using env: {'DISPLAY': ':0', 17:48:01 INFO - 'HOME': '/home/cltbld', 17:48:01 INFO - 'LANG': 'en_US.UTF-8', 17:48:01 INFO - 'LANGUAGE': 'en_US:en', 17:48:01 INFO - 'LOGNAME': 'cltbld', 17:48:01 INFO - 'MAIL': '/var/mail/cltbld', 17:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:01 INFO - 'MOZ_NO_REMOTE': '1', 17:48:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:01 INFO - 'NO_EM_RESTART': '1', 17:48:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:01 INFO - 'PWD': '/builds/slave/test', 17:48:01 INFO - 'SHELL': '/bin/bash', 17:48:01 INFO - 'SHLVL': '1', 17:48:01 INFO - 'TERM': 'linux', 17:48:01 INFO - 'TMOUT': '86400', 17:48:01 INFO - 'USER': 'cltbld', 17:48:01 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', 17:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:01 INFO - '_': '/tools/buildbot/bin/python'} 17:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:01 INFO - Downloading/unpacking jsonschema==2.5.1 17:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:48:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:02 INFO - Downloading functools32-3.2.3-2.tar.gz 17:48:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:48:02 INFO - warning: no files found matching '*.txt' 17:48:02 INFO - no previously-included directories found matching 'build' 17:48:02 INFO - no previously-included directories found matching 'dist' 17:48:02 INFO - no previously-included directories found matching '.git*' 17:48:02 INFO - Installing collected packages: jsonschema, functools32 17:48:02 INFO - Running setup.py install for functools32 17:48:02 INFO - warning: no files found matching '*.txt' 17:48:02 INFO - no previously-included directories found matching 'build' 17:48:02 INFO - no previously-included directories found matching 'dist' 17:48:02 INFO - no previously-included directories found matching '.git*' 17:48:02 INFO - Successfully installed jsonschema functools32 17:48:02 INFO - Cleaning up... 17:48:02 INFO - Return code: 0 17:48:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:02 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 0x1e201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218db20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2190f00>, '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 0x218bba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218c050>, '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': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', '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 17:48:02 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 17:48:02 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 17:48:02 INFO - Using env: {'DISPLAY': ':0', 17:48:02 INFO - 'HOME': '/home/cltbld', 17:48:02 INFO - 'LANG': 'en_US.UTF-8', 17:48:02 INFO - 'LANGUAGE': 'en_US:en', 17:48:02 INFO - 'LOGNAME': 'cltbld', 17:48:02 INFO - 'MAIL': '/var/mail/cltbld', 17:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:02 INFO - 'MOZ_NO_REMOTE': '1', 17:48:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:02 INFO - 'NO_EM_RESTART': '1', 17:48:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:02 INFO - 'PWD': '/builds/slave/test', 17:48:02 INFO - 'SHELL': '/bin/bash', 17:48:02 INFO - 'SHLVL': '1', 17:48:02 INFO - 'TERM': 'linux', 17:48:02 INFO - 'TMOUT': '86400', 17:48:02 INFO - 'USER': 'cltbld', 17:48:02 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', 17:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:02 INFO - '_': '/tools/buildbot/bin/python'} 17:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:48:03 INFO - Cleaning up... 17:48:03 INFO - Return code: 0 17:48:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:48: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')]} 17:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48: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')]} 17:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48: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', '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 0x1e201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218db20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2190f00>, '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 0x218bba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218c050>, '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': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', '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 17:48: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:48: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 blobuploader==1.2.4 17:48:03 INFO - Using env: {'DISPLAY': ':0', 17:48:03 INFO - 'HOME': '/home/cltbld', 17:48:03 INFO - 'LANG': 'en_US.UTF-8', 17:48:03 INFO - 'LANGUAGE': 'en_US:en', 17:48:03 INFO - 'LOGNAME': 'cltbld', 17:48:03 INFO - 'MAIL': '/var/mail/cltbld', 17:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:03 INFO - 'MOZ_NO_REMOTE': '1', 17:48:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:03 INFO - 'NO_EM_RESTART': '1', 17:48:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:03 INFO - 'PWD': '/builds/slave/test', 17:48:03 INFO - 'SHELL': '/bin/bash', 17:48:03 INFO - 'SHLVL': '1', 17:48:03 INFO - 'TERM': 'linux', 17:48:03 INFO - 'TMOUT': '86400', 17:48:03 INFO - 'USER': 'cltbld', 17:48:03 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', 17:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:03 INFO - '_': '/tools/buildbot/bin/python'} 17:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:03 INFO - Downloading/unpacking blobuploader==1.2.4 17:48: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 17:48: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 17:48: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 17:48: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 17:48:04 INFO - Downloading blobuploader-1.2.4.tar.gz 17:48:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:48:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:48:04 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 17:48:04 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 17:48:04 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 17:48:04 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 17:48:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:48:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:48:04 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 17:48:04 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 17:48:04 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 17:48:04 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 17:48:04 INFO - Downloading docopt-0.6.1.tar.gz 17:48:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:48:05 INFO - Installing collected packages: blobuploader, requests, docopt 17:48:05 INFO - Running setup.py install for blobuploader 17:48:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:48:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:48:05 INFO - Running setup.py install for requests 17:48:05 INFO - Running setup.py install for docopt 17:48:05 INFO - Successfully installed blobuploader requests docopt 17:48:05 INFO - Cleaning up... 17:48:05 INFO - Return code: 0 17:48:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:48: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')]} 17:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48: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')]} 17:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:05 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 0x1e201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218db20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2190f00>, '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 0x218bba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218c050>, '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': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', '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 17:48:05 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 17:48:05 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 17:48:05 INFO - Using env: {'DISPLAY': ':0', 17:48:05 INFO - 'HOME': '/home/cltbld', 17:48:05 INFO - 'LANG': 'en_US.UTF-8', 17:48:05 INFO - 'LANGUAGE': 'en_US:en', 17:48:05 INFO - 'LOGNAME': 'cltbld', 17:48:05 INFO - 'MAIL': '/var/mail/cltbld', 17:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:05 INFO - 'MOZ_NO_REMOTE': '1', 17:48:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:05 INFO - 'NO_EM_RESTART': '1', 17:48:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:05 INFO - 'PWD': '/builds/slave/test', 17:48:05 INFO - 'SHELL': '/bin/bash', 17:48:05 INFO - 'SHLVL': '1', 17:48:05 INFO - 'TERM': 'linux', 17:48:05 INFO - 'TMOUT': '86400', 17:48:05 INFO - 'USER': 'cltbld', 17:48:05 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', 17:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:05 INFO - '_': '/tools/buildbot/bin/python'} 17:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:48:05 INFO - Running setup.py (path:/tmp/pip-30qlnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:48:05 INFO - Running setup.py (path:/tmp/pip-glIBVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:48:06 INFO - Running setup.py (path:/tmp/pip-ygTMX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:48:06 INFO - Running setup.py (path:/tmp/pip-ur9wco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:48:06 INFO - Running setup.py (path:/tmp/pip-SFpdTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:48:06 INFO - Running setup.py (path:/tmp/pip-JyNjH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:48:06 INFO - Running setup.py (path:/tmp/pip-VWNaKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:48:06 INFO - Running setup.py (path:/tmp/pip-l7wpX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:48:06 INFO - Running setup.py (path:/tmp/pip-Z6Mu1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:48:06 INFO - Running setup.py (path:/tmp/pip-Uh4txb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:48:06 INFO - Running setup.py (path:/tmp/pip-ah1BFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:48:06 INFO - Running setup.py (path:/tmp/pip-8W7ZzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:48:06 INFO - Running setup.py (path:/tmp/pip-uRe45V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:48:06 INFO - Running setup.py (path:/tmp/pip-FvO8wR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:48:06 INFO - Running setup.py (path:/tmp/pip-wTYbW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:48:06 INFO - Running setup.py (path:/tmp/pip-VCMZtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:48:07 INFO - Running setup.py (path:/tmp/pip-rnadDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:48:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:48:07 INFO - Running setup.py install for manifestparser 17:48:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:48:07 INFO - Running setup.py install for mozcrash 17:48:07 INFO - Running setup.py install for mozdebug 17:48:07 INFO - Running setup.py install for mozdevice 17:48:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:48:07 INFO - Running setup.py install for mozfile 17:48:07 INFO - Running setup.py install for mozhttpd 17:48:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:48:07 INFO - Running setup.py install for mozinfo 17:48:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:48:07 INFO - Running setup.py install for mozInstall 17:48:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:48:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:48:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:48:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:48:07 INFO - Running setup.py install for mozleak 17:48:07 INFO - Running setup.py install for mozlog 17:48:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:48:08 INFO - Running setup.py install for moznetwork 17:48:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:48:08 INFO - Running setup.py install for mozprocess 17:48:08 INFO - Running setup.py install for mozprofile 17:48:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:48:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:48:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:48:08 INFO - Running setup.py install for mozrunner 17:48:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:48:08 INFO - Running setup.py install for mozscreenshot 17:48:08 INFO - Running setup.py install for moztest 17:48:08 INFO - Running setup.py install for mozversion 17:48:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:48:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:48:08 INFO - Cleaning up... 17:48:08 INFO - Return code: 0 17:48:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:48: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')]} 17:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48: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')]} 17:48:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:08 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 0x1e201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218db20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2190f00>, '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 0x218bba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218c050>, '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': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', '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 17:48:08 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 17:48:08 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 17:48:08 INFO - Using env: {'DISPLAY': ':0', 17:48:08 INFO - 'HOME': '/home/cltbld', 17:48:08 INFO - 'LANG': 'en_US.UTF-8', 17:48:08 INFO - 'LANGUAGE': 'en_US:en', 17:48:08 INFO - 'LOGNAME': 'cltbld', 17:48:08 INFO - 'MAIL': '/var/mail/cltbld', 17:48:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:08 INFO - 'MOZ_NO_REMOTE': '1', 17:48:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:08 INFO - 'NO_EM_RESTART': '1', 17:48:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:08 INFO - 'PWD': '/builds/slave/test', 17:48:08 INFO - 'SHELL': '/bin/bash', 17:48:08 INFO - 'SHLVL': '1', 17:48:08 INFO - 'TERM': 'linux', 17:48:08 INFO - 'TMOUT': '86400', 17:48:08 INFO - 'USER': 'cltbld', 17:48:08 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', 17:48:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:08 INFO - '_': '/tools/buildbot/bin/python'} 17:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:48:09 INFO - Running setup.py (path:/tmp/pip-OKmG_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:48:09 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)) 17:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:48:09 INFO - Running setup.py (path:/tmp/pip-1PSN8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:48:09 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)) 17:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:48:09 INFO - Running setup.py (path:/tmp/pip-JM8sSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:48:09 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)) 17:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:48:09 INFO - Running setup.py (path:/tmp/pip-F7z0tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:48:09 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)) 17:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:48:09 INFO - Running setup.py (path:/tmp/pip-m51ue_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:48:09 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)) 17:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:48:09 INFO - Running setup.py (path:/tmp/pip-m6MbFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:48:09 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)) 17:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:48:09 INFO - Running setup.py (path:/tmp/pip-BbHp0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:48:09 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)) 17:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:48:09 INFO - Running setup.py (path:/tmp/pip-rrzhsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:48:09 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)) 17:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:48:09 INFO - Running setup.py (path:/tmp/pip-4Y0hv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:48:09 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)) 17:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:48:09 INFO - Running setup.py (path:/tmp/pip-80NWML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:48:09 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)) 17:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:48:09 INFO - Running setup.py (path:/tmp/pip-SDSJ8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:48:09 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)) 17:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:48:09 INFO - Running setup.py (path:/tmp/pip-qJQ93D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:48:09 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)) 17:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:48:09 INFO - Running setup.py (path:/tmp/pip-_XH9eW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:48:10 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)) 17:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:48:10 INFO - Running setup.py (path:/tmp/pip-BXLzID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:48:10 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)) 17:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:48:10 INFO - Running setup.py (path:/tmp/pip-h2KhED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:48:10 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)) 17:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:48:10 INFO - Running setup.py (path:/tmp/pip-Or_qQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:48:10 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)) 17:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:48:10 INFO - Running setup.py (path:/tmp/pip-t9B7ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:48:10 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)) 17:48:10 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)) 17:48:10 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)) 17:48:10 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)) 17:48:10 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)) 17:48:10 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)) 17:48:10 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)) 17:48:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:48: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 17:48: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 17:48: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 17:48: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 17:48:11 INFO - Downloading blessings-1.6.tar.gz 17:48:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:48:11 INFO - Installing collected packages: blessings 17:48:11 INFO - Running setup.py install for blessings 17:48:11 INFO - Successfully installed blessings 17:48:11 INFO - Cleaning up... 17:48:11 INFO - Return code: 0 17:48:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:48:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:48:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:48:11 INFO - Reading from file tmpfile_stdout 17:48:11 INFO - Current package versions: 17:48:11 INFO - argparse == 1.2.1 17:48:11 INFO - blessings == 1.6 17:48:11 INFO - blobuploader == 1.2.4 17:48:11 INFO - docopt == 0.6.1 17:48:11 INFO - functools32 == 3.2.3-2 17:48:11 INFO - jsonschema == 2.5.1 17:48:11 INFO - manifestparser == 1.1 17:48:11 INFO - mozInstall == 1.13 17:48:11 INFO - mozcrash == 1.0 17:48:11 INFO - mozdebug == 0.1 17:48:11 INFO - mozdevice == 0.51 17:48:11 INFO - mozfile == 1.2 17:48:11 INFO - mozhttpd == 0.7 17:48:11 INFO - mozinfo == 0.10 17:48:11 INFO - mozleak == 0.1 17:48:11 INFO - mozlog == 3.5 17:48:11 INFO - moznetwork == 0.27 17:48:11 INFO - mozprocess == 0.25 17:48:11 INFO - mozprofile == 0.28 17:48:11 INFO - mozrunner == 6.13 17:48:11 INFO - mozscreenshot == 0.1 17:48:11 INFO - mozsystemmonitor == 0.3 17:48:11 INFO - moztest == 0.8 17:48:11 INFO - mozversion == 1.4 17:48:11 INFO - psutil == 3.1.1 17:48:11 INFO - requests == 1.2.3 17:48:11 INFO - wsgiref == 0.1.2 17:48:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:48: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')]} 17:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48: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')]} 17:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:11 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 0x1e201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218db20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2190f00>, '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 0x218bba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218c050>, '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': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', '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 17:48:11 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 17:48:11 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 17:48:11 INFO - Using env: {'DISPLAY': ':0', 17:48:11 INFO - 'HOME': '/home/cltbld', 17:48:11 INFO - 'LANG': 'en_US.UTF-8', 17:48:11 INFO - 'LANGUAGE': 'en_US:en', 17:48:11 INFO - 'LOGNAME': 'cltbld', 17:48:11 INFO - 'MAIL': '/var/mail/cltbld', 17:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:11 INFO - 'MOZ_NO_REMOTE': '1', 17:48:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:11 INFO - 'NO_EM_RESTART': '1', 17:48:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:11 INFO - 'PWD': '/builds/slave/test', 17:48:11 INFO - 'SHELL': '/bin/bash', 17:48:11 INFO - 'SHLVL': '1', 17:48:11 INFO - 'TERM': 'linux', 17:48:11 INFO - 'TMOUT': '86400', 17:48:11 INFO - 'USER': 'cltbld', 17:48:11 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', 17:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:11 INFO - '_': '/tools/buildbot/bin/python'} 17:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:12 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)) 17:48:12 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)) 17:48:12 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)) 17:48:12 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)) 17:48:12 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)) 17:48:12 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)) 17:48:12 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)) 17:48:12 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)) 17:48:12 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)) 17:48:12 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:48:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:48:13 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)) 17:48:13 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)) 17:48:13 INFO - Installing collected packages: simplejson 17:48:13 INFO - Running setup.py install for simplejson 17:48:13 INFO - building 'simplejson._speedups' extension 17:48:13 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 17:48:14 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 17:48:14 INFO - Successfully installed simplejson 17:48:14 INFO - Cleaning up... 17:48:14 INFO - Return code: 0 17:48:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:48: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')]} 17:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:48: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')]} 17:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:48:14 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 0x1e201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1feca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x218db20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2190f00>, '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 0x218bba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218c050>, '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': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', '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 17:48:14 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 17:48:14 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 17:48:14 INFO - Using env: {'DISPLAY': ':0', 17:48:14 INFO - 'HOME': '/home/cltbld', 17:48:14 INFO - 'LANG': 'en_US.UTF-8', 17:48:14 INFO - 'LANGUAGE': 'en_US:en', 17:48:14 INFO - 'LOGNAME': 'cltbld', 17:48:14 INFO - 'MAIL': '/var/mail/cltbld', 17:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:14 INFO - 'MOZ_NO_REMOTE': '1', 17:48:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:14 INFO - 'NO_EM_RESTART': '1', 17:48:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:14 INFO - 'PWD': '/builds/slave/test', 17:48:14 INFO - 'SHELL': '/bin/bash', 17:48:14 INFO - 'SHLVL': '1', 17:48:14 INFO - 'TERM': 'linux', 17:48:14 INFO - 'TMOUT': '86400', 17:48:14 INFO - 'USER': 'cltbld', 17:48:14 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', 17:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:14 INFO - '_': '/tools/buildbot/bin/python'} 17:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:48:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:48:14 INFO - Cleaning up... 17:48:14 INFO - Return code: 0 17:48:14 INFO - Running post-action listener: _resource_record_post_action 17:48:14 INFO - Running post-action listener: _start_resource_monitoring 17:48:14 INFO - Starting resource monitoring. 17:48:14 INFO - [mozharness: 2017-08-14 00:48:14.601423Z] Finished create-virtualenv step (success) 17:48:14 INFO - [mozharness: 2017-08-14 00:48:14.601994Z] Running install step. 17:48:14 INFO - Running pre-action listener: _resource_record_pre_action 17:48:14 INFO - Running main action method: install 17:48:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:48:14 INFO - Reading from file tmpfile_stdout 17:48:14 INFO - Detecting whether we're running mozinstall >=1.0... 17:48:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:48:14 INFO - Reading from file tmpfile_stdout 17:48:14 INFO - Output received: 17:48:14 INFO - Usage: mozinstall [options] installer 17:48:14 INFO - Options: 17:48:14 INFO - -h, --help show this help message and exit 17:48:14 INFO - -d DEST, --destination=DEST 17:48:14 INFO - Directory to install application into. [default: 17:48:14 INFO - "/builds/slave/test"] 17:48:14 INFO - --app=APP Application being installed. [default: firefox] 17:48:14 INFO - mkdir: /builds/slave/test/build/application 17:48:14 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'] 17:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:48:32 INFO - Reading from file tmpfile_stdout 17:48:32 INFO - Output received: 17:48:32 INFO - /builds/slave/test/build/application/firefox/firefox 17:48:32 INFO - Running post-action listener: _resource_record_post_action 17:48:32 INFO - [mozharness: 2017-08-14 00:48:32.709381Z] Finished install step (success) 17:48:32 INFO - [mozharness: 2017-08-14 00:48:32.709725Z] Running setup-mitmproxy step. 17:48:32 INFO - Running pre-action listener: _resource_record_pre_action 17:48:32 INFO - Running main action method: setup_mitmproxy 17:48:32 INFO - Skipping: mitmproxy is not required 17:48:32 INFO - Running post-action listener: _resource_record_post_action 17:48:32 INFO - [mozharness: 2017-08-14 00:48:32.711408Z] Finished setup-mitmproxy step (success) 17:48:32 INFO - [mozharness: 2017-08-14 00:48:32.711722Z] Running run-tests step. 17:48:32 INFO - Running pre-action listener: _resource_record_pre_action 17:48:32 INFO - Running pre-action listener: _set_gcov_prefix 17:48:32 INFO - Running main action method: run_tests 17:48:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:48:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:48:32 INFO - Python 2.7.3 17:48:32 INFO - Return code: 0 17:48:32 INFO - grabbing minidump binary from tooltool 17:48:32 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')]} 17:48:32 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 0x2190f00>, '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 0x218bba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x218c050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:48:32 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 17:48:32 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 17:48:32 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 17:48:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:48:32 INFO - Return code: 0 17:48:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:48:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:48:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:48:32 INFO - ENV: RUST_BACKTRACE is now full 17:48:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:48:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:48:32 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/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-084', '--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 17:48:32 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/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-084 --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 17:48:32 INFO - Using env: {'DISPLAY': ':0', 17:48:32 INFO - 'HOME': '/home/cltbld', 17:48:32 INFO - 'LANG': 'en_US.UTF-8', 17:48:32 INFO - 'LANGUAGE': 'en_US:en', 17:48:32 INFO - 'LOGNAME': 'cltbld', 17:48:32 INFO - 'MAIL': '/var/mail/cltbld', 17:48:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:48:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:48:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:48:32 INFO - 'MOZ_NO_REMOTE': '1', 17:48:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:48:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:48:32 INFO - 'NO_EM_RESTART': '1', 17:48:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:48:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:48:32 INFO - 'PWD': '/builds/slave/test', 17:48:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:48:32 INFO - 'RUST_BACKTRACE': 'full', 17:48:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:48:32 INFO - 'SHELL': '/bin/bash', 17:48:32 INFO - 'SHLVL': '1', 17:48:32 INFO - 'TERM': 'linux', 17:48:32 INFO - 'TMOUT': '86400', 17:48:32 INFO - 'USER': 'cltbld', 17:48:32 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389', 17:48:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:48:32 INFO - '_': '/tools/buildbot/bin/python'} 17:48:32 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/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-084', '--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 17:48:33 INFO - mozversion application_buildid: 20170813233048 17:48:33 INFO - mozversion application_changeset: 3bfcbdf5c6c381d5a8febb5c209e27a69fb89f9b 17:48:33 INFO - mozversion application_display_name: Nightly 17:48:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:48:33 INFO - mozversion application_name: Firefox 17:48:33 INFO - mozversion application_remotingname: firefox 17:48:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:48:33 INFO - mozversion application_vendor: Mozilla 17:48:33 INFO - mozversion application_version: 57.0a1 17:48:33 INFO - mozversion platform_buildid: 20170813233048 17:48:33 INFO - mozversion platform_changeset: 3bfcbdf5c6c381d5a8febb5c209e27a69fb89f9b 17:48:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:48:33 INFO - mozversion platform_version: 57.0a1 17:48:33 INFO - using testdate: 1502671713 17:48:33 INFO - actual date: 1502671713 17:48:33 INFO - starting webserver on 'localhost:50835' 17:48:33 INFO - SUITE-START | Running 2 tests 17:48:33 INFO - TEST-START | dromaeo_css 17:48:33 INFO - Initialising browser for dromaeo_css test... 17:48:33 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50835/getInfo.html -profile /tmp/tmpp0vmrg/profile 17:48:33 INFO - TEST-INFO | started process 15876 (/builds/slave/test/build/application/firefox/firefox http://localhost:50835/getInfo.html) 17:48:39 INFO - TEST-INFO | 15876: exit 0 17:48:39 INFO - Browser initialized. 17:48:39 INFO - Running cycle 1/1 for dromaeo_css test... 17:48:39 INFO - TEST-INFO | started process 16089 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp0vmrg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:48:40 INFO - PID 16089 | 17:48:40 INFO - PID 16089 | (/builds/slave/test/build/application/firefox/firefox:16139): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:48:40 INFO - PID 16089 | 17:48:50 INFO - PID 16089 | RSS: Main: 158715904 17:48:50 INFO - PID 16089 | 17:51:49 INFO - PID 16089 | Cycle 1(1): loaded http://localhost:50835/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50835/tests/dromaeo/cssquery-ext.html) 17:51:50 INFO - PID 16089 | RSS: Main: 166535168 17:51:50 INFO - PID 16089 | 17:54:52 INFO - PID 16089 | Cycle 1(1): loaded http://localhost:50835/tests/dromaeo/cssquery-ext.html (next: http://localhost:50835/tests/dromaeo/cssquery-jquery.html) 17:54:52 INFO - PID 16089 | RSS: Main: 162103296 17:54:52 INFO - PID 16089 | 17:57:54 INFO - PID 16089 | Cycle 1(1): loaded http://localhost:50835/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50835/tests/dromaeo/cssquery-mootools.html) 17:57:54 INFO - PID 16089 | RSS: Main: 165658624 17:57:54 INFO - PID 16089 | 18:00:56 INFO - PID 16089 | Cycle 1(1): loaded http://localhost:50835/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50835/tests/dromaeo/cssquery-prototype.html) 18:00:57 INFO - PID 16089 | RSS: Main: 164147200 18:00:57 INFO - PID 16089 | 18:04:00 INFO - PID 16089 | Cycle 1(1): loaded http://localhost:50835/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50835/tests/dromaeo/cssquery-yui.html) 18:04:01 INFO - PID 16089 | RSS: Main: 167108608 18:04:01 INFO - PID 16089 | 18:07:02 INFO - PID 16089 | Cycle 1(1): loaded http://localhost:50835/tests/dromaeo/cssquery-yui.html (next: http://localhost:50835/tests/dromaeo/cssquery-dojo.html) 18:07:02 INFO - PID 16089 | RSS: Main: 168177664 18:07:02 INFO - PID 16089 | 18:07:02 INFO - PID 16089 | __start_tp_report 18:07:02 INFO - PID 16089 | _x_x_mozilla_page_load 18:07:02 INFO - PID 16089 | _x_x_mozilla_page_load_details 18:07:02 INFO - PID 16089 | |i|pagename|runs| 18:07:02 INFO - PID 16089 | |0;dojo.html;3468.289000754844;3488.790672559645;3509.2981403719255;3521.4893840393142;3575.785452872828;6234.719437625306;6352.142460767801;6358.872822543546;6380.457661098806;6381.266154392246;6234.283057448395;6329.7151628176725;6337.049442583615;6341.968290158555;6357.459615932651;5530.253415788878;5532.723363831792;5537.390887002426;5540.778368865241;5562.082256427685;5579;5591.9720401397935;5625.296837895263;5636.943630563683;5648.43515648436;5626.127950167731;5687.17535957286;5688.516476099516;5693.715314234292;5703.74333155009;5407.675539467645;5522.061249587579;5556.638818476827;5634.859128521779;5706.828795136153;5453.481919217668;5465.207544906006;5518.558515318805;5547.362053363874;5619.1009438490055;6130.203073600448;6246.968765156145;6297.716602752841;6301.716422760941;6338.651374174418;6690.665466726644;6764.289749576273;6791.762288319885;6826.965865170692;6912.274211207851;6772.72909083636;6773.288804675537;6780.423663988567;6785.694643741043;6786.049953006583;6267.122602835607;6267.153934218868;6346.30190679025;6418.165638467063;6418.967905160491;7041.049458323061;7057.7176912922905;7114.324139206767;7130.2869713028285;7131.429485641136;6746.325367463312;6749.662516874137;6758.189017317954;6821.113255276881;6855.314468553204;6740.39336459721;6839.350261725128;6848.486363522656;6912;6916.965415172892;6824.454043676593;6840.281770414134;6892.379685828299;6924.307569243136;6927.618980956096;6799.830004249933;6870.519063665495;6895.689693963784;6914.343137401939;6918.06606108179;4904.2643603459765;4928.137575924199;4977.552020318189;4982.402111746614;4989.101961646866;4320.524742278347;4353.73877567347;4379.277419225792;4392.714473559208;4442.555744425532;6464.903026454607;6479.6760161991715;6482.935170648328;6496.610203387718;6512.869742605216;6804.387605115563;6811.4550835933005;6882;6887.931120688829;6896.103506544117;6421.422072013448;6433.549651524442;6443.420092191725;6498.285188629247;6498.447631951242;5426.810061647823;5479.5342395896;5480.534154596824;5520.779168833282;5528.44715528444;7205.495615306878;7244.782656520313;7270.63646817657;7278.8180295491975;7280.41756659466;6770;6880.208775990825;6926.272741362084;6936.687849046704;6943.097397338313;7031.859362812817;7166.355028047501;7171.4621403395995;7177.4616903731385;7261.782146535613;6711.3288671132495;6718.126643536309;6722.899156512558;6753.86492270142;6812.420944219797;6862.725490980305;6926.896096558455;7000.054992576141;7001.50989430735;7008.789736307919;6678.432333251533;6680.0647909291765;6686.7993960181275;6774.085498457645;6775.89836152468;6531.836704082436;6633.270340262471;6637.070810086689;6685.532012759255;6736.225334086643;6599.208095028627;6671.966640166962;6674.16572928384;6683;6683.064370988162;6526.608403495806;6622.304658011032;6626.304238054935;6704.329567043256;6718.596884186964;6421.775237866839;6446.4520515756685;6447.323031081669;6463.64449955257;6470.191226096738 18:07:02 INFO - PID 16089 | |1;ext.html;895.0236684036756;920.5719340506663;928.1692884868041;967.1489089601158;967.1778987860315;43585.56414435855;44263.77868110663;44267.3359899601;44322.778386108104;44395.77802110989;41888.37167442491;42013;42039.57960420395;42116.36825447613;42135.57864421355;6960.477964152697;7048.04851345067;7068.540544864567;7075.716971321141;7078.964605176967;6835.384815366616;6888.827779305532;6891.069705589732;6891.173059232874;6894.379505844473;7791.844163116734;7927.8810817837775;7946.960265198666;7949.244821741925;7960.880586791202;7776.300132988002;7834.882476762881;7850.136484986676;7921.168277330884;7975.840483190362;7755.108162561265;7829.295363417263;7830.647620857018;7835.177306382861;7837.1379148293645;42039.57960420418;42288.365674514906;42321;42471.78764106159;42482.36276455856;3523.2072783623735;3551.2187318789825;3553.9111522211892;3554.111472131967;3569.0542006368332;32803;32830.34339313224;32860.83569582161;32873.83563082193;32937.6706232937;5150.562202212816;5162.096633089194;5184.300119483897;5197.974010129963;5207.817726379559;217701;219273;221374;223648;233475;54737.452625474034;54806.45193548093;54833.72583137059;54848.983305918555;55004;56576.434235657114;56743.716281419154;56956.43043569511;56992;56994.7150264246;16835;16933.238004289837;17015.744763828552;17029.318827247018;17123.828761712473;13893.513727019506;13913.513027044004;13923.164610123426;13941.093828901088;13960.650983725489;15364.462243821412;15384.461543845911;15398.769018464733;15516.45692400783;15539.5338139856;6121.96939015311;6141.416565426277;6150.3849615039035;6200.875982480326;6263.029230469285;1072.3297938788257;1091.8744344400336;1093.9234253602172;1094.5676457799125;1099.4777480696764;24855.62716559253;24906.5018699625;25080.4983900321;25116.62325065089;25216;1188.8870557297043;1197.820326950947;1206.2400687566776;1210.3887536793864;1215.574548907893;13152.21086734799;13241.337933103307;13258.602241932758;13320.134191277535;13364.465421383036;287227;288643;288792;290189;290230;57921.71039144777;58136.41863581395;58177.4182258172;58256.708716456145;58323.41676583265;1523.6647937453743;1542.3753379881166;1557.447106277278;1561.9453319134002;1571.6856628674082;24867.253982380556;25002.62496062597;25064.874675626506;25182.496350073263;25269.24192274235;40800.59199408087;40839.18321633491;41132;41224;41352.37971430371;233473;233639;234075;234303;234312;36328.4550731734;42074.57925420706;42289.78855105705;42564.787176063925;42809;52261;52920.73539632277;53184.93630127298;53205.73397132989;53330.4666953341;37790;38200.808995954845;38318.80840595779;38709.03227419337;38915.41626875543;38924.610753893234;38972;39110.21779564336;39223.2155356897;39314;44637.55362446334;45146.322805157295;45314.09371812611;45844.083118338116;45973.31040034469;37808.810955945046;37964.810175948944;37978;38079.61920380872;38245.617543824206;27736.167914962585;28013.01954431585;28461.77678723108;28554.714452855205;28764.568531471625 18:07:02 INFO - PID 16089 | |2;jquery.html;564.235460950412;574.2391331485783;578.7106446776612;581.1515187825772;590.929088509379;22760.65859012114;23496.295111146694;23707;23757.643635345485;23776.405589860264;27059.105862589626;27495.725042749567;27616.309592260204;27635.309117272078;27640.723592764065;6324.462420694246;6455.064015717718;6480.060391243267;6486.837829054288;6489.123968264271;5923.111533270001;6309.3375195604485;6472.288048314682;6474.838129046764;6477.255115661688;6454.709538070774;6456.35436456353;6456.548041637087;6457.483401327882;6471.352864713538;6436.710348034349;6443.742250310005;6447.709853056577;6449.742010319582;6455.9677201613695;5995.130706047598;6004.3395226525045;6011.549133814981;6019.367966363557;6029.909551356689;29747.107586772432;30892.227694307596;31079;31285.217869553217;31353.37293254145;2270.421042634118;2306.054517647773;2307.40007598024;2313.9652905206435;2316.328264803205;20419.285325013552;20797.272095476586;20962.475938101517;20968.370948871558;20984.685229721566;2459.311392810016;2468.8148388870727;2497.625356196567;2526.772590466848;2556.271462633141;148647;152928.23535882361;153256;153491;153796;15160.31778569967;15291.923540382228;16062.75905861413;16138.919305403399;16201.513954581382;24340.634890476656;24362.756372436404;24489.510209795706;24509.87745061299;24541.63187552188;10711.89288107109;10797.730056748487;10810.729731756768;10953.34279943206;10971.670849874517;8925.375223734276;9933.453660048623;10040.196784257241;10046.799064018678;10174.542145603466;8792.42849214799;9751.902480975099;10037.34757240777;10139.442330671736;10177.134943529883;2614.9084782032537;2617.5550156473446;2705.742954419327;2766.21029190011;2846.644169478815;1032.6334151376304;1035.0787798859021;1051.6582110814015;1059.782744537368;1063;21628.4592885176;22015.889920550293;22039.779602204093;22171.778282217296;22295.77704222937;1122.8484154639182;1126.7408496045791;1134.5915470430639;1138.538891748843;1148.661144962238;11897.167198296209;12231.388430578614;12324.445399956845;12334.81497777534;12542.81185782214;555073;556722;564815;569422;572580;132266;135040.32479837537;135493.3225333867;140264.29867850596;140762;1241.1684171605045;1251.9123661343801;1278.0670110819153;1285.9357032148355;1290.541857640524;19372.709409358587;19782.010899454683;19974;20047.49881252922;20318.085686143862;65216.347836521025;67487.66256168688;67824;68029;69410.65294673495;82713;83283;83604.58197708972;83729.58135209528;83771;12759.915407190121;15364.923175384052;15542.145182015098;15589.532314030597;15851.603709906884;24989;25011.87494062518;25044.62433063469;25271.49457010886;25287.11495097663;19774.40676779699;20102.49743756418;20204.29284975019;20579.69130463014;20685.379438616867;17197.484075477754;18005.549861253574;18109.275628974694;18113.728294075336;18663.626727465646;21416.571668566852;21451.892740536197;21837.672434913173;22032.559348813254;22035;15238.161901095104;15295.541133766004;15473.613159670647;15485.76113910907;15502.069875807487;20027.499312517302;20094.39716808498;20267.69598455995;20448.284310049075;20543.69184462264 18:07:02 INFO - PID 16089 | |3;mootools.html;1422.6727852593904;1471.0438215160145;1499.3627708224003;1502.5567457600007;1515.1590867068771;15714.764278535831;15774.211289435525;16279.918600406982;16450.670986580277;16454.34182632695;16483.505494835143;16732.498025059238;16733.330666773316;16749.66500669986;16765.7485137723;2750.518659234636;2803.733645303693;2805.270629636299;2805.2846524136316;2805.3547684032583;2755.6968733439307;2879.17943386135;2881.164462305939;2884;2884.480793457177;2773.362126710858;2779.833210007396;2780.4995100881833;2792.6229958955587;2809.3819359740824;2774.5838124281318;2781.930451738692;2803.495370833249;2803.7056109108453;2805.4950108980374;2566.230130960704;2599.7790187834057;2610.843349399042;2618.463215040931;2619.6463477430684;15926.362945482168;16286.674266514603;16301.918490407472;16305.021698698116;16314.347426102942;7913.406494512935;8110.675572977074;8163.346932245406;8172.264496195371;8184.427090103695;5209.6874187548865;5235.083860324428;5341.599380046512;5369.838904832822;5413.53984911283;1949.278766856258;1971.5366888781125;1973.2797528901847;2003.719479272903;2013.8590298679098;28461.71538284632;28716.712832871614;28779.424411511653;28818;28820.423591528262;7458.5151965122095;7573.772786816405;7750.224977502205;7758.874963130314;7869.3311068559815;19422.417327480198;19491.12289946956;19491.805081949282;19619.607607847764;19641.116149773294;7769.6115194240065;7947.4436789425345;7958.681652733826;7960.164182760726;7963.800904977306;7315.817104572428;7323;7428.702851885972;7475.925240747523;7862.960685196652;5362.142057270896;5547.694876781735;5576.386597474275;5608.214849921014;5624.746886390122;1501.399440223919;1507.826599940999;1510.4788847847476;1519.2100107943807;1521.5131158029546;890.5591732092655;910.8041771019263;913.8218047480677;920.4339331311329;927.3786563002805;7768.10666773317;7811.921880781233;7820.452568320163;7839.608019599112;7965.960170199112;737.8561180569737;739.3050532499433;744.9590272534954;746.4588173574115;747.3274053352027;4905.288733133676;5049.192129259301;5295.602829787781;5372.811951581676;5494.313210848644;23296.76703232946;23733.643995340084;24064.87967560151;24267.39331516726;24317.635235471484;23541;23732.69470179122;23856.761432385454;23883.92522336499;24027.27918162458;12511.249325040344;12529.874701252871;12541.811872821914;12552.058595605366;12586.993040556734;8626.956865215634;8666.78333041679;8717.259032982267;8717.694880679146;8811.471311721318;20139;20773.169073236902;20953.26663566768;21208.893955530122;21220.04509797032;20789.1684332625;21132.260370886946;21282.467938301666;21314.57370852605;21440.785592144504;12035;12174.817377739164;12177.634670959886;12186;12211.389430528261;14497.275136243357;14800.185989770665;14893;14904.329305181076;14968.775468367765;11788.23376480526;11817.113716471471;11838.348890811088;12062.819057714318;12127.81808272859;11477.942610286895;11495.080393568662;11577.073834093084;11654.592089276835;11774.529018839494;9902.603895844373;9962.800743984935;9989.950050249703;9992.400455972665;9994.60021599157;7181.892271615826;7211.531250468703;7213.567185968858;7221.602811845189;7271.200167981412;7343.926560734324;7355.411567074514;7395.223501532477;7417.851642967218;7468.3278504934815 18:07:02 INFO - PID 16089 | |4;prototype.html;505.959523238141;507.6801614982561;512.6155383462403;534.8898595042558;541.6371031408955;6148.600340977839;6158;6211.627302361861;6220.564560480768;6264.561480696341;6123.540734444923;6152.477039451652;6174.475169610577;6191.133241346204;6234.563580549352;4921.335619691332;4943.431505376874;4971.925421118686;4991.850244492662;4994.875128121808;4732.698507910335;4830.9275360869615;4915.950840491585;4920.507949205086;4949.059678661061;4739.549742774431;4747.810087596492;4774.546418090275;4777.90444191116;4792.017636384533;4726.810927562893;4740.454847692507;4775.570198682118;4788.497207793166;4790.089882922233;4314.654827613784;4332.176886391576;4335.523092459827;4337.219300525904;4372.19114463822;6106.816795496142;6121;6127.816165514997;6147.907781383283;6153.292371377304;5678.545716342683;5706.743196556165;5714.057180565202;5727.627704199255;5739.225204597367;6612.239592446882;6617.9153566626355;6622.205335359739;6645.3354664533645;6674.599524028574;4913.778879950411;4926.729029903354;4929.2113261878285;4931.742405686563;4943.9258411123865;6693.7575912069515;6781.152355955505;6860.211075726255;6923.861522769566;6944.4097251733665;5062.11413002723;5073.188289873602;5074.797008119634;5085.440601533828;5100.897982040413;5119.641625086283;5126.846194614168;5147.613928955343;5180.896382072413;5201.947980520146;5671.326958547156;5756.740946657326;5779;5793.275840519935;5799.492132045699;6599.175103112111;6636.568623039583;6652.135222421054;6687.097241872285;6699.564528305645;1775.3874913154941;1789.8926064436175;1793.6771381151257;1801.972970405445;1802.2610729601063;1198.8141838015122;1200.9131735779133;1233.6360773571819;1237.3813093453273;1249.7375551134364;6265;6290.779822706183;6362.809115726535;6402.967985160044;6406.743730250831;6688.592106841533;6699.531032827753;6731.865362692719;6743.629100399525;6773.390394864486;6278.748850045947;6301.306856245809;6306.684665766785;6395.776147834804;6401.64790936503;3539.628339024417;3568.965689556928;3585.695215906625;3593.874214402483;3596.4175762664345;7059.505834591632;7068.7525936593;7094.077769889881;7112.039874616964;7115.857682846417;6834.6582670866255;6837.453003759687;6899.551529150589;6954.3045695431065;6954.374106330454;6573.209634463943;6930.5792312574795;6994.405475534637;7070.611116388648;7108.822279443055;6606.801795945937;6622.63575503352;6637.6681165940545;6707.155532228676;6724.260331363644;6626.933730662631;6655.866882662416;6690;6692.130023096966;6716.992451132369;6859.691313890987;6950.687219075053;7003.719851205895;7035.261297563682;7067.328603782633;4987.426445958616;5024.120778863611;5028.622853285945;5059.822906198266;5083.237514372874;6602.566665333903;6752.898706519504;6788.522189400932;6890.8621827564175;6928;6491.253505846888;6557.311482294291;6569.671516424064;6573.835654108686;6580.638064906476;6573.835654108686;6603.127639476843;6641.435477984425;6676.298988606321;6678.662468136114;6511.413972742476;6580.374864387875;6592.340765923561;6624.171978502687;6637.909058646511;6570.145881035626;6611.636360000246;6661.533692641468;6668.698993998002;6670.933290667225;6428.614283143026;6451.741930322734;6462.418382345423;6477.287498375269;6489.610623362613 18:07:02 INFO - PID 16089 | |5;yui.html;937.1752857485412;948.0708905272833;971.9028097190278;979.5560077606373;994.4132961552682;18802.623947521042;18873.716894246598;19343;19844.603107937834;19914.40256792295;20480.385588432335;22726.204582839604;22945.311640650765;23004.424889377733;23042.65436018461;1053.8419237114417;1066.2696053203542;1069.5400977579638;1070.1331921143899;1073.404260635347;1033.9896601033968;1070.6948519671896;1071.614218881202;1075.6611667324808;1079.1744315598573;1129.232122156933;1129.6441620889434;1130.8416821644976;1131.7510147767478;1133.557912414155;1119.356370087195;1119.8488204092466;1119.9048080913133;1122.1247427006947;1122.3041714137287;832.9000519937646;835.1982097186708;835.4945258118811;835.7868743470437;836.7489753074053;20241.493962650904;20257.696134557995;20610.587788244302;20631.69052464214;21221.787782122137;19917.004149792454;20328;20340.593188136154;20357.796422035888;20650.896745516326;17049.7442538362;17237.913810430993;17284.39504617345;17335.739963900553;17360.652786944316;3763.473113764075;3779.4141908004303;3780.527434070741;3786.3941769316775;3792.4500947362476;148023;148350.99999999892;148690;149622;150500;17312.220950057024;17601.471955841345;17624.823751762317;17630.91184544069;17662.381816636356;33141;34074;34075;34148;34197;9070.682526111686;9161.450312981242;9166.175044245916;9175.311851611155;9241.075892410705;4263.424437700933;4330.155619654201;4355.412019377407;4356.956430435718;4357.172137293904;6414.839629009312;6591.373819487141;6628.5691430056895;6629.535932484776;6667;1104.640991677708;1128.4527004402858;1134.8354488599273;1136.8635763708241;1141.5205613642124;731.5098883747902;751.1174370115093;751.1624538639487;765.4985984180371;773.8955241042387;12338.75322493563;12892.355382230851;13125.34373281332;13267.270300133585;13656.590302290748;698.4656737595743;701.5264696329957;702.1293595940996;710.8365076032542;713.0552018575305;5790.7973220937065;5871.970640146772;5960.612560183574;6137.478314343241;6205.751769929243;135473;137099;138067.30966345104;138646;141722;121140.39429802971;123205.38397307956;125373;126494.36752816178;128348.9407583407;104928.47535762272;106798.46600766947;107088;107101.46449267704;107281.46359268154;11194.216404851908;11205.159613028893;11274.379909105079;11424.657260282205;11508.654740357802;50813.237801433745;51884.48115518797;52784.47215527796;53228.73385633047;53717.46282537124;84143;86585.56707216424;88607.11392886247;88692.55653721691;88870.15008781847;18106.72839907376;18467;18542.258309668003;20061.29785457502;20933;19770.802291976896;20010.799892001476;20021.6996745046;20295.69556456625;20519.4870128248;19020.144093515548;19393.32123375675;19539.51151221231;19674.803251967296;20167.596648066665;18034.36879709204;18296.90851545787;18604.534886627942;20444.59110817805;20766.480837979172;19591.70612440843;20109.798902010793;20115.19539218415;20127.698084528452;20865.165393384093;12525.498980040697;12668;13418;15626.218689065729;15720.135392553519;14176.929115354356;14207.28963551839;14576.34406451691;14730.852691472948;14743.18912459825 18:07:02 INFO - PID 16089 | __end_tp_report 18:07:02 INFO - PID 16089 | __start_cc_report 18:07:02 INFO - PID 16089 | _x_x_mozilla_cycle_collect,332 18:07:02 INFO - PID 16089 | __end_cc_report 18:07:02 INFO - PID 16089 | __startTimestamp1502672822908__endTimestamp 18:07:02 INFO - PID 16089 | 18:07:02 INFO - PID 16089 | ------- Summary: start ------- 18:07:02 INFO - PID 16089 | Number of tests: 6 18:07:02 INFO - PID 16089 | 18:07:02 INFO - PID 16089 | [#0] dojo.html Cycles:165 Average:6288.71 Median:6599.21 stddev:827.97 (12.5%) stddev-sans-first:800.40 18:07:02 INFO - PID 16089 | Values: 3468.3 3488.8 3509.3 3521.5 3575.8 6234.7 6352.1 6358.9 6380.5 6381.3 6234.3 6329.7 6337.0 6342.0 6357.5 5530.3 5532.7 5537.4 5540.8 5562.1 5579.0 5592.0 5625.3 5636.9 5648.4 5626.1 5687.2 5688.5 5693.7 5703.7 5407.7 5522.1 5556.6 5634.9 5706.8 5453.5 5465.2 5518.6 5547.4 5619.1 6130.2 6247.0 6297.7 6301.7 6338.7 6690.7 6764.3 6791.8 6827.0 6912.3 6772.7 6773.3 6780.4 6785.7 6786.0 6267.1 6267.2 6346.3 6418.2 6419.0 7041.0 7057.7 7114.3 7130.3 7131.4 6746.3 6749.7 6758.2 6821.1 6855.3 6740.4 6839.4 6848.5 6912.0 6917.0 6824.5 6840.3 6892.4 6924.3 6927.6 6799.8 6870.5 6895.7 6914.3 6918.1 4904.3 4928.1 4977.6 4982.4 4989.1 4320.5 4353.7 4379.3 4392.7 4442.6 6464.9 6479.7 6482.9 6496.6 6512.9 6804.4 6811.5 6882.0 6887.9 6896.1 6421.4 6433.5 6443.4 6498.3 6498.4 5426.8 5479.5 5480.5 5520.8 5528.4 7205.5 7244.8 7270.6 7278.8 7280.4 6770.0 6880.2 6926.3 6936.7 6943.1 7031.9 7166.4 7171.5 7177.5 7261.8 6711.3 6718.1 6722.9 6753.9 6812.4 6862.7 6926.9 7000.1 7001.5 7008.8 6678.4 6680.1 6686.8 6774.1 6775.9 6531.8 6633.3 6637.1 6685.5 6736.2 6599.2 6672.0 6674.2 6683.0 6683.1 6526.6 6622.3 6626.3 6704.3 6718.6 6421.8 6446.5 6447.3 6463.6 6470.2 18:07:02 INFO - PID 16089 | 18:07:02 INFO - PID 16089 | [#1] ext.html Cycles:180 Average:43576.02 Median:40057.30 stddev:65149.40 (162.6%) stddev-sans-first:65253.34 18:07:02 INFO - PID 16089 | Values: 895.0 920.6 928.2 967.1 967.2 43585.6 44263.8 44267.3 44322.8 44395.8 41888.4 42013.0 42039.6 42116.4 42135.6 6960.5 7048.0 7068.5 7075.7 7079.0 6835.4 6888.8 6891.1 6891.2 6894.4 7791.8 7927.9 7947.0 7949.2 7960.9 7776.3 7834.9 7850.1 7921.2 7975.8 7755.1 7829.3 7830.6 7835.2 7837.1 42039.6 42288.4 42321.0 42471.8 42482.4 3523.2 3551.2 3553.9 3554.1 3569.1 32803.0 32830.3 32860.8 32873.8 32937.7 5150.6 5162.1 5184.3 5198.0 5207.8 217701.0 219273.0 221374.0 223648.0 233475.0 54737.5 54806.5 54833.7 54849.0 55004.0 56576.4 56743.7 56956.4 56992.0 56994.7 16835.0 16933.2 17015.7 17029.3 17123.8 13893.5 13913.5 13923.2 13941.1 13960.7 15364.5 15384.5 15398.8 15516.5 15539.5 6122.0 6141.4 6150.4 6200.9 6263.0 1072.3 1091.9 1093.9 1094.6 1099.5 24855.6 24906.5 25080.5 25116.6 25216.0 1188.9 1197.8 1206.2 1210.4 1215.6 13152.2 13241.3 13258.6 13320.1 13364.5 287227.0 288643.0 288792.0 290189.0 290230.0 57921.7 58136.4 58177.4 58256.7 58323.4 1523.7 1542.4 1557.4 1561.9 1571.7 24867.3 25002.6 25064.9 25182.5 25269.2 40800.6 40839.2 41132.0 41224.0 41352.4 233473.0 233639.0 234075.0 234303.0 234312.0 36328.5 42074.6 42289.8 42564.8 42809.0 52261.0 52920.7 53184.9 53205.7 53330.5 37790.0 38200.8 38318.8 38709.0 38915.4 38924.6 38972.0 39110.2 39223.2 39314.0 44637.6 45146.3 45314.1 45844.1 45973.3 37808.8 37964.8 37978.0 38079.6 38245.6 27736.2 28013.0 28461.8 28554.7 28764.6 18:07:02 INFO - PID 16089 | 18:07:02 INFO - PID 16089 | [#2] jquery.html Cycles:180 Average:39182.81 Median:12283.10 stddev:95113.20 (774.3%) stddev-sans-first:95335.82 18:07:02 INFO - PID 16089 | Values: 564.2 574.2 578.7 581.2 590.9 22760.7 23496.3 23707.0 23757.6 23776.4 27059.1 27495.7 27616.3 27635.3 27640.7 6324.5 6455.1 6480.1 6486.8 6489.1 5923.1 6309.3 6472.3 6474.8 6477.3 6454.7 6456.4 6456.5 6457.5 6471.4 6436.7 6443.7 6447.7 6449.7 6456.0 5995.1 6004.3 6011.5 6019.4 6029.9 29747.1 30892.2 31079.0 31285.2 31353.4 2270.4 2306.1 2307.4 2314.0 2316.3 20419.3 20797.3 20962.5 20968.4 20984.7 2459.3 2468.8 2497.6 2526.8 2556.3 148647.0 152928.2 153256.0 153491.0 153796.0 15160.3 15291.9 16062.8 16138.9 16201.5 24340.6 24362.8 24489.5 24509.9 24541.6 10711.9 10797.7 10810.7 10953.3 10971.7 8925.4 9933.5 10040.2 10046.8 10174.5 8792.4 9751.9 10037.3 10139.4 10177.1 2614.9 2617.6 2705.7 2766.2 2846.6 1032.6 1035.1 1051.7 1059.8 1063.0 21628.5 22015.9 22039.8 22171.8 22295.8 1122.8 1126.7 1134.6 1138.5 1148.7 11897.2 12231.4 12324.4 12334.8 12542.8 555073.0 556722.0 564815.0 569422.0 572580.0 132266.0 135040.3 135493.3 140264.3 140762.0 1241.2 1251.9 1278.1 1285.9 1290.5 19372.7 19782.0 19974.0 20047.5 20318.1 65216.3 67487.7 67824.0 68029.0 69410.7 82713.0 83283.0 83604.6 83729.6 83771.0 12759.9 15364.9 15542.1 15589.5 15851.6 24989.0 25011.9 25044.6 25271.5 25287.1 19774.4 20102.5 20204.3 20579.7 20685.4 17197.5 18005.5 18109.3 18113.7 18663.6 21416.6 21451.9 21837.7 22032.6 22035.0 15238.2 15295.5 15473.6 15485.8 15502.1 20027.5 20094.4 20267.7 20448.3 20543.7 18:07:02 INFO - PID 16089 | 18:07:02 INFO - PID 16089 | [#3] mootools.html Cycles:180 Average:9972.60 Median:2781.21 stddev:7428.81 (267.1%) stddev-sans-first:7421.88 18:07:02 INFO - PID 16089 | Values: 1422.7 1471.0 1499.4 1502.6 1515.2 15714.8 15774.2 16279.9 16450.7 16454.3 16483.5 16732.5 16733.3 16749.7 16765.7 2750.5 2803.7 2805.3 2805.3 2805.4 2755.7 2879.2 2881.2 2884.0 2884.5 2773.4 2779.8 2780.5 2792.6 2809.4 2774.6 2781.9 2803.5 2803.7 2805.5 2566.2 2599.8 2610.8 2618.5 2619.6 15926.4 16286.7 16301.9 16305.0 16314.3 7913.4 8110.7 8163.3 8172.3 8184.4 5209.7 5235.1 5341.6 5369.8 5413.5 1949.3 1971.5 1973.3 2003.7 2013.9 28461.7 28716.7 28779.4 28818.0 28820.4 7458.5 7573.8 7750.2 7758.9 7869.3 19422.4 19491.1 19491.8 19619.6 19641.1 7769.6 7947.4 7958.7 7960.2 7963.8 7315.8 7323.0 7428.7 7475.9 7863.0 5362.1 5547.7 5576.4 5608.2 5624.7 1501.4 1507.8 1510.5 1519.2 1521.5 890.6 910.8 913.8 920.4 927.4 7768.1 7811.9 7820.5 7839.6 7966.0 737.9 739.3 745.0 746.5 747.3 4905.3 5049.2 5295.6 5372.8 5494.3 23296.8 23733.6 24064.9 24267.4 24317.6 23541.0 23732.7 23856.8 23883.9 24027.3 12511.2 12529.9 12541.8 12552.1 12587.0 8627.0 8666.8 8717.3 8717.7 8811.5 20139.0 20773.2 20953.3 21208.9 21220.0 20789.2 21132.3 21282.5 21314.6 21440.8 12035.0 12174.8 12177.6 12186.0 12211.4 14497.3 14800.2 14893.0 14904.3 14968.8 11788.2 11817.1 11838.3 12062.8 12127.8 11477.9 11495.1 11577.1 11654.6 11774.5 9902.6 9962.8 9990.0 9992.4 9994.6 7181.9 7211.5 7213.6 7221.6 7271.2 7343.9 7355.4 7395.2 7417.9 7468.3 18:07:02 INFO - PID 16089 | 18:07:02 INFO - PID 16089 | [#4] prototype.html Cycles:180 Average:5595.81 Median:6271.87 stddev:1602.92 (25.6%) stddev-sans-first:1561.23 18:07:02 INFO - PID 16089 | Values: 506.0 507.7 512.6 534.9 541.6 6148.6 6158.0 6211.6 6220.6 6264.6 6123.5 6152.5 6174.5 6191.1 6234.6 4921.3 4943.4 4971.9 4991.9 4994.9 4732.7 4830.9 4916.0 4920.5 4949.1 4739.5 4747.8 4774.5 4777.9 4792.0 4726.8 4740.5 4775.6 4788.5 4790.1 4314.7 4332.2 4335.5 4337.2 4372.2 6106.8 6121.0 6127.8 6147.9 6153.3 5678.5 5706.7 5714.1 5727.6 5739.2 6612.2 6617.9 6622.2 6645.3 6674.6 4913.8 4926.7 4929.2 4931.7 4943.9 6693.8 6781.2 6860.2 6923.9 6944.4 5062.1 5073.2 5074.8 5085.4 5100.9 5119.6 5126.8 5147.6 5180.9 5201.9 5671.3 5756.7 5779.0 5793.3 5799.5 6599.2 6636.6 6652.1 6687.1 6699.6 1775.4 1789.9 1793.7 1802.0 1802.3 1198.8 1200.9 1233.6 1237.4 1249.7 6265.0 6290.8 6362.8 6403.0 6406.7 6688.6 6699.5 6731.9 6743.6 6773.4 6278.7 6301.3 6306.7 6395.8 6401.6 3539.6 3569.0 3585.7 3593.9 3596.4 7059.5 7068.8 7094.1 7112.0 7115.9 6834.7 6837.5 6899.6 6954.3 6954.4 6573.2 6930.6 6994.4 7070.6 7108.8 6606.8 6622.6 6637.7 6707.2 6724.3 6626.9 6655.9 6690.0 6692.1 6717.0 6859.7 6950.7 7003.7 7035.3 7067.3 4987.4 5024.1 5028.6 5059.8 5083.2 6602.6 6752.9 6788.5 6890.9 6928.0 6491.3 6557.3 6569.7 6573.8 6580.6 6573.8 6603.1 6641.4 6676.3 6678.7 6511.4 6580.4 6592.3 6624.2 6637.9 6570.1 6611.6 6661.5 6668.7 6670.9 6428.6 6451.7 6462.4 6477.3 6489.6 18:07:02 INFO - PID 16089 | 18:07:02 INFO - PID 16089 | [#5] yui.html Cycles:180 Average:27774.80 Median:19879.50 stddev:39987.17 (201.1%) stddev-sans-first:40048.57 18:07:02 INFO - PID 16089 | Values: 937.2 948.1 971.9 979.6 994.4 18802.6 18873.7 19343.0 19844.6 19914.4 20480.4 22726.2 22945.3 23004.4 23042.7 1053.8 1066.3 1069.5 1070.1 1073.4 1034.0 1070.7 1071.6 1075.7 1079.2 1129.2 1129.6 1130.8 1131.8 1133.6 1119.4 1119.8 1119.9 1122.1 1122.3 832.9 835.2 835.5 835.8 836.7 20241.5 20257.7 20610.6 20631.7 21221.8 19917.0 20328.0 20340.6 20357.8 20650.9 17049.7 17237.9 17284.4 17335.7 17360.7 3763.5 3779.4 3780.5 3786.4 3792.5 148023.0 148351.0 148690.0 149622.0 150500.0 17312.2 17601.5 17624.8 17630.9 17662.4 33141.0 34074.0 34075.0 34148.0 34197.0 9070.7 9161.5 9166.2 9175.3 9241.1 4263.4 4330.2 4355.4 4357.0 4357.2 6414.8 6591.4 6628.6 6629.5 6667.0 1104.6 1128.5 1134.8 1136.9 1141.5 731.5 751.1 751.2 765.5 773.9 12338.8 12892.4 13125.3 13267.3 13656.6 698.5 701.5 702.1 710.8 713.1 5790.8 5872.0 5960.6 6137.5 6205.8 135473.0 137099.0 138067.3 138646.0 141722.0 121140.4 123205.4 125373.0 126494.4 128348.9 104928.5 106798.5 107088.0 107101.5 107281.5 11194.2 11205.2 11274.4 11424.7 11508.7 50813.2 51884.5 52784.5 53228.7 53717.5 84143.0 86585.6 88607.1 88692.6 88870.2 18106.7 18467.0 18542.3 20061.3 20933.0 19770.8 20010.8 20021.7 20295.7 20519.5 19020.1 19393.3 19539.5 19674.8 20167.6 18034.4 18296.9 18604.5 20444.6 20766.5 19591.7 20109.8 20115.2 20127.7 20865.2 12525.5 12668.0 13418.0 15626.2 15720.1 14176.9 14207.3 14576.3 14730.9 14743.2 18:07:02 INFO - PID 16089 | -------- Summary: end -------- 18:07:02 INFO - PID 16089 | 18:07:03 INFO - TEST-INFO | 16089: exit 0 18:07:03 INFO - TEST-OK | dromaeo_css | took 1110505ms 18:07:03 INFO - TEST-START | kraken 18:07:03 INFO - Initialising browser for kraken test... 18:07:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50835/getInfo.html -profile /tmp/tmptiEqcm/profile 18:07:03 INFO - TEST-INFO | started process 16455 (/builds/slave/test/build/application/firefox/firefox http://localhost:50835/getInfo.html) 18:07:09 INFO - TEST-INFO | 16455: exit 0 18:07:09 INFO - Browser initialized. 18:07:09 INFO - Running cycle 1/1 for kraken test... 18:07:09 INFO - TEST-INFO | started process 16666 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptiEqcm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:07:10 INFO - PID 16666 | 18:07:10 INFO - PID 16666 | (/builds/slave/test/build/application/firefox/firefox:16714): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:07:10 INFO - PID 16666 | 18:07:20 INFO - PID 16666 | RSS: Main: 158007296 18:07:20 INFO - PID 16666 | 18:07:23 INFO - PID 16666 | Cycle 1(1): loaded http://localhost:50835/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50835/tests/kraken/driver.html?testName=audio-beat-detection) 18:07:24 INFO - PID 16666 | RSS: Main: 164843520 18:07:24 INFO - PID 16666 | 18:07:26 INFO - PID 16666 | Cycle 1(1): loaded http://localhost:50835/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50835/tests/kraken/driver.html?testName=audio-dft) 18:07:27 INFO - PID 16666 | RSS: Main: 168116224 18:07:27 INFO - PID 16666 | 18:07:30 INFO - PID 16666 | Cycle 1(1): loaded http://localhost:50835/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50835/tests/kraken/driver.html?testName=audio-fft) 18:07:30 INFO - PID 16666 | RSS: Main: 161517568 18:07:30 INFO - PID 16666 | 18:07:32 INFO - PID 16666 | Cycle 1(1): loaded http://localhost:50835/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50835/tests/kraken/driver.html?testName=audio-oscillator) 18:07:33 INFO - PID 16666 | RSS: Main: 162627584 18:07:33 INFO - PID 16666 | 18:07:35 INFO - PID 16666 | Cycle 1(1): loaded http://localhost:50835/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50835/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:07:36 INFO - PID 16666 | RSS: Main: 162381824 18:07:36 INFO - PID 16666 | 18:07:39 INFO - PID 16666 | Cycle 1(1): loaded http://localhost:50835/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50835/tests/kraken/driver.html?testName=imaging-darkroom) 18:07:39 INFO - PID 16666 | RSS: Main: 162648064 18:07:39 INFO - PID 16666 | 18:07:43 INFO - PID 16666 | Cycle 1(1): loaded http://localhost:50835/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50835/tests/kraken/driver.html?testName=imaging-desaturate) 18:07:43 INFO - PID 16666 | RSS: Main: 164368384 18:07:43 INFO - PID 16666 | 18:07:46 INFO - PID 16666 | Cycle 1(1): loaded http://localhost:50835/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50835/tests/kraken/driver.html?testName=json-parse-financial) 18:07:47 INFO - PID 16666 | RSS: Main: 164851712 18:07:47 INFO - PID 16666 | 18:07:48 INFO - PID 16666 | Cycle 1(1): loaded http://localhost:50835/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50835/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:07:49 INFO - PID 16666 | RSS: Main: 164089856 18:07:49 INFO - PID 16666 | 18:07:51 INFO - PID 16666 | Cycle 1(1): loaded http://localhost:50835/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50835/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:07:51 INFO - PID 16666 | RSS: Main: 164323328 18:07:51 INFO - PID 16666 | 18:07:53 INFO - PID 16666 | Cycle 1(1): loaded http://localhost:50835/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50835/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:07:54 INFO - PID 16666 | RSS: Main: 164118528 18:07:54 INFO - PID 16666 | 18:07:56 INFO - PID 16666 | Cycle 1(1): loaded http://localhost:50835/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50835/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:07:57 INFO - PID 16666 | RSS: Main: 164302848 18:07:57 INFO - PID 16666 | 18:08:00 INFO - PID 16666 | Cycle 1(1): loaded http://localhost:50835/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50835/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:08:01 INFO - PID 16666 | RSS: Main: 165306368 18:08:01 INFO - PID 16666 | 18:08:02 INFO - PID 16666 | Cycle 1(1): loaded http://localhost:50835/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50835/tests/kraken/driver.html?testName=ai-astar) 18:08:03 INFO - PID 16666 | RSS: Main: 166203392 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | __start_tp_report 18:08:03 INFO - PID 16666 | _x_x_mozilla_page_load 18:08:03 INFO - PID 16666 | _x_x_mozilla_page_load_details 18:08:03 INFO - PID 16666 | |i|pagename|runs| 18:08:03 INFO - PID 16666 | |0;ai-astar;97;106;100;106;107;108;109;98;105;107 18:08:03 INFO - PID 16666 | |1;audio-beat-detection;143;112;136;165;113;142;149;115;170;114 18:08:03 INFO - PID 16666 | |2;audio-dft;161;163;161;162;161;161;161;162;163;169 18:08:03 INFO - PID 16666 | |3;audio-fft;76;73;75;172;74;74;74;81;171;74 18:08:03 INFO - PID 16666 | |4;audio-oscillator;78;77;129;78;77;78;129;78;79;129 18:08:03 INFO - PID 16666 | |5;imaging-gaussian-blur;113;113;114;114;113;113;113;114;114;114 18:08:03 INFO - PID 16666 | |6;imaging-darkroom;147;148;147;148;148;148;147;148;148;149 18:08:03 INFO - PID 16666 | |7;imaging-desaturate;94;93;94;94;95;94;94;94;94;93 18:08:03 INFO - PID 16666 | |8;json-parse-financial;48;47;58;48;48;48;48;47;48;49 18:08:03 INFO - PID 16666 | |9;json-stringify-tinderbox;54;53;53;58;53;53;53;55;53;53 18:08:03 INFO - PID 16666 | |10;stanford-crypto-aes;77;73;74;74;74;79;74;72;76;73 18:08:03 INFO - PID 16666 | |11;stanford-crypto-ccm;119;127;121;116;117;122;115;120;118;156 18:08:03 INFO - PID 16666 | |12;stanford-crypto-pbkdf2;173;165;165;163;170;161;164;162;162;164 18:08:03 INFO - PID 16666 | |13;stanford-crypto-sha256-iterative;63;63;61;63;61;61;60;59;62;60 18:08:03 INFO - PID 16666 | __end_tp_report 18:08:03 INFO - PID 16666 | __start_cc_report 18:08:03 INFO - PID 16666 | _x_x_mozilla_cycle_collect,855 18:08:03 INFO - PID 16666 | __end_cc_report 18:08:03 INFO - PID 16666 | __startTimestamp1502672883104__endTimestamp 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | ------- Summary: start ------- 18:08:03 INFO - PID 16666 | Number of tests: 14 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | [#0] ai-astar Cycles:10 Average:104.30 Median:107.50 stddev:4.32 (4.0%) stddev-sans-first:3.69 18:08:03 INFO - PID 16666 | Values: 97.0 106.0 100.0 106.0 107.0 108.0 109.0 98.0 105.0 107.0 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:142.50 stddev:21.81 (15.3%) stddev-sans-first:22.98 18:08:03 INFO - PID 16666 | Values: 143.0 112.0 136.0 165.0 113.0 142.0 149.0 115.0 170.0 114.0 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.00 stddev:2.46 (1.5%) stddev-sans-first:2.55 18:08:03 INFO - PID 16666 | Values: 161.0 163.0 161.0 162.0 161.0 161.0 161.0 162.0 163.0 169.0 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | [#3] audio-fft Cycles:10 Average:94.40 Median:74.00 stddev:40.70 (55.0%) stddev-sans-first:42.62 18:08:03 INFO - PID 16666 | Values: 76.0 73.0 75.0 172.0 74.0 74.0 74.0 81.0 171.0 74.0 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | [#4] audio-oscillator Cycles:10 Average:93.20 Median:78.00 stddev:24.71 (31.7%) stddev-sans-first:25.59 18:08:03 INFO - PID 16666 | Values: 78.0 77.0 129.0 78.0 77.0 78.0 129.0 78.0 79.0 129.0 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | [#5] imaging-gaussian-blur Cycles:10 Average:113.50 Median:114.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 18:08:03 INFO - PID 16666 | Values: 113.0 113.0 114.0 114.0 113.0 113.0 113.0 114.0 114.0 114.0 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 18:08:03 INFO - PID 16666 | Values: 147.0 148.0 147.0 148.0 148.0 148.0 147.0 148.0 148.0 149.0 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 18:08:03 INFO - PID 16666 | Values: 94.0 93.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 93.0 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | [#8] json-parse-financial Cycles:10 Average:48.90 Median:48.00 stddev:3.25 (6.8%) stddev-sans-first:3.43 18:08:03 INFO - PID 16666 | Values: 48.0 47.0 58.0 48.0 48.0 48.0 48.0 47.0 48.0 49.0 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | [#9] json-stringify-tinderbox Cycles:10 Average:53.80 Median:53.00 stddev:1.62 (3.1%) stddev-sans-first:1.72 18:08:03 INFO - PID 16666 | Values: 54.0 53.0 53.0 58.0 53.0 53.0 53.0 55.0 53.0 53.0 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:74.00 stddev:2.12 (2.9%) stddev-sans-first:2.06 18:08:03 INFO - PID 16666 | Values: 77.0 73.0 74.0 74.0 74.0 79.0 74.0 72.0 76.0 73.0 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | [#11] stanford-crypto-ccm Cycles:10 Average:123.10 Median:120.50 stddev:12.06 (10.0%) stddev-sans-first:12.70 18:08:03 INFO - PID 16666 | Values: 119.0 127.0 121.0 116.0 117.0 122.0 115.0 120.0 118.0 156.0 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.90 Median:164.50 stddev:3.78 (2.3%) stddev-sans-first:2.65 18:08:03 INFO - PID 16666 | Values: 173.0 165.0 165.0 163.0 170.0 161.0 164.0 162.0 162.0 164.0 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.50 stddev:1.42 (2.3%) stddev-sans-first:1.36 18:08:03 INFO - PID 16666 | Values: 63.0 63.0 61.0 63.0 61.0 61.0 60.0 59.0 62.0 60.0 18:08:03 INFO - PID 16666 | -------- Summary: end -------- 18:08:03 INFO - PID 16666 | 18:08:03 INFO - PID 16666 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:08:03 INFO - TEST-INFO | 16666: exit 0 18:08:03 INFO - TEST-OK | kraken | took 60197ms 18:08:03 INFO - SUITE-END | took 1170s 18:08:04 INFO - Completed test suite (00:19:31) 18:08:04 INFO - javascript benchmark 18:08:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8999.475581379218, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3468.289000754844, 3488.790672559645, 3509.2981403719255, 3521.4893840393142, 3575.785452872828, 6234.719437625306, 6352.142460767801, 6358.872822543546, 6380.457661098806, 6381.266154392246, 6234.283057448395, 6329.7151628176725, 6337.049442583615, 6341.968290158555, 6357.459615932651, 5530.253415788878, 5532.723363831792, 5537.390887002426, 5540.778368865241, 5562.082256427685, 5579.0, 5591.9720401397935, 5625.296837895263, 5636.943630563683, 5648.43515648436, 5626.127950167731, 5687.17535957286, 5688.516476099516, 5693.715314234292, 5703.74333155009, 5407.675539467645, 5522.061249587579, 5556.638818476827, 5634.859128521779, 5706.828795136153, 5453.481919217668, 5465.207544906006, 5518.558515318805, 5547.362053363874, 5619.1009438490055, 6130.203073600448, 6246.968765156145, 6297.716602752841, 6301.716422760941, 6338.651374174418, 6690.665466726644, 6764.289749576273, 6791.762288319885, 6826.965865170692, 6912.274211207851, 6772.72909083636, 6773.288804675537, 6780.423663988567, 6785.694643741043, 6786.049953006583, 6267.122602835607, 6267.153934218868, 6346.30190679025, 6418.165638467063, 6418.967905160491, 7041.049458323061, 7057.7176912922905, 7114.324139206767, 7130.2869713028285, 7131.429485641136, 6746.325367463312, 6749.662516874137, 6758.189017317954, 6821.113255276881, 6855.314468553204, 6740.39336459721, 6839.350261725128, 6848.486363522656, 6912.0, 6916.965415172892, 6824.454043676593, 6840.281770414134, 6892.379685828299, 6924.307569243136, 6927.618980956096, 6799.830004249933, 6870.519063665495, 6895.689693963784, 6914.343137401939, 6918.06606108179, 4904.2643603459765, 4928.137575924199, 4977.552020318189, 4982.402111746614, 4989.101961646866, 4320.524742278347, 4353.73877567347, 4379.277419225792, 4392.714473559208, 4442.555744425532, 6464.903026454607, 6479.6760161991715, 6482.935170648328, 6496.610203387718, 6512.869742605216, 6804.387605115563, 6811.4550835933005, 6882.0, 6887.931120688829, 6896.103506544117, 6421.422072013448, 6433.549651524442, 6443.420092191725, 6498.285188629247, 6498.447631951242, 5426.810061647823, 5479.5342395896, 5480.534154596824, 5520.779168833282, 5528.44715528444, 7205.495615306878, 7244.782656520313, 7270.63646817657, 7278.8180295491975, 7280.41756659466, 6770.0, 6880.208775990825, 6926.272741362084, 6936.687849046704, 6943.097397338313, 7031.859362812817, 7166.355028047501, 7171.4621403395995, 7177.4616903731385, 7261.782146535613, 6711.3288671132495, 6718.126643536309, 6722.899156512558, 6753.86492270142, 6812.420944219797, 6862.725490980305, 6926.896096558455, 7000.054992576141, 7001.50989430735, 7008.789736307919, 6678.432333251533, 6680.0647909291765, 6686.7993960181275, 6774.085498457645, 6775.89836152468, 6531.836704082436, 6633.270340262471, 6637.070810086689, 6685.532012759255, 6736.225334086643, 6599.208095028627, 6671.966640166962, 6674.16572928384, 6683.0, 6683.064370988162, 6526.608403495806, 6622.304658011032, 6626.304238054935, 6704.329567043256, 6718.596884186964, 6421.775237866839, 6446.4520515756685, 6447.323031081669, 6463.64449955257, 6470.191226096738], "value": 6224.069917305035, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [895.0236684036756, 920.5719340506663, 928.1692884868041, 967.1489089601158, 967.1778987860315, 43585.56414435855, 44263.77868110663, 44267.3359899601, 44322.778386108104, 44395.77802110989, 41888.37167442491, 42013.0, 42039.57960420395, 42116.36825447613, 42135.57864421355, 6960.477964152697, 7048.04851345067, 7068.540544864567, 7075.716971321141, 7078.964605176967, 6835.384815366616, 6888.827779305532, 6891.069705589732, 6891.173059232874, 6894.379505844473, 7791.844163116734, 7927.8810817837775, 7946.960265198666, 7949.244821741925, 7960.880586791202, 7776.300132988002, 7834.882476762881, 7850.136484986676, 7921.168277330884, 7975.840483190362, 7755.108162561265, 7829.295363417263, 7830.647620857018, 7835.177306382861, 7837.1379148293645, 42039.57960420418, 42288.365674514906, 42321.0, 42471.78764106159, 42482.36276455856, 3523.2072783623735, 3551.2187318789825, 3553.9111522211892, 3554.111472131967, 3569.0542006368332, 32803.0, 32830.34339313224, 32860.83569582161, 32873.83563082193, 32937.6706232937, 5150.562202212816, 5162.096633089194, 5184.300119483897, 5197.974010129963, 5207.817726379559, 217701.0, 219273.0, 221374.0, 223648.0, 233475.0, 54737.452625474034, 54806.45193548093, 54833.72583137059, 54848.983305918555, 55004.0, 56576.434235657114, 56743.716281419154, 56956.43043569511, 56992.0, 56994.7150264246, 16835.0, 16933.238004289837, 17015.744763828552, 17029.318827247018, 17123.828761712473, 13893.513727019506, 13913.513027044004, 13923.164610123426, 13941.093828901088, 13960.650983725489, 15364.462243821412, 15384.461543845911, 15398.769018464733, 15516.45692400783, 15539.5338139856, 6121.96939015311, 6141.416565426277, 6150.3849615039035, 6200.875982480326, 6263.029230469285, 1072.3297938788257, 1091.8744344400336, 1093.9234253602172, 1094.5676457799125, 1099.4777480696764, 24855.62716559253, 24906.5018699625, 25080.4983900321, 25116.62325065089, 25216.0, 1188.8870557297043, 1197.820326950947, 1206.2400687566776, 1210.3887536793864, 1215.574548907893, 13152.21086734799, 13241.337933103307, 13258.602241932758, 13320.134191277535, 13364.465421383036, 287227.0, 288643.0, 288792.0, 290189.0, 290230.0, 57921.71039144777, 58136.41863581395, 58177.4182258172, 58256.708716456145, 58323.41676583265, 1523.6647937453743, 1542.3753379881166, 1557.447106277278, 1561.9453319134002, 1571.6856628674082, 24867.253982380556, 25002.62496062597, 25064.874675626506, 25182.496350073263, 25269.24192274235, 40800.59199408087, 40839.18321633491, 41132.0, 41224.0, 41352.37971430371, 233473.0, 233639.0, 234075.0, 234303.0, 234312.0, 36328.4550731734, 42074.57925420706, 42289.78855105705, 42564.787176063925, 42809.0, 52261.0, 52920.73539632277, 53184.93630127298, 53205.73397132989, 53330.4666953341, 37790.0, 38200.808995954845, 38318.80840595779, 38709.03227419337, 38915.41626875543, 38924.610753893234, 38972.0, 39110.21779564336, 39223.2155356897, 39314.0, 44637.55362446334, 45146.322805157295, 45314.09371812611, 45844.083118338116, 45973.31040034469, 37808.810955945046, 37964.810175948944, 37978.0, 38079.61920380872, 38245.617543824206, 27736.167914962585, 28013.01954431585, 28461.77678723108, 28554.714452855205, 28764.568531471625], "value": 18788.59944183514, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.235460950412, 574.2391331485783, 578.7106446776612, 581.1515187825772, 590.929088509379, 22760.65859012114, 23496.295111146694, 23707.0, 23757.643635345485, 23776.405589860264, 27059.105862589626, 27495.725042749567, 27616.309592260204, 27635.309117272078, 27640.723592764065, 6324.462420694246, 6455.064015717718, 6480.060391243267, 6486.837829054288, 6489.123968264271, 5923.111533270001, 6309.3375195604485, 6472.288048314682, 6474.838129046764, 6477.255115661688, 6454.709538070774, 6456.35436456353, 6456.548041637087, 6457.483401327882, 6471.352864713538, 6436.710348034349, 6443.742250310005, 6447.709853056577, 6449.742010319582, 6455.9677201613695, 5995.130706047598, 6004.3395226525045, 6011.549133814981, 6019.367966363557, 6029.909551356689, 29747.107586772432, 30892.227694307596, 31079.0, 31285.217869553217, 31353.37293254145, 2270.421042634118, 2306.054517647773, 2307.40007598024, 2313.9652905206435, 2316.328264803205, 20419.285325013552, 20797.272095476586, 20962.475938101517, 20968.370948871558, 20984.685229721566, 2459.311392810016, 2468.8148388870727, 2497.625356196567, 2526.772590466848, 2556.271462633141, 148647.0, 152928.23535882361, 153256.0, 153491.0, 153796.0, 15160.31778569967, 15291.923540382228, 16062.75905861413, 16138.919305403399, 16201.513954581382, 24340.634890476656, 24362.756372436404, 24489.510209795706, 24509.87745061299, 24541.63187552188, 10711.89288107109, 10797.730056748487, 10810.729731756768, 10953.34279943206, 10971.670849874517, 8925.375223734276, 9933.453660048623, 10040.196784257241, 10046.799064018678, 10174.542145603466, 8792.42849214799, 9751.902480975099, 10037.34757240777, 10139.442330671736, 10177.134943529883, 2614.9084782032537, 2617.5550156473446, 2705.742954419327, 2766.21029190011, 2846.644169478815, 1032.6334151376304, 1035.0787798859021, 1051.6582110814015, 1059.782744537368, 1063.0, 21628.4592885176, 22015.889920550293, 22039.779602204093, 22171.778282217296, 22295.77704222937, 1122.8484154639182, 1126.7408496045791, 1134.5915470430639, 1138.538891748843, 1148.661144962238, 11897.167198296209, 12231.388430578614, 12324.445399956845, 12334.81497777534, 12542.81185782214, 555073.0, 556722.0, 564815.0, 569422.0, 572580.0, 132266.0, 135040.32479837537, 135493.3225333867, 140264.29867850596, 140762.0, 1241.1684171605045, 1251.9123661343801, 1278.0670110819153, 1285.9357032148355, 1290.541857640524, 19372.709409358587, 19782.010899454683, 19974.0, 20047.49881252922, 20318.085686143862, 65216.347836521025, 67487.66256168688, 67824.0, 68029.0, 69410.65294673495, 82713.0, 83283.0, 83604.58197708972, 83729.58135209528, 83771.0, 12759.915407190121, 15364.923175384052, 15542.145182015098, 15589.532314030597, 15851.603709906884, 24989.0, 25011.87494062518, 25044.62433063469, 25271.49457010886, 25287.11495097663, 19774.40676779699, 20102.49743756418, 20204.29284975019, 20579.69130463014, 20685.379438616867, 17197.484075477754, 18005.549861253574, 18109.275628974694, 18113.728294075336, 18663.626727465646, 21416.571668566852, 21451.892740536197, 21837.672434913173, 22032.559348813254, 22035.0, 15238.161901095104, 15295.541133766004, 15473.613159670647, 15485.76113910907, 15502.069875807487, 20027.499312517302, 20094.39716808498, 20267.69598455995, 20448.284310049075, 20543.69184462264], "value": 12826.060871191541, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.6727852593904, 1471.0438215160145, 1499.3627708224003, 1502.5567457600007, 1515.1590867068771, 15714.764278535831, 15774.211289435525, 16279.918600406982, 16450.670986580277, 16454.34182632695, 16483.505494835143, 16732.498025059238, 16733.330666773316, 16749.66500669986, 16765.7485137723, 2750.518659234636, 2803.733645303693, 2805.270629636299, 2805.2846524136316, 2805.3547684032583, 2755.6968733439307, 2879.17943386135, 2881.164462305939, 2884.0, 2884.480793457177, 2773.362126710858, 2779.833210007396, 2780.4995100881833, 2792.6229958955587, 2809.3819359740824, 2774.5838124281318, 2781.930451738692, 2803.495370833249, 2803.7056109108453, 2805.4950108980374, 2566.230130960704, 2599.7790187834057, 2610.843349399042, 2618.463215040931, 2619.6463477430684, 15926.362945482168, 16286.674266514603, 16301.918490407472, 16305.021698698116, 16314.347426102942, 7913.406494512935, 8110.675572977074, 8163.346932245406, 8172.264496195371, 8184.427090103695, 5209.6874187548865, 5235.083860324428, 5341.599380046512, 5369.838904832822, 5413.53984911283, 1949.278766856258, 1971.5366888781125, 1973.2797528901847, 2003.719479272903, 2013.8590298679098, 28461.71538284632, 28716.712832871614, 28779.424411511653, 28818.0, 28820.423591528262, 7458.5151965122095, 7573.772786816405, 7750.224977502205, 7758.874963130314, 7869.3311068559815, 19422.417327480198, 19491.12289946956, 19491.805081949282, 19619.607607847764, 19641.116149773294, 7769.6115194240065, 7947.4436789425345, 7958.681652733826, 7960.164182760726, 7963.800904977306, 7315.817104572428, 7323.0, 7428.702851885972, 7475.925240747523, 7862.960685196652, 5362.142057270896, 5547.694876781735, 5576.386597474275, 5608.214849921014, 5624.746886390122, 1501.399440223919, 1507.826599940999, 1510.4788847847476, 1519.2100107943807, 1521.5131158029546, 890.5591732092655, 910.8041771019263, 913.8218047480677, 920.4339331311329, 927.3786563002805, 7768.10666773317, 7811.921880781233, 7820.452568320163, 7839.608019599112, 7965.960170199112, 737.8561180569737, 739.3050532499433, 744.9590272534954, 746.4588173574115, 747.3274053352027, 4905.288733133676, 5049.192129259301, 5295.602829787781, 5372.811951581676, 5494.313210848644, 23296.76703232946, 23733.643995340084, 24064.87967560151, 24267.39331516726, 24317.635235471484, 23541.0, 23732.69470179122, 23856.761432385454, 23883.92522336499, 24027.27918162458, 12511.249325040344, 12529.874701252871, 12541.811872821914, 12552.058595605366, 12586.993040556734, 8626.956865215634, 8666.78333041679, 8717.259032982267, 8717.694880679146, 8811.471311721318, 20139.0, 20773.169073236902, 20953.26663566768, 21208.893955530122, 21220.04509797032, 20789.1684332625, 21132.260370886946, 21282.467938301666, 21314.57370852605, 21440.785592144504, 12035.0, 12174.817377739164, 12177.634670959886, 12186.0, 12211.389430528261, 14497.275136243357, 14800.185989770665, 14893.0, 14904.329305181076, 14968.775468367765, 11788.23376480526, 11817.113716471471, 11838.348890811088, 12062.819057714318, 12127.81808272859, 11477.942610286895, 11495.080393568662, 11577.073834093084, 11654.592089276835, 11774.529018839494, 9902.603895844373, 9962.800743984935, 9989.950050249703, 9992.400455972665, 9994.60021599157, 7181.892271615826, 7211.531250468703, 7213.567185968858, 7221.602811845189, 7271.200167981412, 7343.926560734324, 7355.411567074514, 7395.223501532477, 7417.851642967218, 7468.3278504934815], "value": 6940.35025237785, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.959523238141, 507.6801614982561, 512.6155383462403, 534.8898595042558, 541.6371031408955, 6148.600340977839, 6158.0, 6211.627302361861, 6220.564560480768, 6264.561480696341, 6123.540734444923, 6152.477039451652, 6174.475169610577, 6191.133241346204, 6234.563580549352, 4921.335619691332, 4943.431505376874, 4971.925421118686, 4991.850244492662, 4994.875128121808, 4732.698507910335, 4830.9275360869615, 4915.950840491585, 4920.507949205086, 4949.059678661061, 4739.549742774431, 4747.810087596492, 4774.546418090275, 4777.90444191116, 4792.017636384533, 4726.810927562893, 4740.454847692507, 4775.570198682118, 4788.497207793166, 4790.089882922233, 4314.654827613784, 4332.176886391576, 4335.523092459827, 4337.219300525904, 4372.19114463822, 6106.816795496142, 6121.0, 6127.816165514997, 6147.907781383283, 6153.292371377304, 5678.545716342683, 5706.743196556165, 5714.057180565202, 5727.627704199255, 5739.225204597367, 6612.239592446882, 6617.9153566626355, 6622.205335359739, 6645.3354664533645, 6674.599524028574, 4913.778879950411, 4926.729029903354, 4929.2113261878285, 4931.742405686563, 4943.9258411123865, 6693.7575912069515, 6781.152355955505, 6860.211075726255, 6923.861522769566, 6944.4097251733665, 5062.11413002723, 5073.188289873602, 5074.797008119634, 5085.440601533828, 5100.897982040413, 5119.641625086283, 5126.846194614168, 5147.613928955343, 5180.896382072413, 5201.947980520146, 5671.326958547156, 5756.740946657326, 5779.0, 5793.275840519935, 5799.492132045699, 6599.175103112111, 6636.568623039583, 6652.135222421054, 6687.097241872285, 6699.564528305645, 1775.3874913154941, 1789.8926064436175, 1793.6771381151257, 1801.972970405445, 1802.2610729601063, 1198.8141838015122, 1200.9131735779133, 1233.6360773571819, 1237.3813093453273, 1249.7375551134364, 6265.0, 6290.779822706183, 6362.809115726535, 6402.967985160044, 6406.743730250831, 6688.592106841533, 6699.531032827753, 6731.865362692719, 6743.629100399525, 6773.390394864486, 6278.748850045947, 6301.306856245809, 6306.684665766785, 6395.776147834804, 6401.64790936503, 3539.628339024417, 3568.965689556928, 3585.695215906625, 3593.874214402483, 3596.4175762664345, 7059.505834591632, 7068.7525936593, 7094.077769889881, 7112.039874616964, 7115.857682846417, 6834.6582670866255, 6837.453003759687, 6899.551529150589, 6954.3045695431065, 6954.374106330454, 6573.209634463943, 6930.5792312574795, 6994.405475534637, 7070.611116388648, 7108.822279443055, 6606.801795945937, 6622.63575503352, 6637.6681165940545, 6707.155532228676, 6724.260331363644, 6626.933730662631, 6655.866882662416, 6690.0, 6692.130023096966, 6716.992451132369, 6859.691313890987, 6950.687219075053, 7003.719851205895, 7035.261297563682, 7067.328603782633, 4987.426445958616, 5024.120778863611, 5028.622853285945, 5059.822906198266, 5083.237514372874, 6602.566665333903, 6752.898706519504, 6788.522189400932, 6890.8621827564175, 6928.0, 6491.253505846888, 6557.311482294291, 6569.671516424064, 6573.835654108686, 6580.638064906476, 6573.835654108686, 6603.127639476843, 6641.435477984425, 6676.298988606321, 6678.662468136114, 6511.413972742476, 6580.374864387875, 6592.340765923561, 6624.171978502687, 6637.909058646511, 6570.145881035626, 6611.636360000246, 6661.533692641468, 6668.698993998002, 6670.933290667225, 6428.614283143026, 6451.741930322734, 6462.418382345423, 6477.287498375269, 6489.610623362613], "value": 5124.066292005588, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [937.1752857485412, 948.0708905272833, 971.9028097190278, 979.5560077606373, 994.4132961552682, 18802.623947521042, 18873.716894246598, 19343.0, 19844.603107937834, 19914.40256792295, 20480.385588432335, 22726.204582839604, 22945.311640650765, 23004.424889377733, 23042.65436018461, 1053.8419237114417, 1066.2696053203542, 1069.5400977579638, 1070.1331921143899, 1073.404260635347, 1033.9896601033968, 1070.6948519671896, 1071.614218881202, 1075.6611667324808, 1079.1744315598573, 1129.232122156933, 1129.6441620889434, 1130.8416821644976, 1131.7510147767478, 1133.557912414155, 1119.356370087195, 1119.8488204092466, 1119.9048080913133, 1122.1247427006947, 1122.3041714137287, 832.9000519937646, 835.1982097186708, 835.4945258118811, 835.7868743470437, 836.7489753074053, 20241.493962650904, 20257.696134557995, 20610.587788244302, 20631.69052464214, 21221.787782122137, 19917.004149792454, 20328.0, 20340.593188136154, 20357.796422035888, 20650.896745516326, 17049.7442538362, 17237.913810430993, 17284.39504617345, 17335.739963900553, 17360.652786944316, 3763.473113764075, 3779.4141908004303, 3780.527434070741, 3786.3941769316775, 3792.4500947362476, 148023.0, 148350.99999999892, 148690.0, 149622.0, 150500.0, 17312.220950057024, 17601.471955841345, 17624.823751762317, 17630.91184544069, 17662.381816636356, 33141.0, 34074.0, 34075.0, 34148.0, 34197.0, 9070.682526111686, 9161.450312981242, 9166.175044245916, 9175.311851611155, 9241.075892410705, 4263.424437700933, 4330.155619654201, 4355.412019377407, 4356.956430435718, 4357.172137293904, 6414.839629009312, 6591.373819487141, 6628.5691430056895, 6629.535932484776, 6667.0, 1104.640991677708, 1128.4527004402858, 1134.8354488599273, 1136.8635763708241, 1141.5205613642124, 731.5098883747902, 751.1174370115093, 751.1624538639487, 765.4985984180371, 773.8955241042387, 12338.75322493563, 12892.355382230851, 13125.34373281332, 13267.270300133585, 13656.590302290748, 698.4656737595743, 701.5264696329957, 702.1293595940996, 710.8365076032542, 713.0552018575305, 5790.7973220937065, 5871.970640146772, 5960.612560183574, 6137.478314343241, 6205.751769929243, 135473.0, 137099.0, 138067.30966345104, 138646.0, 141722.0, 121140.39429802971, 123205.38397307956, 125373.0, 126494.36752816178, 128348.9407583407, 104928.47535762272, 106798.46600766947, 107088.0, 107101.46449267704, 107281.46359268154, 11194.216404851908, 11205.159613028893, 11274.379909105079, 11424.657260282205, 11508.654740357802, 50813.237801433745, 51884.48115518797, 52784.47215527796, 53228.73385633047, 53717.46282537124, 84143.0, 86585.56707216424, 88607.11392886247, 88692.55653721691, 88870.15008781847, 18106.72839907376, 18467.0, 18542.258309668003, 20061.29785457502, 20933.0, 19770.802291976896, 20010.799892001476, 20021.6996745046, 20295.69556456625, 20519.4870128248, 19020.144093515548, 19393.32123375675, 19539.51151221231, 19674.803251967296, 20167.596648066665, 18034.36879709204, 18296.90851545787, 18604.534886627942, 20444.59110817805, 20766.480837979172, 19591.70612440843, 20109.798902010793, 20115.19539218415, 20127.698084528452, 20865.165393384093, 12525.498980040697, 12668.0, 13418.0, 15626.218689065729, 15720.135392553519, 14176.929115354356, 14207.28963551839, 14576.34406451691, 14730.852691472948, 14743.18912459825], "value": 9959.024127731327, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1471.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 106.0, 100.0, 106.0, 107.0, 108.0, 109.0, 98.0, 105.0, 107.0], "value": 104.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 112.0, 136.0, 165.0, 113.0, 142.0, 149.0, 115.0, 170.0, 114.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 162.0, 161.0, 161.0, 161.0, 162.0, 163.0, 169.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 75.0, 172.0, 74.0, 74.0, 74.0, 81.0, 171.0, 74.0], "value": 94.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 129.0, 78.0, 77.0, 78.0, 129.0, 78.0, 79.0, 129.0], "value": 93.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 114.0, 113.0, 113.0, 113.0, 114.0, 114.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 148.0, 148.0, 148.0, 147.0, 148.0, 148.0, 149.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 47.0, 58.0, 48.0, 48.0, 48.0, 48.0, 47.0, 48.0, 49.0], "value": 48.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 58.0, 53.0, 53.0, 53.0, 55.0, 53.0, 53.0], "value": 53.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 74.0, 74.0, 74.0, 79.0, 74.0, 72.0, 76.0, 73.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 127.0, 121.0, 116.0, 117.0, 122.0, 115.0, 120.0, 118.0, 156.0], "value": 123.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 165.0, 165.0, 163.0, 170.0, 161.0, 164.0, 162.0, 162.0, 164.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 61.0, 63.0, 61.0, 61.0, 60.0, 59.0, 62.0, 60.0], "value": 61.3, "unit": "score"}]}]} 18:08:04 INFO - Return code: 0 18:08:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:08:04 INFO - # TBPL SUCCESS # 18:08:04 INFO - Running post-action listener: _package_coverage_data 18:08:04 INFO - Running post-action listener: _resource_record_post_action 18:08:04 INFO - [mozharness: 2017-08-14 01:08:04.391897Z] Finished run-tests step (success) 18:08:04 INFO - Running post-run listener: _resource_record_post_run 18:08:04 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 429469696; Read time: 16; Write time: 345964 18:08:04 INFO - TinderboxPrint: CPU usage
13.1% 18:08:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 18:08:04 INFO - TinderboxPrint: I/O write bytes / time
429,469,696 / 345,964 18:08:04 INFO - TinderboxPrint: CPU idle
8,241.6 (86.9%) 18:08:04 INFO - TinderboxPrint: CPU user
1,189.5 (12.5%) 18:08:04 INFO - TinderboxPrint: Swap in / out
0 / 0 18:08:04 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 108257280; Read time: 0; Write time: 104764 18:08:04 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:08:04 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 321212416; Read time: 0; Write time: 241200 18:08:05 INFO - Running post-run listener: _upload_blobber_files 18:08:05 INFO - Blob upload gear active. 18:08:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:08:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:08:05 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'] 18:08:05 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 18:08:05 INFO - (blobuploader) - INFO - Open directory for files ... 18:08:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:08:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:08:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:08:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:08:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:08:06 INFO - (blobuploader) - INFO - Done attempting. 18:08:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:08:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:08:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:08:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:08:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:08:13 INFO - (blobuploader) - INFO - Done attempting. 18:08:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:08:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:08:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:08:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:08:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:08:13 INFO - (blobuploader) - INFO - Done attempting. 18:08:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:08:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:08:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:08:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:08:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:08:14 INFO - (blobuploader) - INFO - Done attempting. 18:08:14 INFO - (blobuploader) - INFO - Iteration through files over. 18:08:14 INFO - Return code: 0 18:08:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:08:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:08:14 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3f483325e5ea1c34fd485c52ce9a2ca4133480776b520202249e5afdbb0b9fec08aef0fdf10e3f81c35c54a5fe3fc82313bd069ecd394d0bd5cf7cddb9e4ec5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5295fb9dc9b8312b1de8c6833f7b4fa534ef1f42419c3c3f77ae75046dea40c5d853b78a909fb94c997285d5606c5d1c96e16e45b9c966fabdbac2fafc4bc508", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f6311d52eb2ff4ed8a1f5df41ffe49672e7cbbd761193ec43d4cc07b6b70f063e66d3480d09d3c84d6aafea33fca48db6b85d76853348b937933572698ea4d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/310d71c4c4480dbad1c338be584fbde50428f969d1df590039ed6f99626ac0c3dbb11d602d864d4d2ecdceccdc2d46389738d0341d438a09717223e52610ceb7"} 18:08:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:08:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:08:14 INFO - Contents: 18:08:14 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3f483325e5ea1c34fd485c52ce9a2ca4133480776b520202249e5afdbb0b9fec08aef0fdf10e3f81c35c54a5fe3fc82313bd069ecd394d0bd5cf7cddb9e4ec5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5295fb9dc9b8312b1de8c6833f7b4fa534ef1f42419c3c3f77ae75046dea40c5d853b78a909fb94c997285d5606c5d1c96e16e45b9c966fabdbac2fafc4bc508", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f6311d52eb2ff4ed8a1f5df41ffe49672e7cbbd761193ec43d4cc07b6b70f063e66d3480d09d3c84d6aafea33fca48db6b85d76853348b937933572698ea4d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/310d71c4c4480dbad1c338be584fbde50428f969d1df590039ed6f99626ac0c3dbb11d602d864d4d2ecdceccdc2d46389738d0341d438a09717223e52610ceb7"} 18:08:14 INFO - Running post-run listener: copy_logs_to_upload_dir 18:08:14 INFO - Copying logs to upload dir... 18:08:14 INFO - mkdir: /builds/slave/test/build/upload/logs 18:08:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.145312 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-13 18:08:13.555956) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-13 18:08:13.559270) ========= 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=ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389 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/c3f483325e5ea1c34fd485c52ce9a2ca4133480776b520202249e5afdbb0b9fec08aef0fdf10e3f81c35c54a5fe3fc82313bd069ecd394d0bd5cf7cddb9e4ec5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5295fb9dc9b8312b1de8c6833f7b4fa534ef1f42419c3c3f77ae75046dea40c5d853b78a909fb94c997285d5606c5d1c96e16e45b9c966fabdbac2fafc4bc508", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f6311d52eb2ff4ed8a1f5df41ffe49672e7cbbd761193ec43d4cc07b6b70f063e66d3480d09d3c84d6aafea33fca48db6b85d76853348b937933572698ea4d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/310d71c4c4480dbad1c338be584fbde50428f969d1df590039ed6f99626ac0c3dbb11d602d864d4d2ecdceccdc2d46389738d0341d438a09717223e52610ceb7"} build_url:https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011190 build_url: 'https://queue.taskcluster.net/v1/task/HWeJi78SRdSP1Ky9h0TZhA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3f483325e5ea1c34fd485c52ce9a2ca4133480776b520202249e5afdbb0b9fec08aef0fdf10e3f81c35c54a5fe3fc82313bd069ecd394d0bd5cf7cddb9e4ec5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5295fb9dc9b8312b1de8c6833f7b4fa534ef1f42419c3c3f77ae75046dea40c5d853b78a909fb94c997285d5606c5d1c96e16e45b9c966fabdbac2fafc4bc508", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f6311d52eb2ff4ed8a1f5df41ffe49672e7cbbd761193ec43d4cc07b6b70f063e66d3480d09d3c84d6aafea33fca48db6b85d76853348b937933572698ea4d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/310d71c4c4480dbad1c338be584fbde50428f969d1df590039ed6f99626ac0c3dbb11d602d864d4d2ecdceccdc2d46389738d0341d438a09717223e52610ceb7"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-13 18:08:13.583762) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-13 18:08:13.584083) ========= 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=ea65d483da93c827905c2090000001f5-1502671326.126273-1113318389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004535 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-13 18:08:13.665816) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-13 18:08:13.666146) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-13 18:08:13.666455) ========= ========= Total master_lag: 0.15 =========