builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-010 starttime: 1502449896.6 results: success (0) revision: 64f5e3f40f0e7accb12cd90080e58eff0be46383 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:36.603231) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:36.603597) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:36.603877) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:36.635247) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:36.635503) ========= 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=eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-11 04:11:36-- 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% 223M=0s 2017-08-11 04:11:36 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.084622 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:36.746209) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:36.746506) ========= 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=eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018409 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:36.802272) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:36.802570) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 64f5e3f40f0e7accb12cd90080e58eff0be46383 --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 64f5e3f40f0e7accb12cd90080e58eff0be46383 --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=eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-11 04:11:36,990 truncating revision to first 12 chars 2017-08-11 04:11:36,990 Setting DEBUG logging. 2017-08-11 04:11:36,990 attempt 1/10 2017-08-11 04:11:36,990 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/64f5e3f40f0e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-11 04:11:37,427 unpacking tar archive at: mozilla-central-64f5e3f40f0e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.749480 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:37.567677) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:37.571006) ========= script_repo_revision: 64f5e3f40f0e7accb12cd90080e58eff0be46383 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:37.571384) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:37.571645) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-11 04:11:37.599285) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-11 04:11:37.599709) ========= /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=eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:11:37 INFO - MultiFileLogger online at 20170811 04:11:37 in /builds/slave/test 04:11:37 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 04:11:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:11:37 INFO - {'append_to_log': False, 04:11:37 INFO - 'base_work_dir': '/builds/slave/test', 04:11:37 INFO - 'blob_upload_branch': 'Firefox', 04:11:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:11:37 INFO - 'branch': 'Firefox', 04:11:37 INFO - 'buildbot_json_path': 'buildprops.json', 04:11:37 INFO - 'code_coverage': False, 04:11:37 INFO - 'config_files': ('talos/linux_config.py',), 04:11:37 INFO - 'default_actions': ('clobber', 04:11:37 INFO - 'read-buildbot-config', 04:11:37 INFO - 'download-and-extract', 04:11:37 INFO - 'populate-webroot', 04:11:37 INFO - 'create-virtualenv', 04:11:37 INFO - 'install', 04:11:37 INFO - 'setup-mitmproxy', 04:11:37 INFO - 'run-tests'), 04:11:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:11:37 INFO - 'disable_ccov_upload': False, 04:11:37 INFO - 'download_minidump_stackwalk': True, 04:11:37 INFO - 'download_symbols': 'ondemand', 04:11:37 INFO - 'enable_stylo': False, 04:11:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:11:37 INFO - 'tooltool.py': '/tools/tooltool.py', 04:11:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:11:37 INFO - '/tools/misc-python/virtualenv.py')}, 04:11:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:11:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:11:37 INFO - 'gecko_profile': False, 04:11:37 INFO - 'gecko_profile_interval': 0, 04:11:37 INFO - 'installer_path': 'installer.exe', 04:11:37 INFO - 'log_level': 'info', 04:11:37 INFO - 'log_name': 'talos', 04:11:37 INFO - 'log_to_console': True, 04:11:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:11:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:11:37 INFO - 'opt_config_files': (), 04:11:37 INFO - 'pip_index': False, 04:11:37 INFO - 'suite': 'dromaeojs-e10s', 04:11:37 INFO - 'system_bits': '32', 04:11:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:11:37 INFO - 'title': 'talos-linux64-ix-010', 04:11:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:11:37 INFO - 'use_talos_json': True, 04:11:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:11:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:11:37 INFO - 'work_dir': 'build'} 04:11:37 INFO - [mozharness: 2017-08-11 11:11:37.894972Z] Running clobber step. 04:11:37 INFO - Running pre-action listener: _resource_record_pre_action 04:11:37 INFO - Running main action method: clobber 04:11:37 INFO - rmtree: /builds/slave/test/build 04:11:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:11:39 INFO - Running post-action listener: _resource_record_post_action 04:11:39 INFO - [mozharness: 2017-08-11 11:11:39.441925Z] Finished clobber step (success) 04:11:39 INFO - [mozharness: 2017-08-11 11:11:39.442027Z] Running read-buildbot-config step. 04:11:39 INFO - Running pre-action listener: _resource_record_pre_action 04:11:39 INFO - Running main action method: read_buildbot_config 04:11:39 INFO - Using buildbot properties: 04:11:39 INFO - { 04:11:39 INFO - "project": "", 04:11:39 INFO - "product": "firefox", 04:11:39 INFO - "who": "archaeopteryx@coole-files.de", 04:11:39 INFO - "installer_path": "public/build/target.tar.bz2", 04:11:39 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:11:39 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:11:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:11:39 INFO - "stage_platform": "linux64-pgo", 04:11:39 INFO - "basedir": "/builds/slave/test", 04:11:39 INFO - "buildnumber": 106, 04:11:39 INFO - "platform": "ubuntu64_hw", 04:11:39 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:11:39 INFO - "slavebuilddir": "test", 04:11:39 INFO - "taskId": "eMDfQrq3TDahZXzFtTSrNA", 04:11:39 INFO - "branch": "mozilla-central", 04:11:39 INFO - "script_repo_revision": "production", 04:11:39 INFO - "revision": "64f5e3f40f0e7accb12cd90080e58eff0be46383", 04:11:39 INFO - "slavename": "talos-linux64-ix-010", 04:11:39 INFO - "repo_path": "mozilla-central" 04:11:39 INFO - } 04:11:39 INFO - Finding installer, test and symbols from parent task. 04:11:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eMDfQrq3TDahZXzFtTSrNA'}, attempt #1 04:11:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eMDfQrq3TDahZXzFtTSrNA'}, attempt #1 04:11:40 INFO - Task dependencies: GGVDEeRYQcC1WejYlVPkgg 04:11:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg'}, attempt #1 04:11:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.tar.bz2 04:11:40 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.test_packages.json 04:11:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.crashreporter-symbols.zip 04:11:40 INFO - Running post-action listener: _resource_record_post_action 04:11:40 INFO - [mozharness: 2017-08-11 11:11:40.817063Z] Finished read-buildbot-config step (success) 04:11:40 INFO - [mozharness: 2017-08-11 11:11:40.817286Z] Running download-and-extract step. 04:11:40 INFO - Running pre-action listener: _resource_record_pre_action 04:11:40 INFO - Running main action method: download_and_extract 04:11:40 INFO - mkdir: /builds/slave/test/build/tests 04:11:40 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')]} 04:11:40 INFO - trying https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.test_packages.json 04:11:40 INFO - Downloading https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:11:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:11:41 INFO - Downloaded 989 bytes. 04:11:41 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:11:41 INFO - Using the following test package requirements: 04:11:41 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:11:41 INFO - u'common': [u'target.common.tests.zip'], 04:11:41 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:11:41 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:11:41 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:11:41 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:11:41 INFO - u'mozbase': [u'target.common.tests.zip'], 04:11:41 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:11:41 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:11:41 INFO - u'web-platform': [u'target.common.tests.zip', 04:11:41 INFO - u'target.web-platform.tests.tar.gz'], 04:11:41 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:11:41 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 04:11:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.common.tests.zip 04:11:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:11:41 INFO - Fetch https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.common.tests.zip into memory 04:11:42 INFO - Content-Length response header: 54649546 04:11:42 INFO - Bytes received: 54649546 04:11:53 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 04:11:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.common.tests.zip 04:11:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:11:53 INFO - Fetch https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.common.tests.zip into memory 04:11:54 INFO - Content-Length response header: 54649546 04:11:54 INFO - Bytes received: 54649546 04:12:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.talos.tests.zip 04:12:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:12:06 INFO - Fetch https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.talos.tests.zip into memory 04:12:07 INFO - Content-Length response header: 14496050 04:12:07 INFO - Bytes received: 14496050 04:12:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:07 INFO - trying https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.tar.bz2 04:12:07 INFO - Downloading https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:12:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:12:09 INFO - Downloaded 62759261 bytes. 04:12:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.tar.bz2 04:12:09 INFO - mkdir: /builds/slave/test/properties 04:12:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:12:09 INFO - Writing to file /builds/slave/test/properties/build_url 04:12:09 INFO - Contents: 04:12:09 INFO - build_url:https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.tar.bz2 04:12:09 INFO - Running post-action listener: _resource_record_post_action 04:12:09 INFO - Running post-action listener: set_extra_try_arguments 04:12:09 INFO - [mozharness: 2017-08-11 11:12:09.749294Z] Finished download-and-extract step (success) 04:12:09 INFO - [mozharness: 2017-08-11 11:12:09.749392Z] Running populate-webroot step. 04:12:09 INFO - Running pre-action listener: _resource_record_pre_action 04:12:09 INFO - Running main action method: populate_webroot 04:12:09 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:12:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:12:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:12:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:12:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:12:09 INFO - 'tests': ['damp', 'tps']}, 04:12:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:12:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:12:09 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:12:09 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:12:09 INFO - 'other-e10s': {'tests': ['a11yr', 04:12:09 INFO - 'ts_paint', 04:12:09 INFO - 'tpaint', 04:12:09 INFO - 'sessionrestore', 04:12:09 INFO - 'sessionrestore_many_windows', 04:12:09 INFO - 'sessionrestore_no_auto_restore', 04:12:09 INFO - 'tabpaint']}, 04:12:09 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 04:12:09 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 04:12:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:12:09 INFO - 'tsvgr_opacity', 04:12:09 INFO - 'tart', 04:12:09 INFO - 'tscrollx', 04:12:09 INFO - 'tsvg_static']}, 04:12:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:12:09 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:12:09 INFO - 'talos_options': ['--mitmproxy', 04:12:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:12:09 INFO - '--firstNonBlankPaint'], 04:12:09 INFO - 'tests': ['tp6_google', 04:12:09 INFO - 'tp6_youtube', 04:12:09 INFO - 'tp6_amazon', 04:12:09 INFO - 'tp6_facebook']}, 04:12:09 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:12:09 INFO - 'talos_options': ['--stylo', 04:12:09 INFO - '--mitmproxy', 04:12:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:12:09 INFO - '--firstNonBlankPaint'], 04:12:09 INFO - 'tests': ['tp6_google', 04:12:09 INFO - 'tp6_youtube', 04:12:09 INFO - 'tp6_amazon', 04:12:09 INFO - 'tp6_facebook']}, 04:12:09 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:12:09 INFO - 'talos_options': ['--stylo', 04:12:09 INFO - '--stylo-threads=1', 04:12:09 INFO - '--mitmproxy', 04:12:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:12:09 INFO - '--firstNonBlankPaint'], 04:12:09 INFO - 'tests': ['tp6_google', 04:12:09 INFO - 'tp6_youtube', 04:12:09 INFO - 'tp6_amazon', 04:12:09 INFO - 'tp6_facebook']}, 04:12:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:12:09 INFO - 'talos_options': ['--xperf_path', 04:12:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:12:09 INFO - 'tests': ['tp5n']}}} 04:12:09 INFO - Running post-action listener: _resource_record_post_action 04:12:09 INFO - [mozharness: 2017-08-11 11:12:09.757846Z] Finished populate-webroot step (success) 04:12:09 INFO - [mozharness: 2017-08-11 11:12:09.757937Z] Running create-virtualenv step. 04:12:09 INFO - Running pre-action listener: _resource_record_pre_action 04:12:09 INFO - Running main action method: create_virtualenv 04:12:09 INFO - Creating virtualenv /builds/slave/test/build/venv 04:12:09 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 04:12:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:12:09 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:12:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:12:09 INFO - Using real prefix '/usr' 04:12:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:12:10 INFO - Installing distribute.............................................................................................................................................................................................done. 04:12:12 INFO - Installing pip.................done. 04:12:12 INFO - Return code: 0 04:12:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:12:12 INFO - Reading from file tmpfile_stdout 04:12:12 INFO - Output received: 04:12:12 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) 04:12:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f00>, '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 0x22d1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d2050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', '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 04:12:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:12:12 INFO - Using env: {'DISPLAY': ':0', 04:12:12 INFO - 'HOME': '/home/cltbld', 04:12:12 INFO - 'LANG': 'en_US.UTF-8', 04:12:12 INFO - 'LANGUAGE': 'en_US:en', 04:12:12 INFO - 'LOGNAME': 'cltbld', 04:12:12 INFO - 'MAIL': '/var/mail/cltbld', 04:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:12 INFO - 'MOZ_NO_REMOTE': '1', 04:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:12 INFO - 'NO_EM_RESTART': '1', 04:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:12 INFO - 'PWD': '/builds/slave/test', 04:12:12 INFO - 'SHELL': '/bin/bash', 04:12:12 INFO - 'SHLVL': '1', 04:12:12 INFO - 'TERM': 'linux', 04:12:12 INFO - 'TMOUT': '86400', 04:12:12 INFO - 'USER': 'cltbld', 04:12:12 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', 04:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:12 INFO - '_': '/tools/buildbot/bin/python'} 04:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:12 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 04:12:12 INFO - Cleaning up... 04:12:12 INFO - Return code: 0 04:12:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1f661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f00>, '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 0x22d1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d2050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', '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 04:12:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:12:12 INFO - Using env: {'DISPLAY': ':0', 04:12:12 INFO - 'HOME': '/home/cltbld', 04:12:12 INFO - 'LANG': 'en_US.UTF-8', 04:12:12 INFO - 'LANGUAGE': 'en_US:en', 04:12:12 INFO - 'LOGNAME': 'cltbld', 04:12:12 INFO - 'MAIL': '/var/mail/cltbld', 04:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:12 INFO - 'MOZ_NO_REMOTE': '1', 04:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:12 INFO - 'NO_EM_RESTART': '1', 04:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:12 INFO - 'PWD': '/builds/slave/test', 04:12:12 INFO - 'SHELL': '/bin/bash', 04:12:12 INFO - 'SHLVL': '1', 04:12:12 INFO - 'TERM': 'linux', 04:12:12 INFO - 'TMOUT': '86400', 04:12:12 INFO - 'USER': 'cltbld', 04:12:12 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', 04:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:12 INFO - '_': '/tools/buildbot/bin/python'} 04:12:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:12 INFO - Downloading/unpacking psutil>=3.1.1 04:12: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 04:12: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 04:12: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 04:12: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 04:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:12:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:12:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:12:14 INFO - Installing collected packages: psutil 04:12:14 INFO - Running setup.py install for psutil 04:12:14 INFO - building 'psutil._psutil_linux' extension 04:12:14 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 04:12:14 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 04:12:14 INFO - building 'psutil._psutil_posix' extension 04:12:14 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 04:12:14 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 04:12:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:12:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:12:14 INFO - Successfully installed psutil 04:12:14 INFO - Cleaning up... 04:12:14 INFO - Return code: 0 04:12:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:12: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')]} 04:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12: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')]} 04:12:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f00>, '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 0x22d1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d2050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', '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 04:12: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:12: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 mozsystemmonitor==0.3 04:12:14 INFO - Using env: {'DISPLAY': ':0', 04:12:14 INFO - 'HOME': '/home/cltbld', 04:12:14 INFO - 'LANG': 'en_US.UTF-8', 04:12:14 INFO - 'LANGUAGE': 'en_US:en', 04:12:14 INFO - 'LOGNAME': 'cltbld', 04:12:14 INFO - 'MAIL': '/var/mail/cltbld', 04:12:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:14 INFO - 'MOZ_NO_REMOTE': '1', 04:12:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:14 INFO - 'NO_EM_RESTART': '1', 04:12:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:14 INFO - 'PWD': '/builds/slave/test', 04:12:14 INFO - 'SHELL': '/bin/bash', 04:12:14 INFO - 'SHLVL': '1', 04:12:14 INFO - 'TERM': 'linux', 04:12:14 INFO - 'TMOUT': '86400', 04:12:14 INFO - 'USER': 'cltbld', 04:12:14 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', 04:12:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:14 INFO - '_': '/tools/buildbot/bin/python'} 04:12:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:12:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:12:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:12:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:12:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:12:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:12:16 INFO - Installing collected packages: mozsystemmonitor 04:12:16 INFO - Running setup.py install for mozsystemmonitor 04:12:16 INFO - Successfully installed mozsystemmonitor 04:12:16 INFO - Cleaning up... 04:12:16 INFO - Return code: 0 04:12:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:12:16 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')]} 04:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:16 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')]} 04:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:16 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 0x1f661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f00>, '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 0x22d1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d2050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', '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 04:12:16 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 04:12:16 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 04:12:16 INFO - Using env: {'DISPLAY': ':0', 04:12:16 INFO - 'HOME': '/home/cltbld', 04:12:16 INFO - 'LANG': 'en_US.UTF-8', 04:12:16 INFO - 'LANGUAGE': 'en_US:en', 04:12:16 INFO - 'LOGNAME': 'cltbld', 04:12:16 INFO - 'MAIL': '/var/mail/cltbld', 04:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:16 INFO - 'MOZ_NO_REMOTE': '1', 04:12:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:16 INFO - 'NO_EM_RESTART': '1', 04:12:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:16 INFO - 'PWD': '/builds/slave/test', 04:12:16 INFO - 'SHELL': '/bin/bash', 04:12:16 INFO - 'SHLVL': '1', 04:12:16 INFO - 'TERM': 'linux', 04:12:16 INFO - 'TMOUT': '86400', 04:12:16 INFO - 'USER': 'cltbld', 04:12:16 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', 04:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:16 INFO - '_': '/tools/buildbot/bin/python'} 04:12:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:16 INFO - Downloading/unpacking jsonschema==2.5.1 04:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:12:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:12:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:12:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:12:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:12:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:12:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:12:17 INFO - Downloading functools32-3.2.3-2.tar.gz 04:12:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:12:17 INFO - warning: no files found matching '*.txt' 04:12:17 INFO - no previously-included directories found matching 'build' 04:12:17 INFO - no previously-included directories found matching 'dist' 04:12:17 INFO - no previously-included directories found matching '.git*' 04:12:17 INFO - Installing collected packages: jsonschema, functools32 04:12:17 INFO - Running setup.py install for functools32 04:12:17 INFO - warning: no files found matching '*.txt' 04:12:17 INFO - no previously-included directories found matching 'build' 04:12:17 INFO - no previously-included directories found matching 'dist' 04:12:17 INFO - no previously-included directories found matching '.git*' 04:12:17 INFO - Successfully installed jsonschema functools32 04:12:17 INFO - Cleaning up... 04:12:17 INFO - Return code: 0 04:12:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:17 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 0x1f661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f00>, '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 0x22d1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d2050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', '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 04:12:17 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 04:12:17 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 04:12:17 INFO - Using env: {'DISPLAY': ':0', 04:12:17 INFO - 'HOME': '/home/cltbld', 04:12:17 INFO - 'LANG': 'en_US.UTF-8', 04:12:17 INFO - 'LANGUAGE': 'en_US:en', 04:12:17 INFO - 'LOGNAME': 'cltbld', 04:12:17 INFO - 'MAIL': '/var/mail/cltbld', 04:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:17 INFO - 'MOZ_NO_REMOTE': '1', 04:12:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:17 INFO - 'NO_EM_RESTART': '1', 04:12:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:17 INFO - 'PWD': '/builds/slave/test', 04:12:17 INFO - 'SHELL': '/bin/bash', 04:12:17 INFO - 'SHLVL': '1', 04:12:17 INFO - 'TERM': 'linux', 04:12:17 INFO - 'TMOUT': '86400', 04:12:17 INFO - 'USER': 'cltbld', 04:12:17 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', 04:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:17 INFO - '_': '/tools/buildbot/bin/python'} 04:12:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:12:18 INFO - Cleaning up... 04:12:18 INFO - Return code: 0 04:12:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:12:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:18 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 0x1f661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f00>, '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 0x22d1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d2050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', '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 04:12:18 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 04:12:18 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 04:12:18 INFO - Using env: {'DISPLAY': ':0', 04:12:18 INFO - 'HOME': '/home/cltbld', 04:12:18 INFO - 'LANG': 'en_US.UTF-8', 04:12:18 INFO - 'LANGUAGE': 'en_US:en', 04:12:18 INFO - 'LOGNAME': 'cltbld', 04:12:18 INFO - 'MAIL': '/var/mail/cltbld', 04:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:18 INFO - 'MOZ_NO_REMOTE': '1', 04:12:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:18 INFO - 'NO_EM_RESTART': '1', 04:12:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:18 INFO - 'PWD': '/builds/slave/test', 04:12:18 INFO - 'SHELL': '/bin/bash', 04:12:18 INFO - 'SHLVL': '1', 04:12:18 INFO - 'TERM': 'linux', 04:12:18 INFO - 'TMOUT': '86400', 04:12:18 INFO - 'USER': 'cltbld', 04:12:18 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', 04:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:18 INFO - '_': '/tools/buildbot/bin/python'} 04:12:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:18 INFO - Downloading/unpacking blobuploader==1.2.4 04:12:18 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 04:12:18 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 04:12:18 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 04:12:18 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 04:12:19 INFO - Downloading blobuploader-1.2.4.tar.gz 04:12:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:12:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:12:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:12:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:12:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:12:19 INFO - Downloading docopt-0.6.1.tar.gz 04:12:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:12:20 INFO - Installing collected packages: blobuploader, requests, docopt 04:12:20 INFO - Running setup.py install for blobuploader 04:12:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:12:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:12:20 INFO - Running setup.py install for requests 04:12:20 INFO - Running setup.py install for docopt 04:12:20 INFO - Successfully installed blobuploader requests docopt 04:12:20 INFO - Cleaning up... 04:12:20 INFO - Return code: 0 04:12:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:20 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 0x1f661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f00>, '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 0x22d1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d2050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', '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 04:12:20 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 04:12:20 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 04:12:20 INFO - Using env: {'DISPLAY': ':0', 04:12:20 INFO - 'HOME': '/home/cltbld', 04:12:20 INFO - 'LANG': 'en_US.UTF-8', 04:12:20 INFO - 'LANGUAGE': 'en_US:en', 04:12:20 INFO - 'LOGNAME': 'cltbld', 04:12:20 INFO - 'MAIL': '/var/mail/cltbld', 04:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:20 INFO - 'MOZ_NO_REMOTE': '1', 04:12:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:20 INFO - 'NO_EM_RESTART': '1', 04:12:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:20 INFO - 'PWD': '/builds/slave/test', 04:12:20 INFO - 'SHELL': '/bin/bash', 04:12:20 INFO - 'SHLVL': '1', 04:12:20 INFO - 'TERM': 'linux', 04:12:20 INFO - 'TMOUT': '86400', 04:12:20 INFO - 'USER': 'cltbld', 04:12:20 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', 04:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:20 INFO - '_': '/tools/buildbot/bin/python'} 04:12:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:12:20 INFO - Running setup.py (path:/tmp/pip-qr4pf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:12:20 INFO - Running setup.py (path:/tmp/pip-Q_h2Hm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:12:21 INFO - Running setup.py (path:/tmp/pip-R6ZNFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:12:21 INFO - Running setup.py (path:/tmp/pip-LcNOTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:12:21 INFO - Running setup.py (path:/tmp/pip-Ig_AGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:12:21 INFO - Running setup.py (path:/tmp/pip-eB_RDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:12:21 INFO - Running setup.py (path:/tmp/pip-vLFzKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:12:21 INFO - Running setup.py (path:/tmp/pip-vXz8S5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:12:21 INFO - Running setup.py (path:/tmp/pip-r9VkqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:12:21 INFO - Running setup.py (path:/tmp/pip-NyD85t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:12:21 INFO - Running setup.py (path:/tmp/pip-hifEbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:12:21 INFO - Running setup.py (path:/tmp/pip-KCT4IQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:12:21 INFO - Running setup.py (path:/tmp/pip-N8Mq0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:12:21 INFO - Running setup.py (path:/tmp/pip-g9jbM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:12:21 INFO - Running setup.py (path:/tmp/pip-nVL5SY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:12:21 INFO - Running setup.py (path:/tmp/pip-YZBZPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:12:22 INFO - Running setup.py (path:/tmp/pip-ANsgVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:12:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:12:22 INFO - Running setup.py install for manifestparser 04:12:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:12:22 INFO - Running setup.py install for mozcrash 04:12:22 INFO - Running setup.py install for mozdebug 04:12:22 INFO - Running setup.py install for mozdevice 04:12:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:12:22 INFO - Running setup.py install for mozfile 04:12:22 INFO - Running setup.py install for mozhttpd 04:12:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:12:22 INFO - Running setup.py install for mozinfo 04:12:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:12:22 INFO - Running setup.py install for mozInstall 04:12:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:12:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:12:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:12:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:12:22 INFO - Running setup.py install for mozleak 04:12:22 INFO - Running setup.py install for mozlog 04:12:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:12:23 INFO - Running setup.py install for moznetwork 04:12:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:12:23 INFO - Running setup.py install for mozprocess 04:12:23 INFO - Running setup.py install for mozprofile 04:12:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:12:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:12:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:12:23 INFO - Running setup.py install for mozrunner 04:12:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:12:23 INFO - Running setup.py install for mozscreenshot 04:12:23 INFO - Running setup.py install for moztest 04:12:23 INFO - Running setup.py install for mozversion 04:12:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:12:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:12:23 INFO - Cleaning up... 04:12:23 INFO - Return code: 0 04:12:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:23 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 0x1f661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f00>, '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 0x22d1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d2050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', '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 04:12:23 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 04:12:23 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 04:12:23 INFO - Using env: {'DISPLAY': ':0', 04:12:23 INFO - 'HOME': '/home/cltbld', 04:12:23 INFO - 'LANG': 'en_US.UTF-8', 04:12:23 INFO - 'LANGUAGE': 'en_US:en', 04:12:23 INFO - 'LOGNAME': 'cltbld', 04:12:23 INFO - 'MAIL': '/var/mail/cltbld', 04:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:23 INFO - 'MOZ_NO_REMOTE': '1', 04:12:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:23 INFO - 'NO_EM_RESTART': '1', 04:12:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:23 INFO - 'PWD': '/builds/slave/test', 04:12:23 INFO - 'SHELL': '/bin/bash', 04:12:23 INFO - 'SHLVL': '1', 04:12:23 INFO - 'TERM': 'linux', 04:12:23 INFO - 'TMOUT': '86400', 04:12:23 INFO - 'USER': 'cltbld', 04:12:23 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', 04:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:23 INFO - '_': '/tools/buildbot/bin/python'} 04:12:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:12:24 INFO - Running setup.py (path:/tmp/pip-oU7hEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:12:24 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)) 04:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:12:24 INFO - Running setup.py (path:/tmp/pip-Qdhek6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:12:24 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)) 04:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:12:24 INFO - Running setup.py (path:/tmp/pip-vuxwjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:12:24 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)) 04:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:12:24 INFO - Running setup.py (path:/tmp/pip-jChHmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:12:24 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)) 04:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:12:24 INFO - Running setup.py (path:/tmp/pip-xoiVZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:12:24 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)) 04:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:12:24 INFO - Running setup.py (path:/tmp/pip-LZkekH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:12:24 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)) 04:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:12:24 INFO - Running setup.py (path:/tmp/pip-s1bhjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:12:24 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)) 04:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:12:24 INFO - Running setup.py (path:/tmp/pip-ceh3hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:12:24 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)) 04:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:12:24 INFO - Running setup.py (path:/tmp/pip-ai7Xty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:12:24 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)) 04:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:12:24 INFO - Running setup.py (path:/tmp/pip-yYN5zt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:12:24 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)) 04:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:12:24 INFO - Running setup.py (path:/tmp/pip-boH6gY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:12:24 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)) 04:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:12:24 INFO - Running setup.py (path:/tmp/pip-sH2LcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:12:24 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)) 04:12:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:12:24 INFO - Running setup.py (path:/tmp/pip-9ZpsTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:12:25 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)) 04:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:12:25 INFO - Running setup.py (path:/tmp/pip-JcvwSh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:12:25 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)) 04:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:12:25 INFO - Running setup.py (path:/tmp/pip-nupeoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:12:25 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)) 04:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:12:25 INFO - Running setup.py (path:/tmp/pip-yXmFej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:12:25 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)) 04:12:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:12:25 INFO - Running setup.py (path:/tmp/pip-SulPYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:12:25 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)) 04:12:25 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)) 04:12:25 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)) 04:12:25 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)) 04:12:25 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)) 04:12:25 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)) 04:12:25 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)) 04:12:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:12:25 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 04:12:25 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 04:12:25 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 04:12:25 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 04:12:26 INFO - Downloading blessings-1.6.tar.gz 04:12:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:12:26 INFO - Installing collected packages: blessings 04:12:26 INFO - Running setup.py install for blessings 04:12:26 INFO - Successfully installed blessings 04:12:26 INFO - Cleaning up... 04:12:26 INFO - Return code: 0 04:12:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:12:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:12:26 INFO - Reading from file tmpfile_stdout 04:12:26 INFO - Current package versions: 04:12:26 INFO - argparse == 1.2.1 04:12:26 INFO - blessings == 1.6 04:12:26 INFO - blobuploader == 1.2.4 04:12:26 INFO - docopt == 0.6.1 04:12:26 INFO - functools32 == 3.2.3-2 04:12:26 INFO - jsonschema == 2.5.1 04:12:26 INFO - manifestparser == 1.1 04:12:26 INFO - mozInstall == 1.13 04:12:26 INFO - mozcrash == 1.0 04:12:26 INFO - mozdebug == 0.1 04:12:26 INFO - mozdevice == 0.51 04:12:26 INFO - mozfile == 1.2 04:12:26 INFO - mozhttpd == 0.7 04:12:26 INFO - mozinfo == 0.10 04:12:26 INFO - mozleak == 0.1 04:12:26 INFO - mozlog == 3.5 04:12:26 INFO - moznetwork == 0.27 04:12:26 INFO - mozprocess == 0.25 04:12:26 INFO - mozprofile == 0.28 04:12:26 INFO - mozrunner == 6.13 04:12:26 INFO - mozscreenshot == 0.1 04:12:26 INFO - mozsystemmonitor == 0.3 04:12:26 INFO - moztest == 0.8 04:12:26 INFO - mozversion == 1.4 04:12:26 INFO - psutil == 3.1.1 04:12:26 INFO - requests == 1.2.3 04:12:26 INFO - wsgiref == 0.1.2 04:12:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:12:26 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')]} 04:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:26 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')]} 04:12:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:26 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 0x1f661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f00>, '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 0x22d1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d2050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', '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 04:12:26 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 04:12:26 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 04:12:26 INFO - Using env: {'DISPLAY': ':0', 04:12:26 INFO - 'HOME': '/home/cltbld', 04:12:26 INFO - 'LANG': 'en_US.UTF-8', 04:12:26 INFO - 'LANGUAGE': 'en_US:en', 04:12:26 INFO - 'LOGNAME': 'cltbld', 04:12:26 INFO - 'MAIL': '/var/mail/cltbld', 04:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:26 INFO - 'MOZ_NO_REMOTE': '1', 04:12:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:26 INFO - 'NO_EM_RESTART': '1', 04:12:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:26 INFO - 'PWD': '/builds/slave/test', 04:12:26 INFO - 'SHELL': '/bin/bash', 04:12:26 INFO - 'SHLVL': '1', 04:12:26 INFO - 'TERM': 'linux', 04:12:26 INFO - 'TMOUT': '86400', 04:12:26 INFO - 'USER': 'cltbld', 04:12:26 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', 04:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:26 INFO - '_': '/tools/buildbot/bin/python'} 04:12:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:27 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)) 04:12:27 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)) 04:12:27 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)) 04:12:27 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)) 04:12:27 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)) 04:12:27 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)) 04:12:27 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)) 04:12:27 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)) 04:12:27 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)) 04:12:27 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:12:27 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 04:12:27 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 04:12:27 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 04:12:27 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 04:12:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:12:28 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)) 04:12:28 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)) 04:12:28 INFO - Installing collected packages: simplejson 04:12:28 INFO - Running setup.py install for simplejson 04:12:28 INFO - building 'simplejson._speedups' extension 04:12:28 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 04:12:29 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 04:12:29 INFO - Successfully installed simplejson 04:12:29 INFO - Cleaning up... 04:12:29 INFO - Return code: 0 04:12:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:12:29 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')]} 04:12:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:12:29 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')]} 04:12:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:12:29 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 0x1f661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2132a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22d3b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22d6f00>, '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 0x22d1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d2050>, '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': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', '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 04:12:29 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 04:12:29 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 04:12:29 INFO - Using env: {'DISPLAY': ':0', 04:12:29 INFO - 'HOME': '/home/cltbld', 04:12:29 INFO - 'LANG': 'en_US.UTF-8', 04:12:29 INFO - 'LANGUAGE': 'en_US:en', 04:12:29 INFO - 'LOGNAME': 'cltbld', 04:12:29 INFO - 'MAIL': '/var/mail/cltbld', 04:12:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:29 INFO - 'MOZ_NO_REMOTE': '1', 04:12:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:29 INFO - 'NO_EM_RESTART': '1', 04:12:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:29 INFO - 'PWD': '/builds/slave/test', 04:12:29 INFO - 'SHELL': '/bin/bash', 04:12:29 INFO - 'SHLVL': '1', 04:12:29 INFO - 'TERM': 'linux', 04:12:29 INFO - 'TMOUT': '86400', 04:12:29 INFO - 'USER': 'cltbld', 04:12:29 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', 04:12:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:29 INFO - '_': '/tools/buildbot/bin/python'} 04:12:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:12:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:12:29 INFO - Cleaning up... 04:12:29 INFO - Return code: 0 04:12:29 INFO - Running post-action listener: _resource_record_post_action 04:12:29 INFO - Running post-action listener: _start_resource_monitoring 04:12:29 INFO - Starting resource monitoring. 04:12:29 INFO - [mozharness: 2017-08-11 11:12:29.654123Z] Finished create-virtualenv step (success) 04:12:29 INFO - [mozharness: 2017-08-11 11:12:29.654594Z] Running install step. 04:12:29 INFO - Running pre-action listener: _resource_record_pre_action 04:12:29 INFO - Running main action method: install 04:12:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:12:29 INFO - Reading from file tmpfile_stdout 04:12:29 INFO - Detecting whether we're running mozinstall >=1.0... 04:12:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:12:30 INFO - Reading from file tmpfile_stdout 04:12:30 INFO - Output received: 04:12:30 INFO - Usage: mozinstall [options] installer 04:12:30 INFO - Options: 04:12:30 INFO - -h, --help show this help message and exit 04:12:30 INFO - -d DEST, --destination=DEST 04:12:30 INFO - Directory to install application into. [default: 04:12:30 INFO - "/builds/slave/test"] 04:12:30 INFO - --app=APP Application being installed. [default: firefox] 04:12:30 INFO - mkdir: /builds/slave/test/build/application 04:12:30 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'] 04:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:12:47 INFO - Reading from file tmpfile_stdout 04:12:47 INFO - Output received: 04:12:47 INFO - /builds/slave/test/build/application/firefox/firefox 04:12:47 INFO - Running post-action listener: _resource_record_post_action 04:12:47 INFO - [mozharness: 2017-08-11 11:12:47.995245Z] Finished install step (success) 04:12:47 INFO - [mozharness: 2017-08-11 11:12:47.995528Z] Running setup-mitmproxy step. 04:12:47 INFO - Running pre-action listener: _resource_record_pre_action 04:12:47 INFO - Running main action method: setup_mitmproxy 04:12:47 INFO - Skipping: mitmproxy is not required 04:12:47 INFO - Running post-action listener: _resource_record_post_action 04:12:47 INFO - [mozharness: 2017-08-11 11:12:47.996686Z] Finished setup-mitmproxy step (success) 04:12:47 INFO - [mozharness: 2017-08-11 11:12:47.996907Z] Running run-tests step. 04:12:47 INFO - Running pre-action listener: _resource_record_pre_action 04:12:47 INFO - Running pre-action listener: _set_gcov_prefix 04:12:47 INFO - Running main action method: run_tests 04:12:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:12:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:12:48 INFO - Python 2.7.3 04:12:48 INFO - Return code: 0 04:12:48 INFO - grabbing minidump binary from tooltool 04:12:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:12:48 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 0x22d6f00>, '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 0x22d1ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22d2050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:12:48 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 04:12:48 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 04:12:48 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 04:12:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:12:48 INFO - Return code: 0 04:12:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:12:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:12:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:12:48 INFO - ENV: RUST_BACKTRACE is now full 04:12:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:12:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:12:48 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/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 04:12:48 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/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 04:12:48 INFO - Using env: {'DISPLAY': ':0', 04:12:48 INFO - 'HOME': '/home/cltbld', 04:12:48 INFO - 'LANG': 'en_US.UTF-8', 04:12:48 INFO - 'LANGUAGE': 'en_US:en', 04:12:48 INFO - 'LOGNAME': 'cltbld', 04:12:48 INFO - 'MAIL': '/var/mail/cltbld', 04:12:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:12:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:12:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:12:48 INFO - 'MOZ_NO_REMOTE': '1', 04:12:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:12:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:12:48 INFO - 'NO_EM_RESTART': '1', 04:12:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:12:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:12:48 INFO - 'PWD': '/builds/slave/test', 04:12:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:12:48 INFO - 'RUST_BACKTRACE': 'full', 04:12:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:12:48 INFO - 'SHELL': '/bin/bash', 04:12:48 INFO - 'SHLVL': '1', 04:12:48 INFO - 'TERM': 'linux', 04:12:48 INFO - 'TMOUT': '86400', 04:12:48 INFO - 'USER': 'cltbld', 04:12:48 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665', 04:12:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:12:48 INFO - '_': '/tools/buildbot/bin/python'} 04:12:48 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/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 04:12:48 INFO - mozversion application_buildid: 20170811095810 04:12:48 INFO - mozversion application_changeset: 64f5e3f40f0e7accb12cd90080e58eff0be46383 04:12:48 INFO - mozversion application_display_name: Nightly 04:12:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:12:48 INFO - mozversion application_name: Firefox 04:12:48 INFO - mozversion application_remotingname: firefox 04:12:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:12:48 INFO - mozversion application_vendor: Mozilla 04:12:48 INFO - mozversion application_version: 57.0a1 04:12:48 INFO - mozversion platform_buildid: 20170811095810 04:12:48 INFO - mozversion platform_changeset: 64f5e3f40f0e7accb12cd90080e58eff0be46383 04:12:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:12:48 INFO - mozversion platform_version: 57.0a1 04:12:48 INFO - using testdate: 1502449968 04:12:48 INFO - actual date: 1502449968 04:12:48 INFO - starting webserver on 'localhost:38934' 04:12:48 INFO - SUITE-START | Running 2 tests 04:12:48 INFO - TEST-START | dromaeo_css 04:12:48 INFO - Initialising browser for dromaeo_css test... 04:12:48 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38934/getInfo.html -profile /tmp/tmpHrvEQW/profile 04:12:48 INFO - TEST-INFO | started process 3130 (/builds/slave/test/build/application/firefox/firefox http://localhost:38934/getInfo.html) 04:12:54 INFO - TEST-INFO | 3130: exit 0 04:12:54 INFO - Browser initialized. 04:12:54 INFO - Running cycle 1/1 for dromaeo_css test... 04:12:54 INFO - TEST-INFO | started process 3345 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHrvEQW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:12:55 INFO - PID 3345 | 04:12:55 INFO - PID 3345 | (/builds/slave/test/build/application/firefox/firefox:3394): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:12:55 INFO - PID 3345 | 04:13:06 INFO - PID 3345 | RSS: Main: 162127872 04:13:06 INFO - PID 3345 | 04:16:04 INFO - PID 3345 | Cycle 1(1): loaded http://localhost:38934/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38934/tests/dromaeo/cssquery-ext.html) 04:16:05 INFO - PID 3345 | RSS: Main: 167186432 04:16:05 INFO - PID 3345 | 04:19:06 INFO - PID 3345 | Cycle 1(1): loaded http://localhost:38934/tests/dromaeo/cssquery-ext.html (next: http://localhost:38934/tests/dromaeo/cssquery-jquery.html) 04:19:07 INFO - PID 3345 | RSS: Main: 162832384 04:19:07 INFO - PID 3345 | 04:22:09 INFO - PID 3345 | Cycle 1(1): loaded http://localhost:38934/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38934/tests/dromaeo/cssquery-mootools.html) 04:22:09 INFO - PID 3345 | RSS: Main: 165875712 04:22:09 INFO - PID 3345 | 04:25:11 INFO - PID 3345 | Cycle 1(1): loaded http://localhost:38934/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38934/tests/dromaeo/cssquery-prototype.html) 04:25:11 INFO - PID 3345 | RSS: Main: 164683776 04:25:11 INFO - PID 3345 | 04:28:15 INFO - PID 3345 | Cycle 1(1): loaded http://localhost:38934/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38934/tests/dromaeo/cssquery-yui.html) 04:28:15 INFO - PID 3345 | RSS: Main: 168316928 04:28:15 INFO - PID 3345 | 04:31:17 INFO - PID 3345 | Cycle 1(1): loaded http://localhost:38934/tests/dromaeo/cssquery-yui.html (next: http://localhost:38934/tests/dromaeo/cssquery-dojo.html) 04:31:17 INFO - PID 3345 | RSS: Main: 169103360 04:31:17 INFO - PID 3345 | 04:31:17 INFO - PID 3345 | __start_tp_report 04:31:17 INFO - PID 3345 | _x_x_mozilla_page_load 04:31:17 INFO - PID 3345 | _x_x_mozilla_page_load_details 04:31:17 INFO - PID 3345 | |i|pagename|runs| 04:31:17 INFO - PID 3345 | |0;dojo.html;3469.9306013879723;3491.3890069237887;3504.351694936437;3512.525809015782;3513.5256740340064;6174.073888916665;6231.719572619231;6238.6256824590555;6290.7798227062;6313.1792866927235;6083.574149809504;6207.875842483148;6231.252249730027;6238.563300568962;6241.251049874021;5461.317335333073;5470.152126420411;5470.671759694411;5488.7530061147345;5575.69333686647;5565.526930210937;5566.832995010156;5566.86082847928;5592.133219351007;5619.129034999582;5468.699221542814;5502.367227768817;5534;5626.493615574618;5627.324721033461;5314.5216930476445;5459.372172200208;5469.343678758534;5502.669839809584;5520.806771763009;5427.213054107172;5458.699771512565;5497.615166938316;5513.448655134454;5531.032069387881;6020.548458865559;6111;6128.540359473057;6149.538784591129;6239.126522286838;6659.866802663968;6661.233958094833;6677.399034086956;6732.1921369435495;6736;6717.798466046026;6787.660616969132;6793.252742198354;6799.626020568867;6811.931880681229;6190.876182476371;6199.194104766351;6254.249490061222;6268.028455589391;6295.370462953714;6799;6864.965675171692;6891.552049116792;6933.341332573397;6988.6855091521;6650.567713098633;6663.433608143265;6717.261101278856;6717.832054198587;6732.158480189976;6677.232118306457;6684.899726504106;6685.598864068074;6763.323667633197;6770;6557.442617377481;6638.402543771083;6713.899291510631;6792.08306878575;6794.524383293221;6676.132102826601;6748.696308666122;6753.527253092237;6753.986901964745;6755;4883;4892.094962431962;4902.828401006019;4905.386826646669;4907.263910413395;4260.829566817292;4274.679399045084;4316.611504964505;4356.673249506236;4368.737875727466;6268.905966410507;6348.333424990402;6348.809535713936;6361.777337793155;6365.108884756138;6664.866702666017;6677.499187560855;6735.057092007123;6752.5273230874345;6756.425703815231;6206.968965155145;6302.936970630327;6328.177336946167;6337.207849018872;6394.200724909387;5366.838994830161;5408.161734931092;5424.83725488236;5427.6200665953;5433.918491222635;7043.788686339316;7084.822879427952;7112.182099058675;7113.75101871432;7122.501424900311;6560.737570497126;6629.46964242869;6647.302033286629;6716.328367163244;6736;6937.7224911002995;7042.647867606702;7102.538335008415;7167.641617919187;7181.102362204724;6662.467002639874;6663.067170596218;6677.432418244503;6687.732490700512;6695.799126026227;6679.332066793476;6713.462922966053;6716.9588713749445;6771.424428923597;6777.559458635173;6475.3200913903365;6481.773137940149;6555.541112122106;6558.278589355263;6563.113979612691;6454.935450645621;6521.380468855451;6570.375814297634;6591.703373348115;6638.867222655424;6470.029495575702;6516.152900123143;6535.803925882231;6551.279359270381;6551.50863685216;6433.903491447726;6526.4452521536205;6546.803595892131;6614.0078988150235;6616.569922954993;6331.493480521639;6336.936630633634;6381.553291269753;6406.167198264196;6449.097126402401 04:31:17 INFO - PID 3345 | |1;ext.html;919.9080091990802;960.5053397500286;974.1768205866045;1207.9275243485392;1212.9696757581064;34591.827040864795;35230.471542926876;35231.471527927104;43335.56664433355;43772.34341484872;45235;45550;45584.772076139576;45765;45851;7695.653695583685;7815.491993020435;7817.804554886116;7838.412119091063;7841.803954901116;7672.271134242238;7757.844843103135;7760.805979850493;7762.184970578092;7765;7799.883001754978;7820.491668041559;7821.139674635786;7823.882641760378;7824;7762;7778.727744528943;7779.649915753805;7780.533168009937;7785.299323060924;7662.846743065107;7715.614219289069;7717.8456430871065;7722;7723.691052357955;40819.59180408187;40916;41069.794651026554;41106.17787644231;41188.38217426741;3501.7548771586;3533.222691007974;3534.3814832404228;3535.8232088395735;3538.327717733622;31895.202619934455;32027.35945281081;32034.519482207786;32047.71809127632;32171.678283217338;4857.684250523705;4994.126027945095;5054.974725126351;5079.873003174913;5103.770330335144;203682;205874;206143;206272;206542;51193.7440312796;51206.74396627993;51328.48671513237;51411;51444.22833657498;53682;53742.46257537453;53799;53853.73073134688;53915.46084539104;15757.212139392985;16448.917755411385;16465;16471.752923706154;16476;13572.1856688599;13622.250776207404;13652.112612680145;13654.590362289147;13664.65838354049;13452.93273533626;13692.383842727102;13722;13766;13827.446902124004;5333.813316533902;5339.733013349394;5462.617616766867;5475.863103422446;5514.61397702157;1089.8528698625742;1090.8909109089186;1098.0776148035688;1108.7449886525983;1112.8608923884515;25033.749662503506;25161.370965726004;25169.62245566318;25188.748112519006;25264.621030684553;1205.0660737928176;1208.6132437619876;1211.3398197982121;1212.8483939507562;1214.4352875912743;13226.933865330611;13310.534131305358;13409.731805364034;17809.7328540072;17942.910285448488;260266;261009;262514;288249;289788;62804;62844.371556284765;63020.68489657522;63070.684646576476;63156;1523.512476007667;1552.4100841680315;1556.113015581108;1559.3294883200333;1566.1307974074498;29556.22232317997;29574.408511829213;29737.25656858596;29756.85121574465;29848;38099.238015240095;38223.23553528969;41471.170576588906;41472.58527414687;41537.58462415337;228247;228745;229619;229800;230518;35438.64561354354;35508.289834203686;35637.82181089078;35904.461433078;35939.820300898326;44526.332105017806;45479;45710.54289457196;45733.314000289356;45848;34321.48517772186;34471;34539.654603453644;34577.48133777945;34836.65163348334;34188.31623367569;34273.65726342705;34780.82609586936;35006.299874002885;35304.293914122085;40107.799461002505;40424;40609.79695101506;40667.7966610165;40746.1850762989;33755.66244337525;33825.49261761026;34200.82899585486;34318.48522272118;34335.828320858236;25055.498890021732;25136.74863251344;25279.36801579975;25441.222778141513;34939.65060349466 04:31:17 INFO - PID 3345 | |2;jquery.html;558.0986706469052;561.7472137538109;565.5899472882161;570.5150621971322;571.7484306904962;22590.322290331296;23352;23360.883195584018;23366.714830684305;23422.531549369043;26412.33969150772;26714.19857404281;26832.329191770215;26841.865790671018;26920.192394228205;6197.597156184834;6314.336994615568;6340.144080549136;6345.650989195592;6348.873022539547;6192.690365481731;6298.181236439256;6412.871742565146;6464.741410343604;6465;6381.680915954207;6400.743970241185;6403.167588213549;6418.646974416429;6428.132202152697;6383.77656782015;6392.872142557169;6395.392437718409;6402.295747467775;6416.550841441101;5960.612560183574;5961.701914904281;5964.493018093467;5965.0754133109;5965.254343207099;30490.84754576235;30867.22831929197;31056.37887242265;31276;31410.528842067386;2210.5910406574835;2235.9217427389963;2236.0720301075035;2236.4297104238476;2237.127520267097;20033.398998030083;20521.17915283387;20545.589088218152;20576.074076666588;20594.27920022807;2331.347222777625;2360.280114565057;2388.3193289912465;2399.6160614301803;2406.2179791567637;161781;166833.16583417004;167737;169055;169202;15142.318595663222;15296.158711270988;15297.158656273788;15818.76271855923;15972.840271597368;23113.653295200584;23274.767252327598;23435.296941091794;23440;23485.41286467852;10681.961639968438;10683.62607308756;10736.838947415796;10771.299865508874;10907;8909.73270801877;9010.909890901006;10029.849552256723;10075.899241007495;10153.847692284622;9381.577828997712;9623.855642165374;9825.656102036395;9835.213182945346;9892.60429582823;2790.4698107359536;2796.3428594280326;2820.971790282112;2860.284928767808;2962.7481664058364;1055.1400608504046;1063.5852017713098;1070.25617196048;1071.5820829876354;1076.5424694504804;26469;26929.86535067312;27127.864360678464;28232.15303540897;28567.57148642772;1073.8657667791526;1137.8350139229765;1138.0610995928391;1145.1983611472003;1163.03468121459;11926.642200733992;11953.52185912571;12045.15683902136;12105.515779368903;12216.816747748791;600981;601495;618616;638321;640816;123003;124076.37961810133;124212.37893810472;127848;131827.34086329507;1306.4382315604382;1347.1311004402105;1362.420971087284;1366.1939455721172;1380.6893448974101;19070.618587628447;19539.804601953798;19819.20723171057;20094.799052009294;20133.597328053063;62602.37397625965;62693.05960410506;62969;63662.045069323074;64229.678851605444;81438;81747.5912620457;81829.59085204535;84157;85731.5713421429;12907.290099044643;13372.665683357995;14369.56891293263;14749.410023598935;15087.698246035237;23413.76586234184;24395.536267823987;24579.75420245846;24589;24769.876150619133;19345.90327048412;19582.608347833248;19752;19787.901060494605;19841.900790495954;15683.764743529084;15857.444989425314;16504.33982640681;16886.57783555372;18151.27394904189;21393;21980.89009554942;22015;22242.332730018126;22264.443388915406;22788.772112278664;23054.847257637386;23329.18347857817;23764.405889852893;24066.759332407153;19978;20387.59224815525;20548.589028219652;20552.28067017647;20700.792992069888 04:31:17 INFO - PID 3345 | |3;mootools.html;1380.3581334679373;1401.3343661760664;1416.589189135151;1421.0265967812043;1443.5019918128241;13646.658833529142;15560.84439155608;16177.029378237314;16186.676266474678;16186.91906540467;13456.1926284423;15033;16115.83884161158;16130.83869161311;16133.919330403332;2689.408330167365;2747.230775382896;2750.339918419585;2752.146834481307;2754.4353407551503;2628.4217472156192;2658.3088397016722;2721.4557088582264;2722.0336780442954;2722.3058120179326;2635.8813853376646;2666.6133410655443;2699.2442116207494;2699.25770413136;2699.6625421822273;2558.2581051495044;2561.7950563954837;2564.025670245295;2564.3332733489333;2565.4740778140435;2485.9378515537073;2555.9105431309813;2558.3604098975256;2562.5515534781525;2567.075852693029;15372.846271537364;15709.900306978516;15868.444604438904;15872.12703301318;15884.52346429609;8016.879746803803;8261.17388261169;8267.503949762973;8272.296854767352;8273.669053237863;5023.949760502384;5167.6641018333685;5299.205119232108;5316.149416093445;5389.110796718537;1870.2799422222542;1881.491997160759;1891.9527011824678;1922.6250881078195;1951.834094102003;26849.0602828902;27686.3078423039;27893.58159627607;28029;28057.158285251477;7100.857982840314;7119.4660400470175;7204.027456293438;7220.494565380373;7307.19620841707;18604.06979651012;18631.813681863277;18652.906735466237;18663.253469861054;18681.06594670261;7858;8491.787705307417;8532.914670853212;8533.658653653909;8542.572871356408;7689.692412303557;7754.263344982218;7843.7254696085365;7967.2431119043595;8076.232757887991;5338.225957236256;5450.972745136249;5453.072977593739;5457.508824205841;5464.672119672832;1539.7151526967548;1589.6025993501626;1596.1859451679495;1608.4209684513567;1625.504221212518;956.6938579654444;961.5144352102144;963.7638778499313;975.6877799104218;990.9554070066864;9265.490398028038;9571.808563828685;9619.230461563058;9648.517574121266;9716.514174291398;1045.4877110216091;1045.717656232813;1046.8011077895176;1051.9631812886512;1061.3737894642045;5606.439356064361;5883.735231914574;6026.276846778327;6068.4538391544975;6100.054491553817;22615.54768904613;22862;22921.426964326027;23168.768312317;23318;22203.555928881655;22733.204337848096;23076.769232307797;23316.183933562243;23350.64974025391;10491.790164196673;11020.173486988508;12303.938480307721;12448;12480.81278780819;9769.364991275545;9847.60609575609;10068.597256109673;10143.188544916242;10206.285560010729;19441.51396215106;19959.700604490652;20308.79691203069;20395.8980205098;20396;20652;20886.58226835485;20935;20981.790182098594;21154.365369038955;11829.822552661544;12029.939850300692;12030.037596992395;12038.217515861441;12065.336406497727;13924.094933829674;14198.787018194942;14227.857721422653;14302.141871487744;14307.785383219052;11330.490127944428;11361.43192840338;11368.886311136783;11571.942140289244;11695.76608467809;11559.959603635712;11741.530338786695;12007.579734709243;12021.398930053638;12262.20295680778;10761.408122553335;11035.227534072537;11040.723981900517;11047.060999815105;11048.502817373077;8075.515469071875;8203.753887383387;8288.627011784605;8304.003519577689;8354.248117669438;8472.406931514735;8492.108328625653;8587.91412085896;8606.26846718036;8610.44032137909 04:31:17 INFO - PID 3345 | |4;prototype.html;507.7334399440291;509.9872503187421;519.4364114935296;535.2479765928871;541.0828645445971;6077.817665470032;6123.601965872226;6184.598001129929;6193.659348735823;6244.12582238487;6031.306399764017;6047.546434017455;6060.333363330041;6077.359113039488;6099.268087829467;4852.175130227852;4862.3922009748785;4901.901961960776;4902.338184345122;4904.4605093439695;4627.537246275379;4722.409698787652;4844.128056949748;4862.246351815475;4867.0265946810605;4689.695169813968;4696;4755.286706993961;4772.761361931907;4777.832775852846;4709.929351059737;4715.7877895494785;4759.048190361907;4767.165745994437;4782.784774685148;4177.3316269397055;4267.957320426787;4277.97861010696;4288.935665964982;4313.460817397825;5923.614965027259;5933.643981361132;5953.166556682068;6025.216721826178;6040.244969378828;5668.31980162379;5691.430856914317;5691.800786972436;5705.71471426427;5742.655440673531;6532.608043517404;6534.705938232743;6554.8041406129005;6576.967115164393;6602.5708328958945;4828.372311599504;4843.709377437365;4846.36997190363;4880.951190488086;4888.755562221874;6636.83407914806;6655.900161497581;6701.899471507932;6793.286704895964;6932.930670693279;4913.287573301851;4975.407869481731;4982.90034199314;5001.64988450805;5005.724685142352;5085.228550440019;5098.4646612105935;5101;5111.897762044739;5122.154844450699;5580.684016133259;5601.29983752031;5650.8072938789455;5671.489565939085;5690.752152899664;6522.478201743849;6538.1500404947055;6560.671966401661;6571.901421478682;6608.306127856602;1774.3612299572144;1778.6175972165788;1779.2616064333415;1781.118346418531;1795.7934837493856;1187.75057237981;1229.8155276708387;1230.1266101068343;1236.4003458322707;1240.8076748104058;6313.842153946096;6363.5863668861375;6368.012957991518;6388.169537960035;6432.549721519542;6649.73401063952;6716.194056713128;6720.899186512206;6742.393184613408;6743.415297405106;6228.5017198624;6346.936530634727;6353.491720662429;6355.682215889187;6380.266269379081;3556.7510274280553;3559.288142371536;3559.537260156163;3575.1955809942556;3593.1915319053005;6915.749597689078;6978.895316570255;7025.578465292099;7035.33164349386;7104.325089116525;6683.927982325495;6695.397414232645;6766.763163289262;6787.966060169668;6834.794956151323;6385.201849768779;7017.333353331425;7053.964730176316;7106.477200593818;7118.679659415235;6449.322821103717;6551.181102362205;6553.705083271359;6578.736850525926;6642;6351.841203969753;6423.229212494531;6505.21937367519;6580.934190658224;6593.835154121185;7032.156141263081;7048.365647091787;7087.22040575526;7117.82205444868;7134.215236324104;4986.750662466935;4993.725345105909;5039.622028347815;5065.544101030925;5066.9239961401345;6518.543701940818;6562.671866406565;6567.573107747981;6796.898046529405;6832.385085342343;6352.22042884483;6357.936420635734;6405.455536279468;6458.22501299847;6525.9021114684265;6416.582922110048;6438.452731517874;6495.707693153914;6520.478361730955;6521.478281737544;6456.257530384066;6498.642574658439;6509.674516274262;6528.771492997722;6536.150300460909;6422.197225346832;6495.675216239264;6505.536254342925;6516.446102081376;6533.803985880431;6351.491880649629;6375.6174629522375;6391;6397.936020639734;6440.13058237132 04:31:17 INFO - PID 3345 | |5;yui.html;902.5938327752509;930.3766476460771;935.8596210568418;941.8304705153079;945.1399226703699;18825.246990120395;19890.801091989095;19899.204031838708;19940.40178794635;21886.23398181064;22266.332010039725;22589.661155082646;22638.20766273181;22724.431889202777;22766.65850012251;1039.1790485516433;1081.453865797771;1082.1613249731442;1082.837574363844;1083.2958576924982;1040.0795296162876;1088.3252383522226;1090.351241011601;1091.3397394576245;1091.8853520380364;1103.5144536403957;1103.5530610102921;1104.0229396983686;1104.1608377632976;1140.726225705833;1102.2229328323513;1102.5259138570411;1103.0568863621568;1103.189155970361;1142.874283828778;804.9839003219903;804.9959750201211;805.2108933245394;811.9837603247961;841.4908980067032;19976.10107545164;20101.597968040704;20125;20249;20259.594808103902;21766.020529076228;22193.11227550912;22213.666794998087;22233.11067557312;22672;16459.83540164595;16687;16877.07641196018;16908.15459227046;16912.577185570335;3765.5104836371097;3787.5265591801026;3787.867424640152;3788.2802267569073;3789.7915614641183;153701;154893.22553387162;155229.22385388002;155607;156160;16685.91657041707;16988.405405810736;17019.48941531756;17027.914860425866;17219.1390430478;33349.66650333514;33750.83124584361;33813.323733525685;33904;33934.32131357408;8358.205970432798;8613.65545378178;8878.778030549158;9006.459612423276;9015.459072455673;4484.910301793946;4564.520725323793;4568.634509239253;4570.954290457053;4602.2406302959635;5404.540614047772;5528.834134975958;5569.774649577086;5571.359293681278;5584.190292407659;1147.540983606564;1176.082655528672;1183.2545496337252;1184.7808155491261;1186.6618013866007;704.9612271325126;709.443087176564;721.1310371002929;726.3317747672154;733.614852202598;12256.264624122581;12626.81059784104;12699.873001270054;13359.73280534384;13703.246321452216;698.8742026435189;702.9507934444642;705.1467724053849;718.992810071903;719.7804669575829;6006.579539432285;6110.358412366726;6114.938850611437;6178.62928224308;6242.875142497124;138664;142026.28986855;142044;142076.2896185533;147269;122175.38912305559;124475;126960.36519817341;127739;131266.34366828296;106339.46830265954;106985;107813;108114;111355.44322278336;13752.793708094187;14239.359228834934;14318.210223722035;14444.133351998493;14525.564233073024;49540.50459495359;50291;50460.243096354316;51286.48713512817;51402.22896656628;84926;86749.56625216834;87527.56236218777;87558.24883502167;93637.5318123405;19168.520786980436;19991.700124498435;20210.29263975754;20469.38591842247;21155.8942205288;22059.11763529441;22164.445888852908;22384.888075559516;23305.766942331036;23389.181378651665;19260.518487037934;19741.802581973996;20073;20151.899240503702;20731.4817129573;17323.133843308035;17502.649947000733;17634.206460709043;17654;17780.288788448317;18870.3395381161;18942.810571894104;18964.146613402154;19022.334218302294;19459.318923837596;13908.443662253396;14032.422491854284;14060;14329.211893345966;14440.061396009225;14207.076540025279;14789.408423662933;15588.766168507254;15688.294026769052;15888.126153061692 04:31:17 INFO - PID 3345 | __end_tp_report 04:31:17 INFO - PID 3345 | __start_cc_report 04:31:17 INFO - PID 3345 | _x_x_mozilla_cycle_collect,326 04:31:17 INFO - PID 3345 | __end_cc_report 04:31:17 INFO - PID 3345 | __startTimestamp1502451077569__endTimestamp 04:31:17 INFO - PID 3345 | 04:31:17 INFO - PID 3345 | ------- Summary: start ------- 04:31:17 INFO - PID 3345 | Number of tests: 6 04:31:17 INFO - PID 3345 | 04:31:17 INFO - PID 3345 | [#0] dojo.html Cycles:165 Average:6178.37 Median:6470.03 stddev:796.82 (12.3%) stddev-sans-first:770.42 04:31:17 INFO - PID 3345 | Values: 3469.9 3491.4 3504.4 3512.5 3513.5 6174.1 6231.7 6238.6 6290.8 6313.2 6083.6 6207.9 6231.3 6238.6 6241.3 5461.3 5470.2 5470.7 5488.8 5575.7 5565.5 5566.8 5566.9 5592.1 5619.1 5468.7 5502.4 5534.0 5626.5 5627.3 5314.5 5459.4 5469.3 5502.7 5520.8 5427.2 5458.7 5497.6 5513.4 5531.0 6020.5 6111.0 6128.5 6149.5 6239.1 6659.9 6661.2 6677.4 6732.2 6736.0 6717.8 6787.7 6793.3 6799.6 6811.9 6190.9 6199.2 6254.2 6268.0 6295.4 6799.0 6865.0 6891.6 6933.3 6988.7 6650.6 6663.4 6717.3 6717.8 6732.2 6677.2 6684.9 6685.6 6763.3 6770.0 6557.4 6638.4 6713.9 6792.1 6794.5 6676.1 6748.7 6753.5 6754.0 6755.0 4883.0 4892.1 4902.8 4905.4 4907.3 4260.8 4274.7 4316.6 4356.7 4368.7 6268.9 6348.3 6348.8 6361.8 6365.1 6664.9 6677.5 6735.1 6752.5 6756.4 6207.0 6302.9 6328.2 6337.2 6394.2 5366.8 5408.2 5424.8 5427.6 5433.9 7043.8 7084.8 7112.2 7113.8 7122.5 6560.7 6629.5 6647.3 6716.3 6736.0 6937.7 7042.6 7102.5 7167.6 7181.1 6662.5 6663.1 6677.4 6687.7 6695.8 6679.3 6713.5 6717.0 6771.4 6777.6 6475.3 6481.8 6555.5 6558.3 6563.1 6454.9 6521.4 6570.4 6591.7 6638.9 6470.0 6516.2 6535.8 6551.3 6551.5 6433.9 6526.4 6546.8 6614.0 6616.6 6331.5 6336.9 6381.6 6406.2 6449.1 04:31:17 INFO - PID 3345 | 04:31:17 INFO - PID 3345 | [#1] ext.html Cycles:180 Average:41565.37 Median:19418.76 stddev:61761.22 (318.0%) stddev-sans-first:61859.07 04:31:17 INFO - PID 3345 | Values: 919.9 960.5 974.2 1207.9 1213.0 34591.8 35230.5 35231.5 43335.6 43772.3 45235.0 45550.0 45584.8 45765.0 45851.0 7695.7 7815.5 7817.8 7838.4 7841.8 7672.3 7757.8 7760.8 7762.2 7765.0 7799.9 7820.5 7821.1 7823.9 7824.0 7762.0 7778.7 7779.6 7780.5 7785.3 7662.8 7715.6 7717.8 7722.0 7723.7 40819.6 40916.0 41069.8 41106.2 41188.4 3501.8 3533.2 3534.4 3535.8 3538.3 31895.2 32027.4 32034.5 32047.7 32171.7 4857.7 4994.1 5055.0 5079.9 5103.8 203682.0 205874.0 206143.0 206272.0 206542.0 51193.7 51206.7 51328.5 51411.0 51444.2 53682.0 53742.5 53799.0 53853.7 53915.5 15757.2 16448.9 16465.0 16471.8 16476.0 13572.2 13622.3 13652.1 13654.6 13664.7 13452.9 13692.4 13722.0 13766.0 13827.4 5333.8 5339.7 5462.6 5475.9 5514.6 1089.9 1090.9 1098.1 1108.7 1112.9 25033.7 25161.4 25169.6 25188.7 25264.6 1205.1 1208.6 1211.3 1212.8 1214.4 13226.9 13310.5 13409.7 17809.7 17942.9 260266.0 261009.0 262514.0 288249.0 289788.0 62804.0 62844.4 63020.7 63070.7 63156.0 1523.5 1552.4 1556.1 1559.3 1566.1 29556.2 29574.4 29737.3 29756.9 29848.0 38099.2 38223.2 41471.2 41472.6 41537.6 228247.0 228745.0 229619.0 229800.0 230518.0 35438.6 35508.3 35637.8 35904.5 35939.8 44526.3 45479.0 45710.5 45733.3 45848.0 34321.5 34471.0 34539.7 34577.5 34836.7 34188.3 34273.7 34780.8 35006.3 35304.3 40107.8 40424.0 40609.8 40667.8 40746.2 33755.7 33825.5 34200.8 34318.5 34335.8 25055.5 25136.7 25279.4 25441.2 34939.7 04:31:17 INFO - PID 3345 | 04:31:17 INFO - PID 3345 | [#2] jquery.html Cycles:180 Average:40904.73 Median:23356.44 stddev:104018.33 (445.4%) stddev-sans-first:104266.01 04:31:17 INFO - PID 3345 | Values: 558.1 561.7 565.6 570.5 571.7 22590.3 23352.0 23360.9 23366.7 23422.5 26412.3 26714.2 26832.3 26841.9 26920.2 6197.6 6314.3 6340.1 6345.7 6348.9 6192.7 6298.2 6412.9 6464.7 6465.0 6381.7 6400.7 6403.2 6418.6 6428.1 6383.8 6392.9 6395.4 6402.3 6416.6 5960.6 5961.7 5964.5 5965.1 5965.3 30490.8 30867.2 31056.4 31276.0 31410.5 2210.6 2235.9 2236.1 2236.4 2237.1 20033.4 20521.2 20545.6 20576.1 20594.3 2331.3 2360.3 2388.3 2399.6 2406.2 161781.0 166833.2 167737.0 169055.0 169202.0 15142.3 15296.2 15297.2 15818.8 15972.8 23113.7 23274.8 23435.3 23440.0 23485.4 10682.0 10683.6 10736.8 10771.3 10907.0 8909.7 9010.9 10029.8 10075.9 10153.8 9381.6 9623.9 9825.7 9835.2 9892.6 2790.5 2796.3 2821.0 2860.3 2962.7 1055.1 1063.6 1070.3 1071.6 1076.5 26469.0 26929.9 27127.9 28232.2 28567.6 1073.9 1137.8 1138.1 1145.2 1163.0 11926.6 11953.5 12045.2 12105.5 12216.8 600981.0 601495.0 618616.0 638321.0 640816.0 123003.0 124076.4 124212.4 127848.0 131827.3 1306.4 1347.1 1362.4 1366.2 1380.7 19070.6 19539.8 19819.2 20094.8 20133.6 62602.4 62693.1 62969.0 63662.0 64229.7 81438.0 81747.6 81829.6 84157.0 85731.6 12907.3 13372.7 14369.6 14749.4 15087.7 23413.8 24395.5 24579.8 24589.0 24769.9 19345.9 19582.6 19752.0 19787.9 19841.9 15683.8 15857.4 16504.3 16886.6 18151.3 21393.0 21980.9 22015.0 22242.3 22264.4 22788.8 23054.8 23329.2 23764.4 24066.8 19978.0 20387.6 20548.6 20552.3 20700.8 04:31:17 INFO - PID 3345 | 04:31:17 INFO - PID 3345 | [#3] mootools.html Cycles:180 Average:9918.20 Median:23317.09 stddev:7088.16 (30.4%) stddev-sans-first:7079.01 04:31:17 INFO - PID 3345 | Values: 1380.4 1401.3 1416.6 1421.0 1443.5 13646.7 15560.8 16177.0 16186.7 16186.9 13456.2 15033.0 16115.8 16130.8 16133.9 2689.4 2747.2 2750.3 2752.1 2754.4 2628.4 2658.3 2721.5 2722.0 2722.3 2635.9 2666.6 2699.2 2699.3 2699.7 2558.3 2561.8 2564.0 2564.3 2565.5 2485.9 2555.9 2558.4 2562.6 2567.1 15372.8 15709.9 15868.4 15872.1 15884.5 8016.9 8261.2 8267.5 8272.3 8273.7 5023.9 5167.7 5299.2 5316.1 5389.1 1870.3 1881.5 1892.0 1922.6 1951.8 26849.1 27686.3 27893.6 28029.0 28057.2 7100.9 7119.5 7204.0 7220.5 7307.2 18604.1 18631.8 18652.9 18663.3 18681.1 7858.0 8491.8 8532.9 8533.7 8542.6 7689.7 7754.3 7843.7 7967.2 8076.2 5338.2 5451.0 5453.1 5457.5 5464.7 1539.7 1589.6 1596.2 1608.4 1625.5 956.7 961.5 963.8 975.7 991.0 9265.5 9571.8 9619.2 9648.5 9716.5 1045.5 1045.7 1046.8 1052.0 1061.4 5606.4 5883.7 6026.3 6068.5 6100.1 22615.5 22862.0 22921.4 23168.8 23318.0 22203.6 22733.2 23076.8 23316.2 23350.6 10491.8 11020.2 12303.9 12448.0 12480.8 9769.4 9847.6 10068.6 10143.2 10206.3 19441.5 19959.7 20308.8 20395.9 20396.0 20652.0 20886.6 20935.0 20981.8 21154.4 11829.8 12029.9 12030.0 12038.2 12065.3 13924.1 14198.8 14227.9 14302.1 14307.8 11330.5 11361.4 11368.9 11571.9 11695.8 11560.0 11741.5 12007.6 12021.4 12262.2 10761.4 11035.2 11040.7 11047.1 11048.5 8075.5 8203.8 8288.6 8304.0 8354.2 8472.4 8492.1 8587.9 8606.3 8610.4 04:31:17 INFO - PID 3345 | 04:31:17 INFO - PID 3345 | [#4] prototype.html Cycles:180 Average:5530.31 Median:6278.98 stddev:1582.02 (25.2%) stddev-sans-first:1540.89 04:31:17 INFO - PID 3345 | Values: 507.7 510.0 519.4 535.2 541.1 6077.8 6123.6 6184.6 6193.7 6244.1 6031.3 6047.5 6060.3 6077.4 6099.3 4852.2 4862.4 4901.9 4902.3 4904.5 4627.5 4722.4 4844.1 4862.2 4867.0 4689.7 4696.0 4755.3 4772.8 4777.8 4709.9 4715.8 4759.0 4767.2 4782.8 4177.3 4268.0 4278.0 4288.9 4313.5 5923.6 5933.6 5953.2 6025.2 6040.2 5668.3 5691.4 5691.8 5705.7 5742.7 6532.6 6534.7 6554.8 6577.0 6602.6 4828.4 4843.7 4846.4 4881.0 4888.8 6636.8 6655.9 6701.9 6793.3 6932.9 4913.3 4975.4 4982.9 5001.6 5005.7 5085.2 5098.5 5101.0 5111.9 5122.2 5580.7 5601.3 5650.8 5671.5 5690.8 6522.5 6538.2 6560.7 6571.9 6608.3 1774.4 1778.6 1779.3 1781.1 1795.8 1187.8 1229.8 1230.1 1236.4 1240.8 6313.8 6363.6 6368.0 6388.2 6432.5 6649.7 6716.2 6720.9 6742.4 6743.4 6228.5 6346.9 6353.5 6355.7 6380.3 3556.8 3559.3 3559.5 3575.2 3593.2 6915.7 6978.9 7025.6 7035.3 7104.3 6683.9 6695.4 6766.8 6788.0 6834.8 6385.2 7017.3 7054.0 7106.5 7118.7 6449.3 6551.2 6553.7 6578.7 6642.0 6351.8 6423.2 6505.2 6580.9 6593.8 7032.2 7048.4 7087.2 7117.8 7134.2 4986.8 4993.7 5039.6 5065.5 5066.9 6518.5 6562.7 6567.6 6796.9 6832.4 6352.2 6357.9 6405.5 6458.2 6525.9 6416.6 6438.5 6495.7 6520.5 6521.5 6456.3 6498.6 6509.7 6528.8 6536.2 6422.2 6495.7 6505.5 6516.4 6533.8 6351.5 6375.6 6391.0 6397.9 6440.1 04:31:17 INFO - PID 3345 | 04:31:17 INFO - PID 3345 | [#5] yui.html Cycles:180 Average:28248.97 Median:19919.80 stddev:40997.45 (205.8%) stddev-sans-first:41061.04 04:31:17 INFO - PID 3345 | Values: 902.6 930.4 935.9 941.8 945.1 18825.2 19890.8 19899.2 19940.4 21886.2 22266.3 22589.7 22638.2 22724.4 22766.7 1039.2 1081.5 1082.2 1082.8 1083.3 1040.1 1088.3 1090.4 1091.3 1091.9 1103.5 1103.6 1104.0 1104.2 1140.7 1102.2 1102.5 1103.1 1103.2 1142.9 805.0 805.0 805.2 812.0 841.5 19976.1 20101.6 20125.0 20249.0 20259.6 21766.0 22193.1 22213.7 22233.1 22672.0 16459.8 16687.0 16877.1 16908.2 16912.6 3765.5 3787.5 3787.9 3788.3 3789.8 153701.0 154893.2 155229.2 155607.0 156160.0 16685.9 16988.4 17019.5 17027.9 17219.1 33349.7 33750.8 33813.3 33904.0 33934.3 8358.2 8613.7 8878.8 9006.5 9015.5 4484.9 4564.5 4568.6 4571.0 4602.2 5404.5 5528.8 5569.8 5571.4 5584.2 1147.5 1176.1 1183.3 1184.8 1186.7 705.0 709.4 721.1 726.3 733.6 12256.3 12626.8 12699.9 13359.7 13703.2 698.9 703.0 705.1 719.0 719.8 6006.6 6110.4 6114.9 6178.6 6242.9 138664.0 142026.3 142044.0 142076.3 147269.0 122175.4 124475.0 126960.4 127739.0 131266.3 106339.5 106985.0 107813.0 108114.0 111355.4 13752.8 14239.4 14318.2 14444.1 14525.6 49540.5 50291.0 50460.2 51286.5 51402.2 84926.0 86749.6 87527.6 87558.2 93637.5 19168.5 19991.7 20210.3 20469.4 21155.9 22059.1 22164.4 22384.9 23305.8 23389.2 19260.5 19741.8 20073.0 20151.9 20731.5 17323.1 17502.6 17634.2 17654.0 17780.3 18870.3 18942.8 18964.1 19022.3 19459.3 13908.4 14032.4 14060.0 14329.2 14440.1 14207.1 14789.4 15588.8 15688.3 15888.1 04:31:17 INFO - PID 3345 | -------- Summary: end -------- 04:31:17 INFO - PID 3345 | 04:31:18 INFO - TEST-INFO | 3345: exit 0 04:31:18 INFO - TEST-OK | dromaeo_css | took 1110004ms 04:31:18 INFO - TEST-START | kraken 04:31:18 INFO - Initialising browser for kraken test... 04:31:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38934/getInfo.html -profile /tmp/tmplwOOdw/profile 04:31:18 INFO - TEST-INFO | started process 3721 (/builds/slave/test/build/application/firefox/firefox http://localhost:38934/getInfo.html) 04:31:24 INFO - TEST-INFO | 3721: exit 0 04:31:24 INFO - Browser initialized. 04:31:24 INFO - Running cycle 1/1 for kraken test... 04:31:24 INFO - TEST-INFO | started process 3933 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplwOOdw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:31:25 INFO - PID 3933 | 04:31:25 INFO - PID 3933 | (/builds/slave/test/build/application/firefox/firefox:3982): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:31:25 INFO - PID 3933 | 04:31:35 INFO - PID 3933 | RSS: Main: 162295808 04:31:35 INFO - PID 3933 | 04:31:38 INFO - PID 3933 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38934/tests/kraken/driver.html?testName=audio-beat-detection) 04:31:38 INFO - PID 3933 | RSS: Main: 168411136 04:31:38 INFO - PID 3933 | 04:31:41 INFO - PID 3933 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38934/tests/kraken/driver.html?testName=audio-dft) 04:31:42 INFO - PID 3933 | RSS: Main: 172494848 04:31:42 INFO - PID 3933 | 04:31:45 INFO - PID 3933 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38934/tests/kraken/driver.html?testName=audio-fft) 04:31:45 INFO - PID 3933 | RSS: Main: 165953536 04:31:45 INFO - PID 3933 | 04:31:47 INFO - PID 3933 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38934/tests/kraken/driver.html?testName=audio-oscillator) 04:31:48 INFO - PID 3933 | RSS: Main: 166703104 04:31:48 INFO - PID 3933 | 04:31:50 INFO - PID 3933 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38934/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:31:51 INFO - PID 3933 | RSS: Main: 166551552 04:31:51 INFO - PID 3933 | 04:31:54 INFO - PID 3933 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38934/tests/kraken/driver.html?testName=imaging-darkroom) 04:31:54 INFO - PID 3933 | RSS: Main: 168050688 04:31:54 INFO - PID 3933 | 04:31:58 INFO - PID 3933 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38934/tests/kraken/driver.html?testName=imaging-desaturate) 04:31:58 INFO - PID 3933 | RSS: Main: 170184704 04:31:58 INFO - PID 3933 | 04:32:01 INFO - PID 3933 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38934/tests/kraken/driver.html?testName=json-parse-financial) 04:32:02 INFO - PID 3933 | RSS: Main: 169410560 04:32:02 INFO - PID 3933 | 04:32:03 INFO - PID 3933 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38934/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:32:04 INFO - PID 3933 | RSS: Main: 168927232 04:32:04 INFO - PID 3933 | 04:32:06 INFO - PID 3933 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:32:06 INFO - PID 3933 | RSS: Main: 169025536 04:32:06 INFO - PID 3933 | 04:32:08 INFO - PID 3933 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:32:09 INFO - PID 3933 | RSS: Main: 165908480 04:32:09 INFO - PID 3933 | 04:32:11 INFO - PID 3933 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:32:12 INFO - PID 3933 | RSS: Main: 166092800 04:32:12 INFO - PID 3933 | 04:32:15 INFO - PID 3933 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:32:16 INFO - PID 3933 | RSS: Main: 166137856 04:32:16 INFO - PID 3933 | 04:32:17 INFO - PID 3933 | Cycle 1(1): loaded http://localhost:38934/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38934/tests/kraken/driver.html?testName=ai-astar) 04:32:18 INFO - PID 3933 | RSS: Main: 167735296 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | __start_tp_report 04:32:18 INFO - PID 3933 | _x_x_mozilla_page_load 04:32:18 INFO - PID 3933 | _x_x_mozilla_page_load_details 04:32:18 INFO - PID 3933 | |i|pagename|runs| 04:32:18 INFO - PID 3933 | |0;ai-astar;97;108;101;106;106;107;112;99;106;108 04:32:18 INFO - PID 3933 | |1;audio-beat-detection;143;113;143;178;114;142;142;112;172;114 04:32:18 INFO - PID 3933 | |2;audio-dft;162;164;161;161;161;163;163;161;169;168 04:32:18 INFO - PID 3933 | |3;audio-fft;74;75;74;159;75;74;74;90;98;75 04:32:18 INFO - PID 3933 | |4;audio-oscillator;78;77;125;80;78;78;124;77;78;127 04:32:18 INFO - PID 3933 | |5;imaging-gaussian-blur;113;113;117;114;114;114;114;114;114;120 04:32:18 INFO - PID 3933 | |6;imaging-darkroom;148;149;149;150;148;148;148;148;148;157 04:32:18 INFO - PID 3933 | |7;imaging-desaturate;94;94;95;94;95;94;94;94;94;94 04:32:18 INFO - PID 3933 | |8;json-parse-financial;50;48;48;50;49;55;53;49;47;47 04:32:18 INFO - PID 3933 | |9;json-stringify-tinderbox;55;54;54;54;54;54;54;54;54;53 04:32:18 INFO - PID 3933 | |10;stanford-crypto-aes;75;73;74;75;74;74;75;73;73;74 04:32:18 INFO - PID 3933 | |11;stanford-crypto-ccm;126;121;116;117;128;120;117;114;118;163 04:32:18 INFO - PID 3933 | |12;stanford-crypto-pbkdf2;162;165;164;170;163;170;179;157;159;160 04:32:18 INFO - PID 3933 | |13;stanford-crypto-sha256-iterative;64;63;62;61;61;60;61;60;63;62 04:32:18 INFO - PID 3933 | __end_tp_report 04:32:18 INFO - PID 3933 | __start_cc_report 04:32:18 INFO - PID 3933 | _x_x_mozilla_cycle_collect,851 04:32:18 INFO - PID 3933 | __end_cc_report 04:32:18 INFO - PID 3933 | __startTimestamp1502451138121__endTimestamp 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | ------- Summary: start ------- 04:32:18 INFO - PID 3933 | Number of tests: 14 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | [#0] ai-astar Cycles:10 Average:105.00 Median:108.00 stddev:4.59 (4.3%) stddev-sans-first:3.86 04:32:18 INFO - PID 3933 | Values: 97.0 108.0 101.0 106.0 106.0 107.0 112.0 99.0 106.0 108.0 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | [#1] audio-beat-detection Cycles:10 Average:137.30 Median:142.50 stddev:24.24 (17.0%) stddev-sans-first:25.62 04:32:18 INFO - PID 3933 | Values: 143.0 113.0 143.0 178.0 114.0 142.0 142.0 112.0 172.0 114.0 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | [#2] audio-dft Cycles:10 Average:163.30 Median:163.00 stddev:2.95 (1.8%) stddev-sans-first:3.09 04:32:18 INFO - PID 3933 | Values: 162.0 164.0 161.0 161.0 161.0 163.0 163.0 161.0 169.0 168.0 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | [#3] audio-fft Cycles:10 Average:86.80 Median:75.00 stddev:26.71 (35.6%) stddev-sans-first:27.93 04:32:18 INFO - PID 3933 | Values: 74.0 75.0 74.0 159.0 75.0 74.0 74.0 90.0 98.0 75.0 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:78.00 stddev:22.89 (29.3%) stddev-sans-first:23.69 04:32:18 INFO - PID 3933 | Values: 78.0 77.0 125.0 80.0 78.0 78.0 124.0 77.0 78.0 127.0 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:114.00 stddev:2.16 (1.9%) stddev-sans-first:2.20 04:32:18 INFO - PID 3933 | Values: 113.0 113.0 117.0 114.0 114.0 114.0 114.0 114.0 114.0 120.0 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:148.50 stddev:2.79 (1.9%) stddev-sans-first:2.92 04:32:18 INFO - PID 3933 | Values: 148.0 149.0 149.0 150.0 148.0 148.0 148.0 148.0 148.0 157.0 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:32:18 INFO - PID 3933 | Values: 94.0 94.0 95.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:49.50 stddev:2.59 (5.2%) stddev-sans-first:2.74 04:32:18 INFO - PID 3933 | Values: 50.0 48.0 48.0 50.0 49.0 55.0 53.0 49.0 47.0 47.0 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | [#9] json-stringify-tinderbox Cycles:10 Average:54.00 Median:54.00 stddev:0.47 (0.9%) stddev-sans-first:0.33 04:32:18 INFO - PID 3933 | Values: 55.0 54.0 54.0 54.0 54.0 54.0 54.0 54.0 54.0 53.0 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:74.00 stddev:0.82 (1.1%) stddev-sans-first:0.78 04:32:18 INFO - PID 3933 | Values: 75.0 73.0 74.0 75.0 74.0 74.0 75.0 73.0 73.0 74.0 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | [#11] stanford-crypto-ccm Cycles:10 Average:124.00 Median:120.50 stddev:14.39 (11.9%) stddev-sans-first:15.25 04:32:18 INFO - PID 3933 | Values: 126.0 121.0 116.0 117.0 128.0 120.0 117.0 114.0 118.0 163.0 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.90 Median:164.50 stddev:6.54 (4.0%) stddev-sans-first:6.85 04:32:18 INFO - PID 3933 | Values: 162.0 165.0 164.0 170.0 163.0 170.0 179.0 157.0 159.0 160.0 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:62.00 stddev:1.34 (2.2%) stddev-sans-first:1.13 04:32:18 INFO - PID 3933 | Values: 64.0 63.0 62.0 61.0 61.0 60.0 61.0 60.0 63.0 62.0 04:32:18 INFO - PID 3933 | -------- Summary: end -------- 04:32:18 INFO - PID 3933 | 04:32:18 INFO - PID 3933 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:32:18 INFO - TEST-INFO | 3933: exit 0 04:32:18 INFO - TEST-OK | kraken | took 60461ms 04:32:18 INFO - SUITE-END | took 1170s 04:32:19 INFO - Completed test suite (00:19:30) 04:32:19 INFO - javascript benchmark 04:32:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8964.470820195274, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3469.9306013879723, 3491.3890069237887, 3504.351694936437, 3512.525809015782, 3513.5256740340064, 6174.073888916665, 6231.719572619231, 6238.6256824590555, 6290.7798227062, 6313.1792866927235, 6083.574149809504, 6207.875842483148, 6231.252249730027, 6238.563300568962, 6241.251049874021, 5461.317335333073, 5470.152126420411, 5470.671759694411, 5488.7530061147345, 5575.69333686647, 5565.526930210937, 5566.832995010156, 5566.86082847928, 5592.133219351007, 5619.129034999582, 5468.699221542814, 5502.367227768817, 5534.0, 5626.493615574618, 5627.324721033461, 5314.5216930476445, 5459.372172200208, 5469.343678758534, 5502.669839809584, 5520.806771763009, 5427.213054107172, 5458.699771512565, 5497.615166938316, 5513.448655134454, 5531.032069387881, 6020.548458865559, 6111.0, 6128.540359473057, 6149.538784591129, 6239.126522286838, 6659.866802663968, 6661.233958094833, 6677.399034086956, 6732.1921369435495, 6736.0, 6717.798466046026, 6787.660616969132, 6793.252742198354, 6799.626020568867, 6811.931880681229, 6190.876182476371, 6199.194104766351, 6254.249490061222, 6268.028455589391, 6295.370462953714, 6799.0, 6864.965675171692, 6891.552049116792, 6933.341332573397, 6988.6855091521, 6650.567713098633, 6663.433608143265, 6717.261101278856, 6717.832054198587, 6732.158480189976, 6677.232118306457, 6684.899726504106, 6685.598864068074, 6763.323667633197, 6770.0, 6557.442617377481, 6638.402543771083, 6713.899291510631, 6792.08306878575, 6794.524383293221, 6676.132102826601, 6748.696308666122, 6753.527253092237, 6753.986901964745, 6755.0, 4883.0, 4892.094962431962, 4902.828401006019, 4905.386826646669, 4907.263910413395, 4260.829566817292, 4274.679399045084, 4316.611504964505, 4356.673249506236, 4368.737875727466, 6268.905966410507, 6348.333424990402, 6348.809535713936, 6361.777337793155, 6365.108884756138, 6664.866702666017, 6677.499187560855, 6735.057092007123, 6752.5273230874345, 6756.425703815231, 6206.968965155145, 6302.936970630327, 6328.177336946167, 6337.207849018872, 6394.200724909387, 5366.838994830161, 5408.161734931092, 5424.83725488236, 5427.6200665953, 5433.918491222635, 7043.788686339316, 7084.822879427952, 7112.182099058675, 7113.75101871432, 7122.501424900311, 6560.737570497126, 6629.46964242869, 6647.302033286629, 6716.328367163244, 6736.0, 6937.7224911002995, 7042.647867606702, 7102.538335008415, 7167.641617919187, 7181.102362204724, 6662.467002639874, 6663.067170596218, 6677.432418244503, 6687.732490700512, 6695.799126026227, 6679.332066793476, 6713.462922966053, 6716.9588713749445, 6771.424428923597, 6777.559458635173, 6475.3200913903365, 6481.773137940149, 6555.541112122106, 6558.278589355263, 6563.113979612691, 6454.935450645621, 6521.380468855451, 6570.375814297634, 6591.703373348115, 6638.867222655424, 6470.029495575702, 6516.152900123143, 6535.803925882231, 6551.279359270381, 6551.50863685216, 6433.903491447726, 6526.4452521536205, 6546.803595892131, 6614.0078988150235, 6616.569922954993, 6331.493480521639, 6336.936630633634, 6381.553291269753, 6406.167198264196, 6449.097126402401], "value": 6117.59192498401, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [919.9080091990802, 960.5053397500286, 974.1768205866045, 1207.9275243485392, 1212.9696757581064, 34591.827040864795, 35230.471542926876, 35231.471527927104, 43335.56664433355, 43772.34341484872, 45235.0, 45550.0, 45584.772076139576, 45765.0, 45851.0, 7695.653695583685, 7815.491993020435, 7817.804554886116, 7838.412119091063, 7841.803954901116, 7672.271134242238, 7757.844843103135, 7760.805979850493, 7762.184970578092, 7765.0, 7799.883001754978, 7820.491668041559, 7821.139674635786, 7823.882641760378, 7824.0, 7762.0, 7778.727744528943, 7779.649915753805, 7780.533168009937, 7785.299323060924, 7662.846743065107, 7715.614219289069, 7717.8456430871065, 7722.0, 7723.691052357955, 40819.59180408187, 40916.0, 41069.794651026554, 41106.17787644231, 41188.38217426741, 3501.7548771586, 3533.222691007974, 3534.3814832404228, 3535.8232088395735, 3538.327717733622, 31895.202619934455, 32027.35945281081, 32034.519482207786, 32047.71809127632, 32171.678283217338, 4857.684250523705, 4994.126027945095, 5054.974725126351, 5079.873003174913, 5103.770330335144, 203682.0, 205874.0, 206143.0, 206272.0, 206542.0, 51193.7440312796, 51206.74396627993, 51328.48671513237, 51411.0, 51444.22833657498, 53682.0, 53742.46257537453, 53799.0, 53853.73073134688, 53915.46084539104, 15757.212139392985, 16448.917755411385, 16465.0, 16471.752923706154, 16476.0, 13572.1856688599, 13622.250776207404, 13652.112612680145, 13654.590362289147, 13664.65838354049, 13452.93273533626, 13692.383842727102, 13722.0, 13766.0, 13827.446902124004, 5333.813316533902, 5339.733013349394, 5462.617616766867, 5475.863103422446, 5514.61397702157, 1089.8528698625742, 1090.8909109089186, 1098.0776148035688, 1108.7449886525983, 1112.8608923884515, 25033.749662503506, 25161.370965726004, 25169.62245566318, 25188.748112519006, 25264.621030684553, 1205.0660737928176, 1208.6132437619876, 1211.3398197982121, 1212.8483939507562, 1214.4352875912743, 13226.933865330611, 13310.534131305358, 13409.731805364034, 17809.7328540072, 17942.910285448488, 260266.0, 261009.0, 262514.0, 288249.0, 289788.0, 62804.0, 62844.371556284765, 63020.68489657522, 63070.684646576476, 63156.0, 1523.512476007667, 1552.4100841680315, 1556.113015581108, 1559.3294883200333, 1566.1307974074498, 29556.22232317997, 29574.408511829213, 29737.25656858596, 29756.85121574465, 29848.0, 38099.238015240095, 38223.23553528969, 41471.170576588906, 41472.58527414687, 41537.58462415337, 228247.0, 228745.0, 229619.0, 229800.0, 230518.0, 35438.64561354354, 35508.289834203686, 35637.82181089078, 35904.461433078, 35939.820300898326, 44526.332105017806, 45479.0, 45710.54289457196, 45733.314000289356, 45848.0, 34321.48517772186, 34471.0, 34539.654603453644, 34577.48133777945, 34836.65163348334, 34188.31623367569, 34273.65726342705, 34780.82609586936, 35006.299874002885, 35304.293914122085, 40107.799461002505, 40424.0, 40609.79695101506, 40667.7966610165, 40746.1850762989, 33755.66244337525, 33825.49261761026, 34200.82899585486, 34318.48522272118, 34335.828320858236, 25055.498890021732, 25136.74863251344, 25279.36801579975, 25441.222778141513, 34939.65060349466], "value": 18349.530004378168, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.0986706469052, 561.7472137538109, 565.5899472882161, 570.5150621971322, 571.7484306904962, 22590.322290331296, 23352.0, 23360.883195584018, 23366.714830684305, 23422.531549369043, 26412.33969150772, 26714.19857404281, 26832.329191770215, 26841.865790671018, 26920.192394228205, 6197.597156184834, 6314.336994615568, 6340.144080549136, 6345.650989195592, 6348.873022539547, 6192.690365481731, 6298.181236439256, 6412.871742565146, 6464.741410343604, 6465.0, 6381.680915954207, 6400.743970241185, 6403.167588213549, 6418.646974416429, 6428.132202152697, 6383.77656782015, 6392.872142557169, 6395.392437718409, 6402.295747467775, 6416.550841441101, 5960.612560183574, 5961.701914904281, 5964.493018093467, 5965.0754133109, 5965.254343207099, 30490.84754576235, 30867.22831929197, 31056.37887242265, 31276.0, 31410.528842067386, 2210.5910406574835, 2235.9217427389963, 2236.0720301075035, 2236.4297104238476, 2237.127520267097, 20033.398998030083, 20521.17915283387, 20545.589088218152, 20576.074076666588, 20594.27920022807, 2331.347222777625, 2360.280114565057, 2388.3193289912465, 2399.6160614301803, 2406.2179791567637, 161781.0, 166833.16583417004, 167737.0, 169055.0, 169202.0, 15142.318595663222, 15296.158711270988, 15297.158656273788, 15818.76271855923, 15972.840271597368, 23113.653295200584, 23274.767252327598, 23435.296941091794, 23440.0, 23485.41286467852, 10681.961639968438, 10683.62607308756, 10736.838947415796, 10771.299865508874, 10907.0, 8909.73270801877, 9010.909890901006, 10029.849552256723, 10075.899241007495, 10153.847692284622, 9381.577828997712, 9623.855642165374, 9825.656102036395, 9835.213182945346, 9892.60429582823, 2790.4698107359536, 2796.3428594280326, 2820.971790282112, 2860.284928767808, 2962.7481664058364, 1055.1400608504046, 1063.5852017713098, 1070.25617196048, 1071.5820829876354, 1076.5424694504804, 26469.0, 26929.86535067312, 27127.864360678464, 28232.15303540897, 28567.57148642772, 1073.8657667791526, 1137.8350139229765, 1138.0610995928391, 1145.1983611472003, 1163.03468121459, 11926.642200733992, 11953.52185912571, 12045.15683902136, 12105.515779368903, 12216.816747748791, 600981.0, 601495.0, 618616.0, 638321.0, 640816.0, 123003.0, 124076.37961810133, 124212.37893810472, 127848.0, 131827.34086329507, 1306.4382315604382, 1347.1311004402105, 1362.420971087284, 1366.1939455721172, 1380.6893448974101, 19070.618587628447, 19539.804601953798, 19819.20723171057, 20094.799052009294, 20133.597328053063, 62602.37397625965, 62693.05960410506, 62969.0, 63662.045069323074, 64229.678851605444, 81438.0, 81747.5912620457, 81829.59085204535, 84157.0, 85731.5713421429, 12907.290099044643, 13372.665683357995, 14369.56891293263, 14749.410023598935, 15087.698246035237, 23413.76586234184, 24395.536267823987, 24579.75420245846, 24589.0, 24769.876150619133, 19345.90327048412, 19582.608347833248, 19752.0, 19787.901060494605, 19841.900790495954, 15683.764743529084, 15857.444989425314, 16504.33982640681, 16886.57783555372, 18151.27394904189, 21393.0, 21980.89009554942, 22015.0, 22242.332730018126, 22264.443388915406, 22788.772112278664, 23054.847257637386, 23329.18347857817, 23764.405889852893, 24066.759332407153, 19978.0, 20387.59224815525, 20548.589028219652, 20552.28067017647, 20700.792992069888], "value": 12930.306587251525, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.3581334679373, 1401.3343661760664, 1416.589189135151, 1421.0265967812043, 1443.5019918128241, 13646.658833529142, 15560.84439155608, 16177.029378237314, 16186.676266474678, 16186.91906540467, 13456.1926284423, 15033.0, 16115.83884161158, 16130.83869161311, 16133.919330403332, 2689.408330167365, 2747.230775382896, 2750.339918419585, 2752.146834481307, 2754.4353407551503, 2628.4217472156192, 2658.3088397016722, 2721.4557088582264, 2722.0336780442954, 2722.3058120179326, 2635.8813853376646, 2666.6133410655443, 2699.2442116207494, 2699.25770413136, 2699.6625421822273, 2558.2581051495044, 2561.7950563954837, 2564.025670245295, 2564.3332733489333, 2565.4740778140435, 2485.9378515537073, 2555.9105431309813, 2558.3604098975256, 2562.5515534781525, 2567.075852693029, 15372.846271537364, 15709.900306978516, 15868.444604438904, 15872.12703301318, 15884.52346429609, 8016.879746803803, 8261.17388261169, 8267.503949762973, 8272.296854767352, 8273.669053237863, 5023.949760502384, 5167.6641018333685, 5299.205119232108, 5316.149416093445, 5389.110796718537, 1870.2799422222542, 1881.491997160759, 1891.9527011824678, 1922.6250881078195, 1951.834094102003, 26849.0602828902, 27686.3078423039, 27893.58159627607, 28029.0, 28057.158285251477, 7100.857982840314, 7119.4660400470175, 7204.027456293438, 7220.494565380373, 7307.19620841707, 18604.06979651012, 18631.813681863277, 18652.906735466237, 18663.253469861054, 18681.06594670261, 7858.0, 8491.787705307417, 8532.914670853212, 8533.658653653909, 8542.572871356408, 7689.692412303557, 7754.263344982218, 7843.7254696085365, 7967.2431119043595, 8076.232757887991, 5338.225957236256, 5450.972745136249, 5453.072977593739, 5457.508824205841, 5464.672119672832, 1539.7151526967548, 1589.6025993501626, 1596.1859451679495, 1608.4209684513567, 1625.504221212518, 956.6938579654444, 961.5144352102144, 963.7638778499313, 975.6877799104218, 990.9554070066864, 9265.490398028038, 9571.808563828685, 9619.230461563058, 9648.517574121266, 9716.514174291398, 1045.4877110216091, 1045.717656232813, 1046.8011077895176, 1051.9631812886512, 1061.3737894642045, 5606.439356064361, 5883.735231914574, 6026.276846778327, 6068.4538391544975, 6100.054491553817, 22615.54768904613, 22862.0, 22921.426964326027, 23168.768312317, 23318.0, 22203.555928881655, 22733.204337848096, 23076.769232307797, 23316.183933562243, 23350.64974025391, 10491.790164196673, 11020.173486988508, 12303.938480307721, 12448.0, 12480.81278780819, 9769.364991275545, 9847.60609575609, 10068.597256109673, 10143.188544916242, 10206.285560010729, 19441.51396215106, 19959.700604490652, 20308.79691203069, 20395.8980205098, 20396.0, 20652.0, 20886.58226835485, 20935.0, 20981.790182098594, 21154.365369038955, 11829.822552661544, 12029.939850300692, 12030.037596992395, 12038.217515861441, 12065.336406497727, 13924.094933829674, 14198.787018194942, 14227.857721422653, 14302.141871487744, 14307.785383219052, 11330.490127944428, 11361.43192840338, 11368.886311136783, 11571.942140289244, 11695.76608467809, 11559.959603635712, 11741.530338786695, 12007.579734709243, 12021.398930053638, 12262.20295680778, 10761.408122553335, 11035.227534072537, 11040.723981900517, 11047.060999815105, 11048.502817373077, 8075.515469071875, 8203.753887383387, 8288.627011784605, 8304.003519577689, 8354.248117669438, 8472.406931514735, 8492.108328625653, 8587.91412085896, 8606.26846718036, 8610.44032137909], "value": 7039.388209601965, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.7334399440291, 509.9872503187421, 519.4364114935296, 535.2479765928871, 541.0828645445971, 6077.817665470032, 6123.601965872226, 6184.598001129929, 6193.659348735823, 6244.12582238487, 6031.306399764017, 6047.546434017455, 6060.333363330041, 6077.359113039488, 6099.268087829467, 4852.175130227852, 4862.3922009748785, 4901.901961960776, 4902.338184345122, 4904.4605093439695, 4627.537246275379, 4722.409698787652, 4844.128056949748, 4862.246351815475, 4867.0265946810605, 4689.695169813968, 4696.0, 4755.286706993961, 4772.761361931907, 4777.832775852846, 4709.929351059737, 4715.7877895494785, 4759.048190361907, 4767.165745994437, 4782.784774685148, 4177.3316269397055, 4267.957320426787, 4277.97861010696, 4288.935665964982, 4313.460817397825, 5923.614965027259, 5933.643981361132, 5953.166556682068, 6025.216721826178, 6040.244969378828, 5668.31980162379, 5691.430856914317, 5691.800786972436, 5705.71471426427, 5742.655440673531, 6532.608043517404, 6534.705938232743, 6554.8041406129005, 6576.967115164393, 6602.5708328958945, 4828.372311599504, 4843.709377437365, 4846.36997190363, 4880.951190488086, 4888.755562221874, 6636.83407914806, 6655.900161497581, 6701.899471507932, 6793.286704895964, 6932.930670693279, 4913.287573301851, 4975.407869481731, 4982.90034199314, 5001.64988450805, 5005.724685142352, 5085.228550440019, 5098.4646612105935, 5101.0, 5111.897762044739, 5122.154844450699, 5580.684016133259, 5601.29983752031, 5650.8072938789455, 5671.489565939085, 5690.752152899664, 6522.478201743849, 6538.1500404947055, 6560.671966401661, 6571.901421478682, 6608.306127856602, 1774.3612299572144, 1778.6175972165788, 1779.2616064333415, 1781.118346418531, 1795.7934837493856, 1187.75057237981, 1229.8155276708387, 1230.1266101068343, 1236.4003458322707, 1240.8076748104058, 6313.842153946096, 6363.5863668861375, 6368.012957991518, 6388.169537960035, 6432.549721519542, 6649.73401063952, 6716.194056713128, 6720.899186512206, 6742.393184613408, 6743.415297405106, 6228.5017198624, 6346.936530634727, 6353.491720662429, 6355.682215889187, 6380.266269379081, 3556.7510274280553, 3559.288142371536, 3559.537260156163, 3575.1955809942556, 3593.1915319053005, 6915.749597689078, 6978.895316570255, 7025.578465292099, 7035.33164349386, 7104.325089116525, 6683.927982325495, 6695.397414232645, 6766.763163289262, 6787.966060169668, 6834.794956151323, 6385.201849768779, 7017.333353331425, 7053.964730176316, 7106.477200593818, 7118.679659415235, 6449.322821103717, 6551.181102362205, 6553.705083271359, 6578.736850525926, 6642.0, 6351.841203969753, 6423.229212494531, 6505.21937367519, 6580.934190658224, 6593.835154121185, 7032.156141263081, 7048.365647091787, 7087.22040575526, 7117.82205444868, 7134.215236324104, 4986.750662466935, 4993.725345105909, 5039.622028347815, 5065.544101030925, 5066.9239961401345, 6518.543701940818, 6562.671866406565, 6567.573107747981, 6796.898046529405, 6832.385085342343, 6352.22042884483, 6357.936420635734, 6405.455536279468, 6458.22501299847, 6525.9021114684265, 6416.582922110048, 6438.452731517874, 6495.707693153914, 6520.478361730955, 6521.478281737544, 6456.257530384066, 6498.642574658439, 6509.674516274262, 6528.771492997722, 6536.150300460909, 6422.197225346832, 6495.675216239264, 6505.536254342925, 6516.446102081376, 6533.803985880431, 6351.491880649629, 6375.6174629522375, 6391.0, 6397.936020639734, 6440.13058237132], "value": 5068.341560376297, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [902.5938327752509, 930.3766476460771, 935.8596210568418, 941.8304705153079, 945.1399226703699, 18825.246990120395, 19890.801091989095, 19899.204031838708, 19940.40178794635, 21886.23398181064, 22266.332010039725, 22589.661155082646, 22638.20766273181, 22724.431889202777, 22766.65850012251, 1039.1790485516433, 1081.453865797771, 1082.1613249731442, 1082.837574363844, 1083.2958576924982, 1040.0795296162876, 1088.3252383522226, 1090.351241011601, 1091.3397394576245, 1091.8853520380364, 1103.5144536403957, 1103.5530610102921, 1104.0229396983686, 1104.1608377632976, 1140.726225705833, 1102.2229328323513, 1102.5259138570411, 1103.0568863621568, 1103.189155970361, 1142.874283828778, 804.9839003219903, 804.9959750201211, 805.2108933245394, 811.9837603247961, 841.4908980067032, 19976.10107545164, 20101.597968040704, 20125.0, 20249.0, 20259.594808103902, 21766.020529076228, 22193.11227550912, 22213.666794998087, 22233.11067557312, 22672.0, 16459.83540164595, 16687.0, 16877.07641196018, 16908.15459227046, 16912.577185570335, 3765.5104836371097, 3787.5265591801026, 3787.867424640152, 3788.2802267569073, 3789.7915614641183, 153701.0, 154893.22553387162, 155229.22385388002, 155607.0, 156160.0, 16685.91657041707, 16988.405405810736, 17019.48941531756, 17027.914860425866, 17219.1390430478, 33349.66650333514, 33750.83124584361, 33813.323733525685, 33904.0, 33934.32131357408, 8358.205970432798, 8613.65545378178, 8878.778030549158, 9006.459612423276, 9015.459072455673, 4484.910301793946, 4564.520725323793, 4568.634509239253, 4570.954290457053, 4602.2406302959635, 5404.540614047772, 5528.834134975958, 5569.774649577086, 5571.359293681278, 5584.190292407659, 1147.540983606564, 1176.082655528672, 1183.2545496337252, 1184.7808155491261, 1186.6618013866007, 704.9612271325126, 709.443087176564, 721.1310371002929, 726.3317747672154, 733.614852202598, 12256.264624122581, 12626.81059784104, 12699.873001270054, 13359.73280534384, 13703.246321452216, 698.8742026435189, 702.9507934444642, 705.1467724053849, 718.992810071903, 719.7804669575829, 6006.579539432285, 6110.358412366726, 6114.938850611437, 6178.62928224308, 6242.875142497124, 138664.0, 142026.28986855, 142044.0, 142076.2896185533, 147269.0, 122175.38912305559, 124475.0, 126960.36519817341, 127739.0, 131266.34366828296, 106339.46830265954, 106985.0, 107813.0, 108114.0, 111355.44322278336, 13752.793708094187, 14239.359228834934, 14318.210223722035, 14444.133351998493, 14525.564233073024, 49540.50459495359, 50291.0, 50460.243096354316, 51286.48713512817, 51402.22896656628, 84926.0, 86749.56625216834, 87527.56236218777, 87558.24883502167, 93637.5318123405, 19168.520786980436, 19991.700124498435, 20210.29263975754, 20469.38591842247, 21155.8942205288, 22059.11763529441, 22164.445888852908, 22384.888075559516, 23305.766942331036, 23389.181378651665, 19260.518487037934, 19741.802581973996, 20073.0, 20151.899240503702, 20731.4817129573, 17323.133843308035, 17502.649947000733, 17634.206460709043, 17654.0, 17780.288788448317, 18870.3395381161, 18942.810571894104, 18964.146613402154, 19022.334218302294, 19459.318923837596, 13908.443662253396, 14032.422491854284, 14060.0, 14329.211893345966, 14440.061396009225, 14207.076540025279, 14789.408423662933, 15588.766168507254, 15688.294026769052, 15888.126153061692], "value": 10020.85090873063, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1471.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 108.0, 101.0, 106.0, 106.0, 107.0, 112.0, 99.0, 106.0, 108.0], "value": 105.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 113.0, 143.0, 178.0, 114.0, 142.0, 142.0, 112.0, 172.0, 114.0], "value": 137.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 161.0, 161.0, 161.0, 163.0, 163.0, 161.0, 169.0, 168.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 159.0, 75.0, 74.0, 74.0, 90.0, 98.0, 75.0], "value": 86.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 125.0, 80.0, 78.0, 78.0, 124.0, 77.0, 78.0, 127.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 117.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 120.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 149.0, 150.0, 148.0, 148.0, 148.0, 148.0, 148.0, 157.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 48.0, 48.0, 50.0, 49.0, 55.0, 53.0, 49.0, 47.0, 47.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 74.0, 75.0, 74.0, 74.0, 75.0, 73.0, 73.0, 74.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 121.0, 116.0, 117.0, 128.0, 120.0, 117.0, 114.0, 118.0, 163.0], "value": 124.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 164.0, 170.0, 163.0, 170.0, 179.0, 157.0, 159.0, 160.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 62.0, 61.0, 61.0, 60.0, 61.0, 60.0, 63.0, 62.0], "value": 61.7, "unit": "score"}]}]} 04:32:19 INFO - Return code: 0 04:32:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:32:19 INFO - # TBPL SUCCESS # 04:32:19 INFO - Running post-action listener: _package_coverage_data 04:32:19 INFO - Running post-action listener: _resource_record_post_action 04:32:19 INFO - [mozharness: 2017-08-11 11:32:19.396074Z] Finished run-tests step (success) 04:32:19 INFO - Running post-run listener: _resource_record_post_run 04:32:19 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 45056; Write bytes: 427687936; Read time: 256; Write time: 363952 04:32:19 INFO - TinderboxPrint: CPU usage
13.1% 04:32:19 INFO - TinderboxPrint: I/O read bytes / time
45,056 / 256 04:32:19 INFO - TinderboxPrint: I/O write bytes / time
427,687,936 / 363,952 04:32:19 INFO - TinderboxPrint: CPU idle
8,240.0 (86.9%) 04:32:19 INFO - TinderboxPrint: CPU user
1,190.3 (12.6%) 04:32:19 INFO - TinderboxPrint: Swap in / out
0 / 0 04:32:19 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 108220416; Read time: 0; Write time: 121812 04:32:19 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:32:19 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 40960; Write bytes: 319467520; Read time: 244; Write time: 242140 04:32:20 INFO - Running post-run listener: _upload_blobber_files 04:32:20 INFO - Blob upload gear active. 04:32:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:32:20 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 04:32:20 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'] 04:32:20 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 04:32:20 INFO - (blobuploader) - INFO - Open directory for files ... 04:32:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:32:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:32:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:32:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:32:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:32:26 INFO - (blobuploader) - INFO - Done attempting. 04:32:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:32:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:32:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:32:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:32:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:32:27 INFO - (blobuploader) - INFO - Done attempting. 04:32:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:32:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:32:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:32:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:32:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:32:29 INFO - (blobuploader) - INFO - Done attempting. 04:32:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:32:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:32:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:32:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:32:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:32:30 INFO - (blobuploader) - INFO - Done attempting. 04:32:30 INFO - (blobuploader) - INFO - Iteration through files over. 04:32:30 INFO - Return code: 0 04:32:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:32:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:32:30 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba47df2d3a17b7dfff71a6b7e24914f558fbf8cd280de98722585daecc50178716891d526d59fac4ac15f8260806dcd2ef85ad7aeec1f5671ff77943badcb189", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62e257edb4303ea86061039c07cff8a4b6683472b1c7685f01b0b7652814638f32795d044f7ee9bfb8e53efa36784494076e5af1d826a182a2216801de27c6bc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/282ad1360017c4493944d9e248e82da5a495e56598db88bbe877548f2bb802687ae45cb701ace449205f84e8d1c627bb3296708400367afdd90d76beecf3cd87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9253f11064c20510d2f2437b6426635fff61bdc83bf04459db2fbaffcaab3b10ada5dde14b6118d6620a3eb0598983d34ede31b75769c9d934ca5fe08527fec2"} 04:32:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:32:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:32:30 INFO - Contents: 04:32:30 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba47df2d3a17b7dfff71a6b7e24914f558fbf8cd280de98722585daecc50178716891d526d59fac4ac15f8260806dcd2ef85ad7aeec1f5671ff77943badcb189", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62e257edb4303ea86061039c07cff8a4b6683472b1c7685f01b0b7652814638f32795d044f7ee9bfb8e53efa36784494076e5af1d826a182a2216801de27c6bc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/282ad1360017c4493944d9e248e82da5a495e56598db88bbe877548f2bb802687ae45cb701ace449205f84e8d1c627bb3296708400367afdd90d76beecf3cd87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9253f11064c20510d2f2437b6426635fff61bdc83bf04459db2fbaffcaab3b10ada5dde14b6118d6620a3eb0598983d34ede31b75769c9d934ca5fe08527fec2"} 04:32:30 INFO - Running post-run listener: copy_logs_to_upload_dir 04:32:30 INFO - Copying logs to upload dir... 04:32:30 INFO - mkdir: /builds/slave/test/build/upload/logs 04:32:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.419389 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-11 04:32:30.034808) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-11 04:32:30.042172) ========= 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=eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665 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/ba47df2d3a17b7dfff71a6b7e24914f558fbf8cd280de98722585daecc50178716891d526d59fac4ac15f8260806dcd2ef85ad7aeec1f5671ff77943badcb189", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62e257edb4303ea86061039c07cff8a4b6683472b1c7685f01b0b7652814638f32795d044f7ee9bfb8e53efa36784494076e5af1d826a182a2216801de27c6bc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/282ad1360017c4493944d9e248e82da5a495e56598db88bbe877548f2bb802687ae45cb701ace449205f84e8d1c627bb3296708400367afdd90d76beecf3cd87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9253f11064c20510d2f2437b6426635fff61bdc83bf04459db2fbaffcaab3b10ada5dde14b6118d6620a3eb0598983d34ede31b75769c9d934ca5fe08527fec2"} build_url:https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011455 build_url: 'https://queue.taskcluster.net/v1/task/GGVDEeRYQcC1WejYlVPkgg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ba47df2d3a17b7dfff71a6b7e24914f558fbf8cd280de98722585daecc50178716891d526d59fac4ac15f8260806dcd2ef85ad7aeec1f5671ff77943badcb189", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62e257edb4303ea86061039c07cff8a4b6683472b1c7685f01b0b7652814638f32795d044f7ee9bfb8e53efa36784494076e5af1d826a182a2216801de27c6bc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/282ad1360017c4493944d9e248e82da5a495e56598db88bbe877548f2bb802687ae45cb701ace449205f84e8d1c627bb3296708400367afdd90d76beecf3cd87", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9253f11064c20510d2f2437b6426635fff61bdc83bf04459db2fbaffcaab3b10ada5dde14b6118d6620a3eb0598983d34ede31b75769c9d934ca5fe08527fec2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-11 04:32:30.069910) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:32:30.070239) ========= 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=eca2ab57546de7b41f256b19000001ef-1502449241.897117-527586665 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004541 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-11 04:32:30.155153) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-11 04:32:30.155467) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-11 04:32:30.155789) ========= ========= Total master_lag: 0.19 =========