builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-034 starttime: 1502582960.31 results: success (0) revision: 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.307123) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.308278) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.308641) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.406161) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.406488) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-12 17:09:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.0M=0s 2017-08-12 17:09:20 (43.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.199615 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.664355) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.664691) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014195 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:20.722748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-12 17:09:20.723081) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 --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 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 --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=d6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-12 17:09:20,919 truncating revision to first 12 chars 2017-08-12 17:09:20,919 Setting DEBUG logging. 2017-08-12 17:09:20,919 attempt 1/10 2017-08-12 17:09:20,919 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/59594b3879cc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-12 17:09:22,212 unpacking tar archive at: mozilla-central-59594b3879cc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.619829 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-12 17:09:22.359559) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:22.359911) ========= script_repo_revision: 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:22.360341) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:22.360748) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-12 17:09:22.371371) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-08-12 17:09:22.371714) ========= /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=d6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:09:22 INFO - MultiFileLogger online at 20170812 17:09:22 in /builds/slave/test 17:09:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:09:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:09:22 INFO - {'append_to_log': False, 17:09:22 INFO - 'base_work_dir': '/builds/slave/test', 17:09:22 INFO - 'blob_upload_branch': 'Firefox', 17:09:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:09:22 INFO - 'branch': 'Firefox', 17:09:22 INFO - 'buildbot_json_path': 'buildprops.json', 17:09:22 INFO - 'code_coverage': False, 17:09:22 INFO - 'config_files': ('talos/linux_config.py',), 17:09:22 INFO - 'default_actions': ('clobber', 17:09:22 INFO - 'read-buildbot-config', 17:09:22 INFO - 'download-and-extract', 17:09:22 INFO - 'populate-webroot', 17:09:22 INFO - 'create-virtualenv', 17:09:22 INFO - 'install', 17:09:22 INFO - 'setup-mitmproxy', 17:09:22 INFO - 'run-tests'), 17:09:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:09:22 INFO - 'disable_ccov_upload': False, 17:09:22 INFO - 'download_minidump_stackwalk': True, 17:09:22 INFO - 'download_symbols': 'ondemand', 17:09:22 INFO - 'enable_stylo': False, 17:09:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:09:22 INFO - 'tooltool.py': '/tools/tooltool.py', 17:09:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:09:22 INFO - '/tools/misc-python/virtualenv.py')}, 17:09:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:09:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:09:22 INFO - 'gecko_profile': False, 17:09:22 INFO - 'gecko_profile_interval': 0, 17:09:22 INFO - 'installer_path': 'installer.exe', 17:09:22 INFO - 'log_level': 'info', 17:09:22 INFO - 'log_name': 'talos', 17:09:22 INFO - 'log_to_console': True, 17:09:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:09:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:09:22 INFO - 'opt_config_files': (), 17:09:22 INFO - 'pip_index': False, 17:09:22 INFO - 'suite': 'dromaeojs-e10s', 17:09:22 INFO - 'system_bits': '32', 17:09:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:09:22 INFO - 'title': 'talos-linux64-ix-034', 17:09:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:09:22 INFO - 'use_talos_json': True, 17:09:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:09:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:09:22 INFO - 'work_dir': 'build'} 17:09:22 INFO - [mozharness: 2017-08-13 00:09:22.778197Z] Running clobber step. 17:09:22 INFO - Running pre-action listener: _resource_record_pre_action 17:09:22 INFO - Running main action method: clobber 17:09:22 INFO - rmtree: /builds/slave/test/build 17:09:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:09:23 INFO - Running post-action listener: _resource_record_post_action 17:09:23 INFO - [mozharness: 2017-08-13 00:09:23.752686Z] Finished clobber step (success) 17:09:23 INFO - [mozharness: 2017-08-13 00:09:23.752788Z] Running read-buildbot-config step. 17:09:23 INFO - Running pre-action listener: _resource_record_pre_action 17:09:23 INFO - Running main action method: read_buildbot_config 17:09:23 INFO - Using buildbot properties: 17:09:23 INFO - { 17:09:23 INFO - "project": "", 17:09:23 INFO - "product": "firefox", 17:09:23 INFO - "who": "archaeopteryx@coole-files.de", 17:09:23 INFO - "installer_path": "public/build/target.tar.bz2", 17:09:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 17:09:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 17:09:23 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 17:09:23 INFO - "stage_platform": "linux64-stylosequential", 17:09:23 INFO - "basedir": "/builds/slave/test", 17:09:23 INFO - "buildnumber": 186, 17:09:23 INFO - "platform": "ubuntu64_hw_styloseq", 17:09:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:09:23 INFO - "slavebuilddir": "test", 17:09:23 INFO - "taskId": "T50Du0wbQ4mId4oBFTp6mw", 17:09:23 INFO - "branch": "mozilla-central", 17:09:23 INFO - "script_repo_revision": "production", 17:09:23 INFO - "revision": "59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2", 17:09:23 INFO - "slavename": "talos-linux64-ix-034", 17:09:23 INFO - "repo_path": "mozilla-central" 17:09:23 INFO - } 17:09:23 INFO - Finding installer, test and symbols from parent task. 17:09:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T50Du0wbQ4mId4oBFTp6mw'}, attempt #1 17:09:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T50Du0wbQ4mId4oBFTp6mw'}, attempt #1 17:09:24 INFO - Task dependencies: CP2xHjBSS1Kh1OeG7_y_fA 17:09:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA'}, attempt #1 17:09:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 17:09:25 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.test_packages.json 17:09:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.crashreporter-symbols.zip 17:09:25 INFO - Running post-action listener: _resource_record_post_action 17:09:25 INFO - [mozharness: 2017-08-13 00:09:25.093071Z] Finished read-buildbot-config step (success) 17:09:25 INFO - [mozharness: 2017-08-13 00:09:25.093343Z] Running download-and-extract step. 17:09:25 INFO - Running pre-action listener: _resource_record_pre_action 17:09:25 INFO - Running main action method: download_and_extract 17:09:25 INFO - mkdir: /builds/slave/test/build/tests 17:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:25 INFO - trying https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.test_packages.json 17:09:25 INFO - Downloading https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:09:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:09:25 INFO - Downloaded 989 bytes. 17:09:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:09:25 INFO - Using the following test package requirements: 17:09:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 17:09:25 INFO - u'common': [u'target.common.tests.zip'], 17:09:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:09:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:09:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:09:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:09:25 INFO - u'mozbase': [u'target.common.tests.zip'], 17:09:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:09:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:09:25 INFO - u'web-platform': [u'target.common.tests.zip', 17:09:25 INFO - u'target.web-platform.tests.tar.gz'], 17:09:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:09:25 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:09:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip 17:09:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:09:25 INFO - Fetch https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip into memory 17:09:27 INFO - Content-Length response header: 54034669 17:09:27 INFO - Bytes received: 54034669 17:09:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:09:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip 17:09:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:09:39 INFO - Fetch https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.common.tests.zip into memory 17:09:40 INFO - Content-Length response header: 54034669 17:09:40 INFO - Bytes received: 54034669 17:09:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.talos.tests.zip 17:09:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:09:53 INFO - Fetch https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.talos.tests.zip into memory 17:09:54 INFO - Content-Length response header: 14496050 17:09:54 INFO - Bytes received: 14496050 17:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:54 INFO - trying https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 17:09:54 INFO - Downloading https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:09:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:09:55 INFO - Downloaded 61674492 bytes. 17:09:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 17:09:55 INFO - mkdir: /builds/slave/test/properties 17:09:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:09:55 INFO - Writing to file /builds/slave/test/properties/build_url 17:09:55 INFO - Contents: 17:09:55 INFO - build_url:https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 17:09:55 INFO - Running post-action listener: _resource_record_post_action 17:09:55 INFO - Running post-action listener: set_extra_try_arguments 17:09:55 INFO - [mozharness: 2017-08-13 00:09:55.566411Z] Finished download-and-extract step (success) 17:09:55 INFO - [mozharness: 2017-08-13 00:09:55.566510Z] Running populate-webroot step. 17:09:55 INFO - Running pre-action listener: _resource_record_pre_action 17:09:55 INFO - Running main action method: populate_webroot 17:09:55 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:09:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:09:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:09:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:09:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:09:55 INFO - 'tests': ['damp', 'tps']}, 17:09:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:09:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:09:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:09:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:09:55 INFO - 'other-e10s': {'tests': ['a11yr', 17:09:55 INFO - 'ts_paint', 17:09:55 INFO - 'tpaint', 17:09:55 INFO - 'sessionrestore', 17:09:55 INFO - 'sessionrestore_many_windows', 17:09:55 INFO - 'sessionrestore_no_auto_restore', 17:09:55 INFO - 'tabpaint']}, 17:09:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 17:09:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 17:09:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:09:55 INFO - 'tsvgr_opacity', 17:09:55 INFO - 'tart', 17:09:55 INFO - 'tscrollx', 17:09:55 INFO - 'tsvg_static']}, 17:09:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:09:55 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:09:55 INFO - 'talos_options': ['--mitmproxy', 17:09:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:09:55 INFO - '--firstNonBlankPaint'], 17:09:55 INFO - 'tests': ['tp6_google', 17:09:55 INFO - 'tp6_youtube', 17:09:55 INFO - 'tp6_amazon', 17:09:55 INFO - 'tp6_facebook']}, 17:09:55 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:09:55 INFO - 'talos_options': ['--stylo', 17:09:55 INFO - '--mitmproxy', 17:09:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:09:55 INFO - '--firstNonBlankPaint'], 17:09:55 INFO - 'tests': ['tp6_google', 17:09:55 INFO - 'tp6_youtube', 17:09:55 INFO - 'tp6_amazon', 17:09:55 INFO - 'tp6_facebook']}, 17:09:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:09:55 INFO - 'talos_options': ['--stylo', 17:09:55 INFO - '--stylo-threads=1', 17:09:55 INFO - '--mitmproxy', 17:09:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:09:55 INFO - '--firstNonBlankPaint'], 17:09:55 INFO - 'tests': ['tp6_google', 17:09:55 INFO - 'tp6_youtube', 17:09:55 INFO - 'tp6_amazon', 17:09:55 INFO - 'tp6_facebook']}, 17:09:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:09:55 INFO - 'talos_options': ['--xperf_path', 17:09:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:09:55 INFO - 'tests': ['tp5n']}}} 17:09:55 INFO - Running post-action listener: _resource_record_post_action 17:09:55 INFO - [mozharness: 2017-08-13 00:09:55.574973Z] Finished populate-webroot step (success) 17:09:55 INFO - [mozharness: 2017-08-13 00:09:55.575064Z] Running create-virtualenv step. 17:09:55 INFO - Running pre-action listener: _resource_record_pre_action 17:09:55 INFO - Running main action method: create_virtualenv 17:09:55 INFO - Creating virtualenv /builds/slave/test/build/venv 17:09:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:09:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:09:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:09:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:09:55 INFO - Using real prefix '/usr' 17:09:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:09:56 INFO - Installing distribute.............................................................................................................................................................................................done. 17:09:58 INFO - Installing pip.................done. 17:09:58 INFO - Return code: 0 17:09:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:09:58 INFO - Reading from file tmpfile_stdout 17:09:58 INFO - Output received: 17:09:58 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:09:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:58 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 0x15db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19478a0>, '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 0x19487a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1948c30>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:09:58 INFO - Using env: {'DISPLAY': ':0', 17:09:58 INFO - 'HOME': '/home/cltbld', 17:09:58 INFO - 'LANG': 'en_US.UTF-8', 17:09:58 INFO - 'LANGUAGE': 'en_US:en', 17:09:58 INFO - 'LOGNAME': 'cltbld', 17:09:58 INFO - 'MAIL': '/var/mail/cltbld', 17:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:58 INFO - 'MOZ_NO_REMOTE': '1', 17:09:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:58 INFO - 'NO_EM_RESTART': '1', 17:09:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:58 INFO - 'PWD': '/builds/slave/test', 17:09:58 INFO - 'SHELL': '/bin/bash', 17:09:58 INFO - 'SHLVL': '1', 17:09:58 INFO - 'TERM': 'linux', 17:09:58 INFO - 'TMOUT': '86400', 17:09:58 INFO - 'USER': 'cltbld', 17:09:58 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 17:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:58 INFO - '_': '/tools/buildbot/bin/python'} 17:09:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:09:58 INFO - Cleaning up... 17:09:58 INFO - Return code: 0 17:09:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:09:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:09:58 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 0x15db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19478a0>, '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 0x19487a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1948c30>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:09:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:09:58 INFO - Using env: {'DISPLAY': ':0', 17:09:58 INFO - 'HOME': '/home/cltbld', 17:09:58 INFO - 'LANG': 'en_US.UTF-8', 17:09:58 INFO - 'LANGUAGE': 'en_US:en', 17:09:58 INFO - 'LOGNAME': 'cltbld', 17:09:58 INFO - 'MAIL': '/var/mail/cltbld', 17:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:09:58 INFO - 'MOZ_NO_REMOTE': '1', 17:09:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:09:58 INFO - 'NO_EM_RESTART': '1', 17:09:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:09:58 INFO - 'PWD': '/builds/slave/test', 17:09:58 INFO - 'SHELL': '/bin/bash', 17:09:58 INFO - 'SHLVL': '1', 17:09:58 INFO - 'TERM': 'linux', 17:09:58 INFO - 'TMOUT': '86400', 17:09:58 INFO - 'USER': 'cltbld', 17:09:58 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 17:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:09:58 INFO - '_': '/tools/buildbot/bin/python'} 17:09:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:09:59 INFO - Downloading/unpacking psutil>=3.1.1 17:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:09:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:10:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:10:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:10:00 INFO - Installing collected packages: psutil 17:10:00 INFO - Running setup.py install for psutil 17:10:00 INFO - building 'psutil._psutil_linux' extension 17:10:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 17:10:01 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 17:10:01 INFO - building 'psutil._psutil_posix' extension 17:10:01 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 17:10:01 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 17:10:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:10:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:10:01 INFO - Successfully installed psutil 17:10:01 INFO - Cleaning up... 17:10:01 INFO - Return code: 0 17:10:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19478a0>, '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 0x19487a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1948c30>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:10:01 INFO - Using env: {'DISPLAY': ':0', 17:10:01 INFO - 'HOME': '/home/cltbld', 17:10:01 INFO - 'LANG': 'en_US.UTF-8', 17:10:01 INFO - 'LANGUAGE': 'en_US:en', 17:10:01 INFO - 'LOGNAME': 'cltbld', 17:10:01 INFO - 'MAIL': '/var/mail/cltbld', 17:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:01 INFO - 'MOZ_NO_REMOTE': '1', 17:10:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:01 INFO - 'NO_EM_RESTART': '1', 17:10:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:01 INFO - 'PWD': '/builds/slave/test', 17:10:01 INFO - 'SHELL': '/bin/bash', 17:10:01 INFO - 'SHLVL': '1', 17:10:01 INFO - 'TERM': 'linux', 17:10:01 INFO - 'TMOUT': '86400', 17:10:01 INFO - 'USER': 'cltbld', 17:10:01 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 17:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:01 INFO - '_': '/tools/buildbot/bin/python'} 17:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:10:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:10:03 INFO - Installing collected packages: mozsystemmonitor 17:10:03 INFO - Running setup.py install for mozsystemmonitor 17:10:03 INFO - Successfully installed mozsystemmonitor 17:10:03 INFO - Cleaning up... 17:10:03 INFO - Return code: 0 17:10:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19478a0>, '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 0x19487a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1948c30>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:10:03 INFO - Using env: {'DISPLAY': ':0', 17:10:03 INFO - 'HOME': '/home/cltbld', 17:10:03 INFO - 'LANG': 'en_US.UTF-8', 17:10:03 INFO - 'LANGUAGE': 'en_US:en', 17:10:03 INFO - 'LOGNAME': 'cltbld', 17:10:03 INFO - 'MAIL': '/var/mail/cltbld', 17:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:03 INFO - 'MOZ_NO_REMOTE': '1', 17:10:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:03 INFO - 'NO_EM_RESTART': '1', 17:10:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:03 INFO - 'PWD': '/builds/slave/test', 17:10:03 INFO - 'SHELL': '/bin/bash', 17:10:03 INFO - 'SHLVL': '1', 17:10:03 INFO - 'TERM': 'linux', 17:10:03 INFO - 'TMOUT': '86400', 17:10:03 INFO - 'USER': 'cltbld', 17:10:03 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 17:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:03 INFO - '_': '/tools/buildbot/bin/python'} 17:10:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:03 INFO - Downloading/unpacking jsonschema==2.5.1 17:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:10:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:04 INFO - Downloading functools32-3.2.3-2.tar.gz 17:10:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:10:04 INFO - warning: no files found matching '*.txt' 17:10:04 INFO - no previously-included directories found matching 'build' 17:10:04 INFO - no previously-included directories found matching 'dist' 17:10:04 INFO - no previously-included directories found matching '.git*' 17:10:04 INFO - Installing collected packages: jsonschema, functools32 17:10:04 INFO - Running setup.py install for functools32 17:10:05 INFO - warning: no files found matching '*.txt' 17:10:05 INFO - no previously-included directories found matching 'build' 17:10:05 INFO - no previously-included directories found matching 'dist' 17:10:05 INFO - no previously-included directories found matching '.git*' 17:10:05 INFO - Successfully installed jsonschema functools32 17:10:05 INFO - Cleaning up... 17:10:05 INFO - Return code: 0 17:10:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x15db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19478a0>, '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 0x19487a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1948c30>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:10:05 INFO - Using env: {'DISPLAY': ':0', 17:10:05 INFO - 'HOME': '/home/cltbld', 17:10:05 INFO - 'LANG': 'en_US.UTF-8', 17:10:05 INFO - 'LANGUAGE': 'en_US:en', 17:10:05 INFO - 'LOGNAME': 'cltbld', 17:10:05 INFO - 'MAIL': '/var/mail/cltbld', 17:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:05 INFO - 'MOZ_NO_REMOTE': '1', 17:10:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:05 INFO - 'NO_EM_RESTART': '1', 17:10:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:05 INFO - 'PWD': '/builds/slave/test', 17:10:05 INFO - 'SHELL': '/bin/bash', 17:10:05 INFO - 'SHLVL': '1', 17:10:05 INFO - 'TERM': 'linux', 17:10:05 INFO - 'TMOUT': '86400', 17:10:05 INFO - 'USER': 'cltbld', 17:10:05 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 17:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:05 INFO - '_': '/tools/buildbot/bin/python'} 17:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:10:05 INFO - Cleaning up... 17:10:05 INFO - Return code: 0 17:10:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x15db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19478a0>, '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 0x19487a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1948c30>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:10:05 INFO - Using env: {'DISPLAY': ':0', 17:10:05 INFO - 'HOME': '/home/cltbld', 17:10:05 INFO - 'LANG': 'en_US.UTF-8', 17:10:05 INFO - 'LANGUAGE': 'en_US:en', 17:10:05 INFO - 'LOGNAME': 'cltbld', 17:10:05 INFO - 'MAIL': '/var/mail/cltbld', 17:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:05 INFO - 'MOZ_NO_REMOTE': '1', 17:10:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:05 INFO - 'NO_EM_RESTART': '1', 17:10:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:05 INFO - 'PWD': '/builds/slave/test', 17:10:05 INFO - 'SHELL': '/bin/bash', 17:10:05 INFO - 'SHLVL': '1', 17:10:05 INFO - 'TERM': 'linux', 17:10:05 INFO - 'TMOUT': '86400', 17:10:05 INFO - 'USER': 'cltbld', 17:10:05 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 17:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:05 INFO - '_': '/tools/buildbot/bin/python'} 17:10:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:05 INFO - Downloading/unpacking blobuploader==1.2.4 17:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:06 INFO - Downloading blobuploader-1.2.4.tar.gz 17:10:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:10:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:10:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:07 INFO - Downloading docopt-0.6.1.tar.gz 17:10:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:10:07 INFO - Installing collected packages: blobuploader, requests, docopt 17:10:07 INFO - Running setup.py install for blobuploader 17:10:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:10:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:10:07 INFO - Running setup.py install for requests 17:10:07 INFO - Running setup.py install for docopt 17:10:07 INFO - Successfully installed blobuploader requests docopt 17:10:07 INFO - Cleaning up... 17:10:07 INFO - Return code: 0 17:10:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:10: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')]} 17:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10: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')]} 17:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:07 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 0x15db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19478a0>, '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 0x19487a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1948c30>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:10:07 INFO - Using env: {'DISPLAY': ':0', 17:10:07 INFO - 'HOME': '/home/cltbld', 17:10:07 INFO - 'LANG': 'en_US.UTF-8', 17:10:07 INFO - 'LANGUAGE': 'en_US:en', 17:10:07 INFO - 'LOGNAME': 'cltbld', 17:10:07 INFO - 'MAIL': '/var/mail/cltbld', 17:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:07 INFO - 'MOZ_NO_REMOTE': '1', 17:10:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:07 INFO - 'NO_EM_RESTART': '1', 17:10:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:07 INFO - 'PWD': '/builds/slave/test', 17:10:07 INFO - 'SHELL': '/bin/bash', 17:10:07 INFO - 'SHLVL': '1', 17:10:07 INFO - 'TERM': 'linux', 17:10:07 INFO - 'TMOUT': '86400', 17:10:07 INFO - 'USER': 'cltbld', 17:10:07 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 17:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:07 INFO - '_': '/tools/buildbot/bin/python'} 17:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:10:08 INFO - Running setup.py (path:/tmp/pip-sB7l8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:10:08 INFO - Running setup.py (path:/tmp/pip-M4990z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:10:08 INFO - Running setup.py (path:/tmp/pip-SCOld0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:10:08 INFO - Running setup.py (path:/tmp/pip-2QY2A7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:10:08 INFO - Running setup.py (path:/tmp/pip-dEBDG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:10:08 INFO - Running setup.py (path:/tmp/pip-KPCz01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:10:08 INFO - Running setup.py (path:/tmp/pip-EUkL3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:10:08 INFO - Running setup.py (path:/tmp/pip-v35R79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:10:08 INFO - Running setup.py (path:/tmp/pip-j7Pn5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:10:08 INFO - Running setup.py (path:/tmp/pip-KPVitQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:10:08 INFO - Running setup.py (path:/tmp/pip-RqMjp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:10:08 INFO - Running setup.py (path:/tmp/pip-yGSvjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:10:08 INFO - Running setup.py (path:/tmp/pip-emCPO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:10:09 INFO - Running setup.py (path:/tmp/pip-hWfkml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:10:09 INFO - Running setup.py (path:/tmp/pip-h03FT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:10:09 INFO - Running setup.py (path:/tmp/pip-WIVAXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:10:09 INFO - Running setup.py (path:/tmp/pip-VhW5lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:10:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:10:09 INFO - Running setup.py install for manifestparser 17:10:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:10:09 INFO - Running setup.py install for mozcrash 17:10:09 INFO - Running setup.py install for mozdebug 17:10:09 INFO - Running setup.py install for mozdevice 17:10:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:10:09 INFO - Running setup.py install for mozfile 17:10:09 INFO - Running setup.py install for mozhttpd 17:10:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:10:09 INFO - Running setup.py install for mozinfo 17:10:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:10:10 INFO - Running setup.py install for mozInstall 17:10:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:10:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:10:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:10:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:10:10 INFO - Running setup.py install for mozleak 17:10:10 INFO - Running setup.py install for mozlog 17:10:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:10:10 INFO - Running setup.py install for moznetwork 17:10:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:10:10 INFO - Running setup.py install for mozprocess 17:10:10 INFO - Running setup.py install for mozprofile 17:10:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:10:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:10:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:10:10 INFO - Running setup.py install for mozrunner 17:10:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:10:10 INFO - Running setup.py install for mozscreenshot 17:10:10 INFO - Running setup.py install for moztest 17:10:10 INFO - Running setup.py install for mozversion 17:10:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:10:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:10:11 INFO - Cleaning up... 17:10:11 INFO - Return code: 0 17:10:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:11 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 0x15db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19478a0>, '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 0x19487a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1948c30>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:10:11 INFO - Using env: {'DISPLAY': ':0', 17:10:11 INFO - 'HOME': '/home/cltbld', 17:10:11 INFO - 'LANG': 'en_US.UTF-8', 17:10:11 INFO - 'LANGUAGE': 'en_US:en', 17:10:11 INFO - 'LOGNAME': 'cltbld', 17:10:11 INFO - 'MAIL': '/var/mail/cltbld', 17:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:11 INFO - 'MOZ_NO_REMOTE': '1', 17:10:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:11 INFO - 'NO_EM_RESTART': '1', 17:10:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:11 INFO - 'PWD': '/builds/slave/test', 17:10:11 INFO - 'SHELL': '/bin/bash', 17:10:11 INFO - 'SHLVL': '1', 17:10:11 INFO - 'TERM': 'linux', 17:10:11 INFO - 'TMOUT': '86400', 17:10:11 INFO - 'USER': 'cltbld', 17:10:11 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 17:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:11 INFO - '_': '/tools/buildbot/bin/python'} 17:10:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:10:11 INFO - Running setup.py (path:/tmp/pip-ruIVFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:10:11 INFO - Running setup.py (path:/tmp/pip-e8twhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:10:11 INFO - Running setup.py (path:/tmp/pip-zVpSAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:10:11 INFO - Running setup.py (path:/tmp/pip-dC3laG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:10:11 INFO - Running setup.py (path:/tmp/pip-YPDITv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:10:11 INFO - Running setup.py (path:/tmp/pip-0_ECFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:10:11 INFO - Running setup.py (path:/tmp/pip-jlVmcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:10:11 INFO - Running setup.py (path:/tmp/pip-Ya0LF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:10:11 INFO - Running setup.py (path:/tmp/pip-xTsCul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:10:11 INFO - Running setup.py (path:/tmp/pip-mjeX7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:10:12 INFO - Running setup.py (path:/tmp/pip-ZbqOAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:10:12 INFO - Running setup.py (path:/tmp/pip-XsAJQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:10:12 INFO - Running setup.py (path:/tmp/pip-Jp2Dya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:10:12 INFO - Running setup.py (path:/tmp/pip-o7n3kz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:10:12 INFO - Running setup.py (path:/tmp/pip-T8yRuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:10:12 INFO - Running setup.py (path:/tmp/pip-I5v7Hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:10:12 INFO - Running setup.py (path:/tmp/pip-lGGAEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:10:12 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:10:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:10:13 INFO - Downloading blessings-1.6.tar.gz 17:10:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:10:13 INFO - Installing collected packages: blessings 17:10:13 INFO - Running setup.py install for blessings 17:10:13 INFO - Successfully installed blessings 17:10:13 INFO - Cleaning up... 17:10:13 INFO - Return code: 0 17:10:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:10:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:10:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:10:14 INFO - Reading from file tmpfile_stdout 17:10:14 INFO - Current package versions: 17:10:14 INFO - argparse == 1.2.1 17:10:14 INFO - blessings == 1.6 17:10:14 INFO - blobuploader == 1.2.4 17:10:14 INFO - docopt == 0.6.1 17:10:14 INFO - functools32 == 3.2.3-2 17:10:14 INFO - jsonschema == 2.5.1 17:10:14 INFO - manifestparser == 1.1 17:10:14 INFO - mozInstall == 1.13 17:10:14 INFO - mozcrash == 1.0 17:10:14 INFO - mozdebug == 0.1 17:10:14 INFO - mozdevice == 0.51 17:10:14 INFO - mozfile == 1.2 17:10:14 INFO - mozhttpd == 0.7 17:10:14 INFO - mozinfo == 0.10 17:10:14 INFO - mozleak == 0.1 17:10:14 INFO - mozlog == 3.5 17:10:14 INFO - moznetwork == 0.27 17:10:14 INFO - mozprocess == 0.25 17:10:14 INFO - mozprofile == 0.28 17:10:14 INFO - mozrunner == 6.13 17:10:14 INFO - mozscreenshot == 0.1 17:10:14 INFO - mozsystemmonitor == 0.3 17:10:14 INFO - moztest == 0.8 17:10:14 INFO - mozversion == 1.4 17:10:14 INFO - psutil == 3.1.1 17:10:14 INFO - requests == 1.2.3 17:10:14 INFO - wsgiref == 0.1.2 17:10:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:10:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10:14 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 0x15db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19478a0>, '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 0x19487a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1948c30>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:10:14 INFO - Using env: {'DISPLAY': ':0', 17:10:14 INFO - 'HOME': '/home/cltbld', 17:10:14 INFO - 'LANG': 'en_US.UTF-8', 17:10:14 INFO - 'LANGUAGE': 'en_US:en', 17:10:14 INFO - 'LOGNAME': 'cltbld', 17:10:14 INFO - 'MAIL': '/var/mail/cltbld', 17:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:14 INFO - 'MOZ_NO_REMOTE': '1', 17:10:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:14 INFO - 'NO_EM_RESTART': '1', 17:10:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:14 INFO - 'PWD': '/builds/slave/test', 17:10:14 INFO - 'SHELL': '/bin/bash', 17:10:14 INFO - 'SHLVL': '1', 17:10:14 INFO - 'TERM': 'linux', 17:10:14 INFO - 'TMOUT': '86400', 17:10:14 INFO - 'USER': 'cltbld', 17:10:14 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 17:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:14 INFO - '_': '/tools/buildbot/bin/python'} 17:10:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:10:14 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:10: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 17:10: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 17:10: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 17:10: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 17:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 17:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:10:15 INFO - Installing collected packages: simplejson 17:10:15 INFO - Running setup.py install for simplejson 17:10:15 INFO - building 'simplejson._speedups' extension 17:10:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 17:10:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 17:10:16 INFO - Successfully installed simplejson 17:10:16 INFO - Cleaning up... 17:10:16 INFO - Return code: 0 17:10:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:10: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')]} 17:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:10: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')]} 17:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:10: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15db1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194a700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19478a0>, '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 0x19487a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1948c30>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:10: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'] in /builds/slave/test/build 17:10: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 17:10:16 INFO - Using env: {'DISPLAY': ':0', 17:10:16 INFO - 'HOME': '/home/cltbld', 17:10:16 INFO - 'LANG': 'en_US.UTF-8', 17:10:16 INFO - 'LANGUAGE': 'en_US:en', 17:10:16 INFO - 'LOGNAME': 'cltbld', 17:10:16 INFO - 'MAIL': '/var/mail/cltbld', 17:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:16 INFO - 'MOZ_NO_REMOTE': '1', 17:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:16 INFO - 'NO_EM_RESTART': '1', 17:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:16 INFO - 'PWD': '/builds/slave/test', 17:10:16 INFO - 'SHELL': '/bin/bash', 17:10:16 INFO - 'SHLVL': '1', 17:10:16 INFO - 'TERM': 'linux', 17:10:16 INFO - 'TMOUT': '86400', 17:10:16 INFO - 'USER': 'cltbld', 17:10:16 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 17:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:16 INFO - '_': '/tools/buildbot/bin/python'} 17:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:10:16 INFO - Cleaning up... 17:10:16 INFO - Return code: 0 17:10:16 INFO - Running post-action listener: _resource_record_post_action 17:10:16 INFO - Running post-action listener: _start_resource_monitoring 17:10:16 INFO - Starting resource monitoring. 17:10:16 INFO - [mozharness: 2017-08-13 00:10:16.847118Z] Finished create-virtualenv step (success) 17:10:16 INFO - [mozharness: 2017-08-13 00:10:16.848124Z] Running install step. 17:10:16 INFO - Running pre-action listener: _resource_record_pre_action 17:10:16 INFO - Running main action method: install 17:10:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:10:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:10:17 INFO - Reading from file tmpfile_stdout 17:10:17 INFO - Detecting whether we're running mozinstall >=1.0... 17:10:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:10:17 INFO - Reading from file tmpfile_stdout 17:10:17 INFO - Output received: 17:10:17 INFO - Usage: mozinstall [options] installer 17:10:17 INFO - Options: 17:10:17 INFO - -h, --help show this help message and exit 17:10:17 INFO - -d DEST, --destination=DEST 17:10:17 INFO - Directory to install application into. [default: 17:10:17 INFO - "/builds/slave/test"] 17:10:17 INFO - --app=APP Application being installed. [default: firefox] 17:10:17 INFO - mkdir: /builds/slave/test/build/application 17:10:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:10:35 INFO - Reading from file tmpfile_stdout 17:10:35 INFO - Output received: 17:10:35 INFO - /builds/slave/test/build/application/firefox/firefox 17:10:35 INFO - Running post-action listener: _resource_record_post_action 17:10:35 INFO - [mozharness: 2017-08-13 00:10:35.096904Z] Finished install step (success) 17:10:35 INFO - [mozharness: 2017-08-13 00:10:35.097157Z] Running setup-mitmproxy step. 17:10:35 INFO - Running pre-action listener: _resource_record_pre_action 17:10:35 INFO - Running main action method: setup_mitmproxy 17:10:35 INFO - Skipping: mitmproxy is not required 17:10:35 INFO - Running post-action listener: _resource_record_post_action 17:10:35 INFO - [mozharness: 2017-08-13 00:10:35.098406Z] Finished setup-mitmproxy step (success) 17:10:35 INFO - [mozharness: 2017-08-13 00:10:35.098629Z] Running run-tests step. 17:10:35 INFO - Running pre-action listener: _resource_record_pre_action 17:10:35 INFO - Running pre-action listener: _set_gcov_prefix 17:10:35 INFO - Running main action method: run_tests 17:10:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:10:35 INFO - Python 2.7.3 17:10:35 INFO - Return code: 0 17:10:35 INFO - grabbing minidump binary from tooltool 17:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:10:35 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 0x19478a0>, '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 0x19487a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1948c30>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:10:35 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:10:35 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 17:10:35 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:10:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:10:35 INFO - Return code: 0 17:10:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:10:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:10:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:10:35 INFO - ENV: RUST_BACKTRACE is now full 17:10:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:10:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:10:35 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/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:10:35 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/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:10:35 INFO - Using env: {'DISPLAY': ':0', 17:10:35 INFO - 'HOME': '/home/cltbld', 17:10:35 INFO - 'LANG': 'en_US.UTF-8', 17:10:35 INFO - 'LANGUAGE': 'en_US:en', 17:10:35 INFO - 'LOGNAME': 'cltbld', 17:10:35 INFO - 'MAIL': '/var/mail/cltbld', 17:10:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:10:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:10:35 INFO - 'MOZ_NO_REMOTE': '1', 17:10:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:10:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:10:35 INFO - 'NO_EM_RESTART': '1', 17:10:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:10:35 INFO - 'PWD': '/builds/slave/test', 17:10:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:10:35 INFO - 'RUST_BACKTRACE': 'full', 17:10:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:10:35 INFO - 'SHELL': '/bin/bash', 17:10:35 INFO - 'SHLVL': '1', 17:10:35 INFO - 'STYLO_FORCE_ENABLED': '1', 17:10:35 INFO - 'TERM': 'linux', 17:10:35 INFO - 'TMOUT': '86400', 17:10:35 INFO - 'USER': 'cltbld', 17:10:35 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358', 17:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:10:35 INFO - '_': '/tools/buildbot/bin/python'} 17:10:35 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/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:10:35 INFO - mozversion application_buildid: 20170812232023 17:10:35 INFO - mozversion application_changeset: 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 17:10:35 INFO - mozversion application_display_name: Nightly 17:10:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:10:35 INFO - mozversion application_name: Firefox 17:10:35 INFO - mozversion application_remotingname: firefox 17:10:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:10:35 INFO - mozversion application_vendor: Mozilla 17:10:35 INFO - mozversion application_version: 57.0a1 17:10:35 INFO - mozversion platform_buildid: 20170812232023 17:10:35 INFO - mozversion platform_changeset: 59594b3879cc7b038ba8a1ed6e9e66c6423bf6e2 17:10:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:10:35 INFO - mozversion platform_version: 57.0a1 17:10:35 INFO - using testdate: 1502583035 17:10:35 INFO - actual date: 1502583035 17:10:35 INFO - starting webserver on 'localhost:37330' 17:10:35 INFO - SUITE-START | Running 2 tests 17:10:35 INFO - TEST-START | dromaeo_css 17:10:35 INFO - Initialising browser for dromaeo_css test... 17:10:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37330/getInfo.html -profile /tmp/tmpFHYRGC/profile 17:10:35 INFO - TEST-INFO | started process 3352 (/builds/slave/test/build/application/firefox/firefox http://localhost:37330/getInfo.html) 17:10:45 INFO - TEST-INFO | 3352: exit 0 17:10:45 INFO - Browser initialized. 17:10:45 INFO - Running cycle 1/1 for dromaeo_css test... 17:10:45 INFO - TEST-INFO | started process 3583 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFHYRGC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:10:46 INFO - PID 3583 | 17:10:46 INFO - PID 3583 | (/builds/slave/test/build/application/firefox/firefox:3637): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:10:46 INFO - PID 3583 | 17:10:56 INFO - PID 3583 | RSS: Main: 171343872 17:10:56 INFO - PID 3583 | 17:13:56 INFO - PID 3583 | Cycle 1(1): loaded http://localhost:37330/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37330/tests/dromaeo/cssquery-ext.html) 17:13:57 INFO - PID 3583 | RSS: Main: 176054272 17:13:57 INFO - PID 3583 | 17:16:59 INFO - PID 3583 | Cycle 1(1): loaded http://localhost:37330/tests/dromaeo/cssquery-ext.html (next: http://localhost:37330/tests/dromaeo/cssquery-jquery.html) 17:17:00 INFO - PID 3583 | RSS: Main: 173146112 17:17:00 INFO - PID 3583 | 17:20:01 INFO - PID 3583 | Cycle 1(1): loaded http://localhost:37330/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37330/tests/dromaeo/cssquery-mootools.html) 17:20:02 INFO - PID 3583 | RSS: Main: 175648768 17:20:02 INFO - PID 3583 | 17:23:04 INFO - PID 3583 | Cycle 1(1): loaded http://localhost:37330/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37330/tests/dromaeo/cssquery-prototype.html) 17:23:04 INFO - PID 3583 | RSS: Main: 173522944 17:23:04 INFO - PID 3583 | 17:26:08 INFO - PID 3583 | Cycle 1(1): loaded http://localhost:37330/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37330/tests/dromaeo/cssquery-yui.html) 17:26:08 INFO - PID 3583 | RSS: Main: 177422336 17:26:08 INFO - PID 3583 | 17:29:10 INFO - PID 3583 | Cycle 1(1): loaded http://localhost:37330/tests/dromaeo/cssquery-yui.html (next: http://localhost:37330/tests/dromaeo/cssquery-dojo.html) 17:29:11 INFO - PID 3583 | RSS: Main: 176271360 17:29:11 INFO - PID 3583 | 17:29:11 INFO - PID 3583 | __start_tp_report 17:29:11 INFO - PID 3583 | _x_x_mozilla_page_load 17:29:11 INFO - PID 3583 | _x_x_mozilla_page_load_details 17:29:11 INFO - PID 3583 | |i|pagename|runs| 17:29:11 INFO - PID 3583 | |0;dojo.html;3129.061281615515;3141.418837515061;3170.6512283648794;3188.7608429367797;3211.5985501812274;6151.723172457239;6171;6182.598131121474;6214.844628884274;6226.377362263771;6180.629162250269;6200.875982480347;6210;6231.252249730027;6235;5723.599348045628;5724.284464441945;5730.111832665943;5738.081906894898;5801.6519008859395;5728.369879313273;5797.449242321973;5823.184754134403;5823.796167134151;5824.796132135376;5672;5738.0532212184935;5744.856378590527;5760.539156867461;5788.6237394569425;5605.859853503654;5662.235598194274;5696.316442026942;5699.772009119631;5730.942690573124;5616.269884914976;5684.772609095631;5714.628549144292;5736.942630573682;5756.395578464243;6174.382561743834;6217.937820621826;6235.6258624482225;6245.594036387665;6274.686265686743;6699.430548403391;6700.865982680368;6764.3573860483175;6782.626955517445;6802.387785099315;6612.86774264512;6738.663066846638;6757.526973111884;6819.420349270318;6831.04365388846;6333.303336632966;6347.174867267272;6358.39595238447;6374.713137908805;6374.968125159345;6895.620740859301;6902.516823822284;6917.515773895779;7004.544704594185;7008.088948436671;6622.03980422836;6623.30455302196;6697.263301036882;6713.194416669933;6714.764983225564;6524.445422139076;6624.304448032887;6681.866362672817;6785.185777706707;6792.592444453348;6505.251896032017;6523.347665233438;6528.771492997722;6574.441172500391;6695.497837662195;6758.831029224309;6811.3869751721595;6817.5909445433435;6853.862922741617;6867.450603951672;5397.460253974571;5405.324334458192;5472.91790623141;5473.452654734574;5475;4554.567316104964;4609.792559334838;4639;4684.344191813149;4684.367610372624;5733.426657334233;5772.624779389326;5772.740226689809;5775.595708300379;5797.420257974169;6725.798226053226;6742.797716068526;6745.460363171032;6755.864882702417;6777.661116944231;5711.628744131618;5794.681292528951;5826.41735826414;5837.824865254049;5856.033754430557;5839.445252700986;5859;5949.672767997715;5950.375210602824;5966;6399.072134540373;6528.575642583217;6597.802065937934;6605.669716514155;6606.636634985122;6672.266050734318;6680;6689.130413046273;6689.565178263398;6693.330666933268;6441.1304473895425;6444.967775161094;6451.70967306463;6452.612843229421;6460.12788273577;6542.54202205841;6545.2800191979795;6612.603243805387;6644.302348253364;6657.234418041987;6770.1537307836525;6817;6826.453883689193;6835.93164068353;6867.553609015398;6392.52056095804;6444.677766111582;6452.709628066655;6453.064305675557;6496.415322620798;6469.935300646934;6483.448906842971;6491.642959637265;6493.123428337114;6494.220693516808;6167.753289868355;6246.718897649707;6252;6330.778422755181;6438.871222575616;6431.5819471734185;6599.934000659932;6600.207975043026;6658.334166583303;6675.966620166868;6382.744690212339;6405.807825765234;6406.423421892052;6409.4231519163495;6417.935820641734 17:29:11 INFO - PID 3583 | |1;ext.html;792.4690457393546;815.4821688227973;851.2211326636128;871.577285016767;878.5343767803066;41656.58343416569;42395.57604423955;42412;42462.57537424625;42658.7867060665;37733.811330943376;38077.61922380776;38098.619013809854;38237.23525529488;38332.808335958354;6730.192376914777;6856.52004359695;6860.55406398585;6873.934540146285;6876.243613202569;6781.016752570874;6879.449644028491;6888.380045795878;6894.172699276094;6897.689603967833;7481.738139165131;7600.2779735925;7659.042619672541;7676.577788221645;7682.385409167253;7567.394608431312;7574.962125189394;7575.772726818204;7623.123340815766;7648.541087534738;7439.814004649873;7488.213737557532;7619.504732192445;7621.6570254338685;7627.389808815337;38724.225515489816;39008.414873776914;39072;39084.80457597694;39158.41262381067;3560.2701446203464;3585.4980302757635;3600.225951420457;3604.4052731299303;3619.040954147153;31847.681523184703;31877.681223187705;31891.52162717561;31905.36189276225;31980.840095799373;5107.9744601277125;5129.17933130701;5144.228365745168;5163.7676304566385;5170.25031370453;183072;183392;183436;185287;200184;51016;51189.74405128025;51228.48771512312;51233.743831280604;51351.743241283555;52909;53058.46941530535;53169;53185;53259.20111198335;14642;14751.704965900837;14779.926100369645;14783.408663653549;14862.628434289012;11942.880571194177;11995.40022998864;11996.520139194508;12000.1599888007;12002.699932501757;14683.412663493555;14756.335964881606;14831.11013339203;14836.406543738345;14941.775873361908;5834.620749651259;5900.940990590125;5950.345461999176;6008.279006519158;6088.908666370008;981.7790997025612;982.6069572171189;1007.2948935745007;1009.6516701737888;1012.1295685710284;22304.55390892173;22310.888445557994;23401.88299058528;30507.389852202832;30771.69228307733;1102.3716481605409;1128.6388355726253;1128.8250321200228;1135.1883403366467;1135.2961164078324;12678.366081695878;12794.168379055332;12884;12923.030847559186;12926.353682316034;233158;233963;234782;235721;235908;54283;54337.45662543324;54646.72676636671;54670;54796.17805732917;1477.0842077911548;1520.7338715724704;1537.5233677559768;1539.3688587678998;1558.0729465967731;23734.643980339963;28400;28869.989550365637;28994;29412.970546030785;52195;52612.47387526229;52636;52648.21027684664;52649.736751316;207673;209523;210896;211669;212048;36017.63982360143;36523.08692282714;37870;37882.62117378901;37986.810065949496;46430.53569464262;46461.76769116133;46683.76658116688;46829.76585117052;46842.76578617085;34431.311373772885;34550.30899382049;35051.649483504836;35052.82473587616;35625.28749424945;33402.66597333996;34019.82990085034;34122.658773411946;34172.31655366928;34176.14559636029;37957.240855182186;37972.62027379691;37993;38243.42634860424;38251.80874095612;33543.664563354054;33576.1605959853;33648.831755842046;33697.326053478304;33948.83025584856;24489.877550612135;24691.135810246557;24749;24992.250232493054;25272.873635631706 17:29:11 INFO - PID 3583 | |2;jquery.html;570.9229254050701;572.3646752105166;575.7840809696365;576.723172877019;586.1295975476417;22094.558108837813;22835.20076797308;22839.885800570995;22849;22987.42531436715;25370.492590148187;26042.609360859602;26081.60877586838;26081.73918260817;26087;6381.90427143592;6505;6525.836854078662;6526.869462610745;6529.249136349309;6060.606060606068;6464.256610489783;6505.1543299371015;6693.732250709966;6708.027336036272;6655.700493477805;6662.733490660367;6671.266160722316;6672.132622759059;6672.266050734415;6641.601503909781;6655.900161497581;6659.300773418794;6662;6664.0670306156935;6169.845753856144;6176.96911515444;6192.411720886508;6207.286162091372;6215.81352559424;28211.858940705166;28640.99269576706;29251.70748292511;29593.112206633836;29817.552736708967;1909.446260584429;1937.3800383877165;1938.738270333501;1943.24213556713;1943.2906988949073;19308.420747377462;19783.604327913505;19787.30744423938;19811.10850011753;19818.405447836587;2335.2643917166038;2372.79831214347;2377.738448770634;2384.8688322142275;2482.6027835546224;136947.31526342305;143232.28383858013;143249;144682.27658861745;145291;14489.971404003449;14823.77764333536;15030.924845375703;15331.61670958235;15404;23725;23779.643305350433;23785.405364866016;23822.523549529258;23899.283021509036;10038.698839034842;10072.496375181212;10156.187504999582;10238.488075596191;10273.691789246335;9342.57958391874;9397.060293970548;9437;9474.05259474061;9605.511145772416;7719.884201736979;9264.147170565913;9296.907030929604;9314.767130821649;9413.246940244764;2724.8365098094177;2796.566532187514;2796.566532187514;2834.489791837489;2834.6173266609158;950.9952450237705;969.8981606931312;970.1801977329145;973.6981535723949;980.6420656460341;19319;19866.90066549687;20013.299534516515;20046.298379556647;23746.406339841644;960.4429430930044;1035.233926894093;1035.321864178964;1038.2939601071344;1047.1047254597363;11017.669469915916;11089.390083545482;11261.774764504664;11269.66191014271;11398.373089479992;457824;463832;470083;487231;493261;127199.72800272064;129009;130747;131968.34015829858;132710;1221.4991853340089;1233.3956361383036;1257.063487701668;1264.1151194164306;1266.5250531050856;17672.823271767633;17714.645707086045;17966.191521381308;18190.181441834884;18204;52691;54663;55498.72250638721;56529.15206271827;57016.71491642515;73360.26639733535;73559.26440735524;76405.61797190979;81980.18019819725;82826.17173828426;12813.74372512526;13345.86654133485;14857.702845942804;15319.157446340558;15352.385904564138;22933.54132917366;23390.532189356458;23790.286291411285;23888.761112388653;24537.509249814546;18725.438236852915;18969;18990.335338263096;19100.904495477433;19117.330893418664;16967.151642417582;17326.22032008588;17718.557036074202;18050.81949180544;18250.178741956377;18346.357877474224;18746.625067498848;18875.90562047181;19089;19358.8064119357;14812.629684257548;15159.772603410736;15162.696746065238;15169.924150379178;15191.696166076837;18531.81468185355;18667.346642867433;18755.40579050796;18872.811271887105;18881.527961801065 17:29:11 INFO - PID 3583 | |3;mootools.html;1368.849426563078;1415.370160278675;1427.3220220395308;1431.6277767780373;1447.2763618190904;13902;14120.505782297621;14470.276486175702;14478.927605361972;14482.420703171874;14713.779293310583;14903.254837258148;14903.478378256763;14918.104913705158;14930.402783888632;2719.809613327068;2748.8625568721486;2749.134022782827;2749.285185851683;2758.9310267243377;2715.945681086377;2812.521871281877;2818.7885908556837;2819.3092692290415;2819.6052552642645;2724.5368287391093;2754.6969833318317;2759.7930155238337;2762.047093752652;2775.4726601945567;2571.537123317802;2624.8950041998296;2695.7169497202804;2716.5110280149665;2736.904208352708;2572.9099481518056;2577.5102730481335;2597.8701064946677;2599.1552745357835;2610.673665791776;14054.789178162337;14312.355943982442;14346.64133396663;14349;14351.928240358835;7310.926890731131;7597.81005474862;7597.810054748676;7598;7606.771796846104;4993.425756038066;5159.664621799571;5201.479852014806;5256.8685782855355;5336.946630533723;1879.2107314927764;1893.6591413545557;1914.9234030638759;1953.8241558259824;1960.607878424321;24006;24252.757472425226;24272.150474733477;24275.514489710105;24285.7571424287;6354.841128971812;6504.772332968418;6542.901856472156;6655.168103987002;6728.6635668216395;16384.590385240463;16415.179241037902;16422.83577164237;16428.342866285213;16469.58826029359;7036.824079398056;7147.571145731273;7154.821129471805;7181.425485961111;7201;5422.864428389322;6135.662538560333;6209.627422354673;6652.667366631649;6680.632565208863;4875.146849301358;4893.486183950705;4975.228839529878;5144;5159.638825282194;1490.8967364150667;1509.6150481627335;1520.8022957015517;1524.5959820647538;1537.2851623994895;894.6019021535354;919.4437365393841;929.0709290709291;935.335911502827;937.9869740680185;7360.852782944418;7366.77899663011;7384.335409813035;7419.629018549051;7467.663955122032;718.9784306470814;733.886247631618;734.5519233267703;737.5500944423898;740.9703611855465;4628.861134165981;4633.698809577367;4811.711297322172;4907.950920490821;5399.055165346049;19115.426537203904;19504.90247548753;19621.21515139407;20046.298379556938;20257.898710506648;19176.137073831713;19818.306359277645;20057.498562536053;20175.192992280437;20181.899090504452;10023.599056037678;10594.470276486145;10616.575336986589;10625.73435664099;10675.87903270161;7918.259262034979;8749.868751968854;8753.240137246796;8759.444868932793;9033.187013168847;17309.826901731325;17353.913230433765;17507;17512.299508019536;17580;18073.81926180774;18079.728804068214;18087.728684069487;18237.81762182361;18340.991245481626;11797.351145686725;11974.401279936143;12010.219335743148;12037.398130093285;12044.91595756386;13244.668883277609;13403;13517.59447216585;13568.118072325267;13575.32123393846;10605.628802991858;10725.141988640735;10744.892551074388;10906.72733181645;10984.286021408583;10811.027007569357;10888.67333979982;10919.672409827717;11009.504572294427;11073.11415086775;9716.9028309716;9739;9776.462294573867;9867.11195992364;9873.703788886345;7383.372413344711;7416.703331866881;7417.072865891763;7455.589942553212;7455.6644950978425;7583.544987300779;7588.27911348421;7605.429592780453;7623.161452240138;7707.614619269127 17:29:11 INFO - PID 3583 | |4;prototype.html;505.09083649431017;510.9907931834625;520.7344254430241;523.6125267302195;529.2986792499936;6243.625382477049;6254.8123556293385;6257;6268.373162683735;6330.936690633092;6197.721102550385;6229.968850155754;6293.716782744787;6294.118823364733;6324.545354568452;5125.923111153335;5133.6919784812935;5133.717645529495;5144.099782538041;5163.302954101205;4988.176950803131;5012.598992080625;5072.568831649296;5112.284280200774;5159;5095.515925987044;5117.13008788505;5137.860256992895;5161.406438259592;5184.455632158626;5100.622831835402;5126.743662816844;5138.229265610151;5153.458886816868;5154.407243167008;4643.628509719214;4734.621230301567;4743.596794272491;4776.522347765195;4825.372701548811;6102.938970610326;6120.632762034292;6139.478144357735;6142.26292844857;6145.293291271471;5819.53443724501;5911.763529458816;5961.374055724174;5967.134765459027;6001.5198784097165;6650.268470468293;6670.933290667128;6701.195856497252;6725.966370168167;6761.425278851303;5240.34495688039;5252.185911183735;5264.973675131638;5283.973580132075;5290;6717.932820671828;6797.490188235902;6926.653667316614;6955.89566156508;7073.469489788286;5385.973070134624;5394.217838413487;5402.702851343214;5407.729613519308;5410.756515956791;5460.672359658433;5517.944820551743;5518;5526.806561770319;5544.306961629796;5924.755801281693;5935.139404786368;5940.524758019348;5946.762129514771;5998.730057147439;6645.966770166215;6654.833629159213;6667.43326817226;6697;6822.897656535156;1905.1522072677803;1909.6180763847285;1934.6420912131018;1944.7763507196853;1947.5423275530238;1345.8586848380976;1406.8030475733406;1408.204364534035;1427.864352886474;1429.7426463236718;5769.567282453832;5782.306123265151;5841.269841269841;5851.21008663825;5887.4701276884425;6658.633775142317;6678.465722742266;6709.672261391375;6758.594484330956;6844.247132815386;5730.598858079894;5760.135979603009;5774.971125144431;5793.536517078624;5839.416058394127;3623.7644553103964;3640.43573246142;3648.4709717090873;3650.762700424464;3660.7803531788177;6625.668716564152;6640.402363787282;6641.568298060611;6648.40164385198;6652.935530315126;7024.754133605299;7035.261297563682;7035.6482175891;7046.956382649077;7052.7884163474155;6600.2409722882485;6637.767678131242;6646.867062658623;6652.201735791736;6657.23441804189;6855.320446490442;6865.588064715933;6874.346937040973;6883.483738719516;6906;6719.966400167968;6721.630310332783;6743.629100399329;6776.713294052282;6790.7623233186605;6599.568224523317;6626.237982632059;6628.304028077176;6628.90056649141;6635;5440.7551660174595;5455.9454405455435;5467.687754938706;5488.69812160335;5489.451054894638;6519.217693876765;6710.798676039727;6734.518405950683;6795.422389096784;6817.284185160487;6434.806955791334;6596.373344532261;6634.336566343327;6635.170603674541;6654.70233304501;6520.152380190734;6550.934490655032;6560.507961902972;6616;6643.60138391698;6504.837379065561;6521.152250207443;6530.412262896362;6536.346365363425;6555.868882622416;6560.770373036921;6616.371444712745;6654.105464123122;6667.966660166668;6679.499037572299;6345.1434056401795;6357.237131544244;6407.807765767035;6408.359164083555;6431.716740557034 17:29:11 INFO - PID 3583 | |5;yui.html;872.1278721278725;892.4957399069525;903.5030733096795;905.9229965452936;920.2270093121776;20228.696569551452;21429.57140857182;22189.556208875812;22543.210987615435;22659.09363625455;18802.24791008358;19361.903190484027;19372;19376.61246775067;19444.805551944475;1032.3599368391608;1057.4554104636118;1063.1229235880392;1066.0831684751108;1070.6359837655195;1014.1278500489573;1058.2909450668033;1070.7965486557569;1071.5071067309048;1075.2580719303694;1110.744528758386;1111.5609334312983;1111.8665760108759;1112.5382966069071;1112.760756437366;1112.7941330853819;1118.3010618363523;1119.026446991119;1119.2221406122749;1120.3501968858084;812.7642983534768;814.287498438866;814.9348052155814;815.6166601697192;815.6981916690862;19716.60566788656;19936.302229422046;19938.501537461536;20513;21186.258480953245;21260.5747885043;21636.134554617798;21721.34835954924;21788.782112178993;22629.207977720864;17138.485845424657;17266.91366543172;17304.13479326041;17380;17514.737278940825;3538.7699799512948;3539.7699149555456;3562.6793588576893;3566.6076731559506;3566.6433356664384;124832;125502.37248813789;128950.99999999907;129200.35399823035;129251.35374323068;17393.739093913602;17752.55618609545;17790.822091778915;17884.374046908295;17984.190711418018;31487.37025259482;31532.36935261282;32127.196820079684;32742;32908;8833;8873.689420870238;8909.420887642282;8914.375993680509;8946.507942063248;4126.91746165075;4182.226288136643;4226.387173859773;4239.385289133058;4244.38456423823;5306.628536002443;5371.382291036581;5403.8108666196495;5414.675119492843;5416.133418652997;996.3772642098688;1094.5949998500605;1135.432283858071;1135.7558125003;1137.4653912661036;607.5686262753494;615.1019511513151;624.6002558362652;626.6616027345274;639.2616527910271;11254.381009044417;11565.421728913521;11698.122640801974;12090.455929483194;12475.376231188404;595.8510372406898;601.1013534765553;608.3186830749589;614.1463365921372;615.1971676961608;5305.363356397257;5329.440408757102;5365.785368585291;5378.569714422837;5449.482299181572;105565;106397;107384;108574;112179;94423.52788236015;95176;101573;102851;106181;87895.12104878997;88352;89011;91629;91865.54067229755;10618.469076545987;11918;12023.519059237533;12068.396580171131;12447.502099916264;40578.594214057484;41854.37218441665;42067.789661052724;42164;42734.786326069414;70156;73888;74775.252247479;75196;75821.62089189519;18511.074446277362;18526.629467410847;18882.71675924835;19313.90343048276;19445;20180.192792288144;20521.897390512953;20761.273355432488;20827.79172208319;21119.683204752248;18123;18211.726824097914;18235.270589176285;18524.629507409507;18571.81428185701;18576.44270671882;18869.245230191187;18937.43187704371;18939.526511837314;19143.329983450512;17624.206910689307;17703.911480442515;17766.911165444606;17859.642807143526;17879.28482860671;13057.347132643139;13583.456661733815;13690.315484225948;13879;13930.79103813464;13169.47322107105;13595.252261125734;13837.654058648613;14282.07166534172;14587.489437869624 17:29:11 INFO - PID 3583 | __end_tp_report 17:29:11 INFO - PID 3583 | __start_cc_report 17:29:11 INFO - PID 3583 | _x_x_mozilla_cycle_collect,358 17:29:11 INFO - PID 3583 | __end_cc_report 17:29:11 INFO - PID 3583 | __startTimestamp1502584151011__endTimestamp 17:29:11 INFO - PID 3583 | 17:29:11 INFO - PID 3583 | ------- Summary: start ------- 17:29:11 INFO - PID 3583 | Number of tests: 6 17:29:11 INFO - PID 3583 | 17:29:11 INFO - PID 3583 | [#0] dojo.html Cycles:165 Average:6171.35 Median:6405.81 stddev:731.84 (11.4%) stddev-sans-first:694.07 17:29:11 INFO - PID 3583 | Values: 3129.1 3141.4 3170.7 3188.8 3211.6 6151.7 6171.0 6182.6 6214.8 6226.4 6180.6 6200.9 6210.0 6231.3 6235.0 5723.6 5724.3 5730.1 5738.1 5801.7 5728.4 5797.4 5823.2 5823.8 5824.8 5672.0 5738.1 5744.9 5760.5 5788.6 5605.9 5662.2 5696.3 5699.8 5730.9 5616.3 5684.8 5714.6 5736.9 5756.4 6174.4 6217.9 6235.6 6245.6 6274.7 6699.4 6700.9 6764.4 6782.6 6802.4 6612.9 6738.7 6757.5 6819.4 6831.0 6333.3 6347.2 6358.4 6374.7 6375.0 6895.6 6902.5 6917.5 7004.5 7008.1 6622.0 6623.3 6697.3 6713.2 6714.8 6524.4 6624.3 6681.9 6785.2 6792.6 6505.3 6523.3 6528.8 6574.4 6695.5 6758.8 6811.4 6817.6 6853.9 6867.5 5397.5 5405.3 5472.9 5473.5 5475.0 4554.6 4609.8 4639.0 4684.3 4684.4 5733.4 5772.6 5772.7 5775.6 5797.4 6725.8 6742.8 6745.5 6755.9 6777.7 5711.6 5794.7 5826.4 5837.8 5856.0 5839.4 5859.0 5949.7 5950.4 5966.0 6399.1 6528.6 6597.8 6605.7 6606.6 6672.3 6680.0 6689.1 6689.6 6693.3 6441.1 6445.0 6451.7 6452.6 6460.1 6542.5 6545.3 6612.6 6644.3 6657.2 6770.2 6817.0 6826.5 6835.9 6867.6 6392.5 6444.7 6452.7 6453.1 6496.4 6469.9 6483.4 6491.6 6493.1 6494.2 6167.8 6246.7 6252.0 6330.8 6438.9 6431.6 6599.9 6600.2 6658.3 6676.0 6382.7 6405.8 6406.4 6409.4 6417.9 17:29:11 INFO - PID 3583 | 17:29:11 INFO - PID 3583 | [#1] ext.html Cycles:180 Average:39114.69 Median:37801.91 stddev:54916.85 (145.3%) stddev-sans-first:54995.51 17:29:11 INFO - PID 3583 | Values: 792.5 815.5 851.2 871.6 878.5 41656.6 42395.6 42412.0 42462.6 42658.8 37733.8 38077.6 38098.6 38237.2 38332.8 6730.2 6856.5 6860.6 6873.9 6876.2 6781.0 6879.4 6888.4 6894.2 6897.7 7481.7 7600.3 7659.0 7676.6 7682.4 7567.4 7575.0 7575.8 7623.1 7648.5 7439.8 7488.2 7619.5 7621.7 7627.4 38724.2 39008.4 39072.0 39084.8 39158.4 3560.3 3585.5 3600.2 3604.4 3619.0 31847.7 31877.7 31891.5 31905.4 31980.8 5108.0 5129.2 5144.2 5163.8 5170.3 183072.0 183392.0 183436.0 185287.0 200184.0 51016.0 51189.7 51228.5 51233.7 51351.7 52909.0 53058.5 53169.0 53185.0 53259.2 14642.0 14751.7 14779.9 14783.4 14862.6 11942.9 11995.4 11996.5 12000.2 12002.7 14683.4 14756.3 14831.1 14836.4 14941.8 5834.6 5900.9 5950.3 6008.3 6088.9 981.8 982.6 1007.3 1009.7 1012.1 22304.6 22310.9 23401.9 30507.4 30771.7 1102.4 1128.6 1128.8 1135.2 1135.3 12678.4 12794.2 12884.0 12923.0 12926.4 233158.0 233963.0 234782.0 235721.0 235908.0 54283.0 54337.5 54646.7 54670.0 54796.2 1477.1 1520.7 1537.5 1539.4 1558.1 23734.6 28400.0 28870.0 28994.0 29413.0 52195.0 52612.5 52636.0 52648.2 52649.7 207673.0 209523.0 210896.0 211669.0 212048.0 36017.6 36523.1 37870.0 37882.6 37986.8 46430.5 46461.8 46683.8 46829.8 46842.8 34431.3 34550.3 35051.6 35052.8 35625.3 33402.7 34019.8 34122.7 34172.3 34176.1 37957.2 37972.6 37993.0 38243.4 38251.8 33543.7 33576.2 33648.8 33697.3 33948.8 24489.9 24691.1 24749.0 24992.3 25272.9 17:29:11 INFO - PID 3583 | 17:29:11 INFO - PID 3583 | [#2] jquery.html Cycles:180 Average:35162.50 Median:12881.67 stddev:80882.92 (627.9%) stddev-sans-first:81068.12 17:29:11 INFO - PID 3583 | Values: 570.9 572.4 575.8 576.7 586.1 22094.6 22835.2 22839.9 22849.0 22987.4 25370.5 26042.6 26081.6 26081.7 26087.0 6381.9 6505.0 6525.8 6526.9 6529.2 6060.6 6464.3 6505.2 6693.7 6708.0 6655.7 6662.7 6671.3 6672.1 6672.3 6641.6 6655.9 6659.3 6662.0 6664.1 6169.8 6177.0 6192.4 6207.3 6215.8 28211.9 28641.0 29251.7 29593.1 29817.6 1909.4 1937.4 1938.7 1943.2 1943.3 19308.4 19783.6 19787.3 19811.1 19818.4 2335.3 2372.8 2377.7 2384.9 2482.6 136947.3 143232.3 143249.0 144682.3 145291.0 14490.0 14823.8 15030.9 15331.6 15404.0 23725.0 23779.6 23785.4 23822.5 23899.3 10038.7 10072.5 10156.2 10238.5 10273.7 9342.6 9397.1 9437.0 9474.1 9605.5 7719.9 9264.1 9296.9 9314.8 9413.2 2724.8 2796.6 2796.6 2834.5 2834.6 951.0 969.9 970.2 973.7 980.6 19319.0 19866.9 20013.3 20046.3 23746.4 960.4 1035.2 1035.3 1038.3 1047.1 11017.7 11089.4 11261.8 11269.7 11398.4 457824.0 463832.0 470083.0 487231.0 493261.0 127199.7 129009.0 130747.0 131968.3 132710.0 1221.5 1233.4 1257.1 1264.1 1266.5 17672.8 17714.6 17966.2 18190.2 18204.0 52691.0 54663.0 55498.7 56529.2 57016.7 73360.3 73559.3 76405.6 81980.2 82826.2 12813.7 13345.9 14857.7 15319.2 15352.4 22933.5 23390.5 23790.3 23888.8 24537.5 18725.4 18969.0 18990.3 19100.9 19117.3 16967.2 17326.2 17718.6 18050.8 18250.2 18346.4 18746.6 18875.9 19089.0 19358.8 14812.6 15159.8 15162.7 15169.9 15191.7 18531.8 18667.3 18755.4 18872.8 18881.5 17:29:11 INFO - PID 3583 | 17:29:11 INFO - PID 3583 | [#3] mootools.html Cycles:180 Average:8901.88 Median:2617.78 stddev:6194.39 (236.6%) stddev-sans-first:6185.91 17:29:11 INFO - PID 3583 | Values: 1368.8 1415.4 1427.3 1431.6 1447.3 13902.0 14120.5 14470.3 14478.9 14482.4 14713.8 14903.3 14903.5 14918.1 14930.4 2719.8 2748.9 2749.1 2749.3 2758.9 2715.9 2812.5 2818.8 2819.3 2819.6 2724.5 2754.7 2759.8 2762.0 2775.5 2571.5 2624.9 2695.7 2716.5 2736.9 2572.9 2577.5 2597.9 2599.2 2610.7 14054.8 14312.4 14346.6 14349.0 14351.9 7310.9 7597.8 7597.8 7598.0 7606.8 4993.4 5159.7 5201.5 5256.9 5336.9 1879.2 1893.7 1914.9 1953.8 1960.6 24006.0 24252.8 24272.2 24275.5 24285.8 6354.8 6504.8 6542.9 6655.2 6728.7 16384.6 16415.2 16422.8 16428.3 16469.6 7036.8 7147.6 7154.8 7181.4 7201.0 5422.9 6135.7 6209.6 6652.7 6680.6 4875.1 4893.5 4975.2 5144.0 5159.6 1490.9 1509.6 1520.8 1524.6 1537.3 894.6 919.4 929.1 935.3 938.0 7360.9 7366.8 7384.3 7419.6 7467.7 719.0 733.9 734.6 737.6 741.0 4628.9 4633.7 4811.7 4908.0 5399.1 19115.4 19504.9 19621.2 20046.3 20257.9 19176.1 19818.3 20057.5 20175.2 20181.9 10023.6 10594.5 10616.6 10625.7 10675.9 7918.3 8749.9 8753.2 8759.4 9033.2 17309.8 17353.9 17507.0 17512.3 17580.0 18073.8 18079.7 18087.7 18237.8 18341.0 11797.4 11974.4 12010.2 12037.4 12044.9 13244.7 13403.0 13517.6 13568.1 13575.3 10605.6 10725.1 10744.9 10906.7 10984.3 10811.0 10888.7 10919.7 11009.5 11073.1 9716.9 9739.0 9776.5 9867.1 9873.7 7383.4 7416.7 7417.1 7455.6 7455.7 7583.5 7588.3 7605.4 7623.2 7707.6 17:29:11 INFO - PID 3583 | 17:29:11 INFO - PID 3583 | [#4] prototype.html Cycles:180 Average:5654.52 Median:6213.84 stddev:1526.15 (24.6%) stddev-sans-first:1480.68 17:29:11 INFO - PID 3583 | Values: 505.1 511.0 520.7 523.6 529.3 6243.6 6254.8 6257.0 6268.4 6330.9 6197.7 6230.0 6293.7 6294.1 6324.5 5125.9 5133.7 5133.7 5144.1 5163.3 4988.2 5012.6 5072.6 5112.3 5159.0 5095.5 5117.1 5137.9 5161.4 5184.5 5100.6 5126.7 5138.2 5153.5 5154.4 4643.6 4734.6 4743.6 4776.5 4825.4 6102.9 6120.6 6139.5 6142.3 6145.3 5819.5 5911.8 5961.4 5967.1 6001.5 6650.3 6670.9 6701.2 6726.0 6761.4 5240.3 5252.2 5265.0 5284.0 5290.0 6717.9 6797.5 6926.7 6955.9 7073.5 5386.0 5394.2 5402.7 5407.7 5410.8 5460.7 5517.9 5518.0 5526.8 5544.3 5924.8 5935.1 5940.5 5946.8 5998.7 6646.0 6654.8 6667.4 6697.0 6822.9 1905.2 1909.6 1934.6 1944.8 1947.5 1345.9 1406.8 1408.2 1427.9 1429.7 5769.6 5782.3 5841.3 5851.2 5887.5 6658.6 6678.5 6709.7 6758.6 6844.2 5730.6 5760.1 5775.0 5793.5 5839.4 3623.8 3640.4 3648.5 3650.8 3660.8 6625.7 6640.4 6641.6 6648.4 6652.9 7024.8 7035.3 7035.6 7047.0 7052.8 6600.2 6637.8 6646.9 6652.2 6657.2 6855.3 6865.6 6874.3 6883.5 6906.0 6720.0 6721.6 6743.6 6776.7 6790.8 6599.6 6626.2 6628.3 6628.9 6635.0 5440.8 5455.9 5467.7 5488.7 5489.5 6519.2 6710.8 6734.5 6795.4 6817.3 6434.8 6596.4 6634.3 6635.2 6654.7 6520.2 6550.9 6560.5 6616.0 6643.6 6504.8 6521.2 6530.4 6536.3 6555.9 6560.8 6616.4 6654.1 6668.0 6679.5 6345.1 6357.2 6407.8 6408.4 6431.7 17:29:11 INFO - PID 3583 | 17:29:11 INFO - PID 3583 | [#5] yui.html Cycles:180 Average:24254.20 Median:19444.90 stddev:32622.24 (167.8%) stddev-sans-first:32666.51 17:29:11 INFO - PID 3583 | Values: 872.1 892.5 903.5 905.9 920.2 20228.7 21429.6 22189.6 22543.2 22659.1 18802.2 19361.9 19372.0 19376.6 19444.8 1032.4 1057.5 1063.1 1066.1 1070.6 1014.1 1058.3 1070.8 1071.5 1075.3 1110.7 1111.6 1111.9 1112.5 1112.8 1112.8 1118.3 1119.0 1119.2 1120.4 812.8 814.3 814.9 815.6 815.7 19716.6 19936.3 19938.5 20513.0 21186.3 21260.6 21636.1 21721.3 21788.8 22629.2 17138.5 17266.9 17304.1 17380.0 17514.7 3538.8 3539.8 3562.7 3566.6 3566.6 124832.0 125502.4 128951.0 129200.4 129251.4 17393.7 17752.6 17790.8 17884.4 17984.2 31487.4 31532.4 32127.2 32742.0 32908.0 8833.0 8873.7 8909.4 8914.4 8946.5 4126.9 4182.2 4226.4 4239.4 4244.4 5306.6 5371.4 5403.8 5414.7 5416.1 996.4 1094.6 1135.4 1135.8 1137.5 607.6 615.1 624.6 626.7 639.3 11254.4 11565.4 11698.1 12090.5 12475.4 595.9 601.1 608.3 614.1 615.2 5305.4 5329.4 5365.8 5378.6 5449.5 105565.0 106397.0 107384.0 108574.0 112179.0 94423.5 95176.0 101573.0 102851.0 106181.0 87895.1 88352.0 89011.0 91629.0 91865.5 10618.5 11918.0 12023.5 12068.4 12447.5 40578.6 41854.4 42067.8 42164.0 42734.8 70156.0 73888.0 74775.3 75196.0 75821.6 18511.1 18526.6 18882.7 19313.9 19445.0 20180.2 20521.9 20761.3 20827.8 21119.7 18123.0 18211.7 18235.3 18524.6 18571.8 18576.4 18869.2 18937.4 18939.5 19143.3 17624.2 17703.9 17766.9 17859.6 17879.3 13057.3 13583.5 13690.3 13879.0 13930.8 13169.5 13595.3 13837.7 14282.1 14587.5 17:29:11 INFO - PID 3583 | -------- Summary: end -------- 17:29:11 INFO - PID 3583 | 17:29:11 INFO - TEST-INFO | 3583: exit 0 17:29:11 INFO - TEST-OK | dromaeo_css | took 1116166ms 17:29:11 INFO - TEST-START | kraken 17:29:11 INFO - Initialising browser for kraken test... 17:29:11 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37330/getInfo.html -profile /tmp/tmpKI4oqs/profile 17:29:11 INFO - TEST-INFO | started process 3959 (/builds/slave/test/build/application/firefox/firefox http://localhost:37330/getInfo.html) 17:29:17 INFO - TEST-INFO | 3959: exit 0 17:29:17 INFO - Browser initialized. 17:29:17 INFO - Running cycle 1/1 for kraken test... 17:29:17 INFO - TEST-INFO | started process 4194 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKI4oqs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:29:18 INFO - PID 4194 | 17:29:18 INFO - PID 4194 | (/builds/slave/test/build/application/firefox/firefox:4248): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:29:18 INFO - PID 4194 | 17:29:29 INFO - PID 4194 | RSS: Main: 167550976 17:29:29 INFO - PID 4194 | 17:29:31 INFO - PID 4194 | Cycle 1(1): loaded http://localhost:37330/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37330/tests/kraken/driver.html?testName=audio-beat-detection) 17:29:32 INFO - PID 4194 | RSS: Main: 173977600 17:29:32 INFO - PID 4194 | 17:29:35 INFO - PID 4194 | Cycle 1(1): loaded http://localhost:37330/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37330/tests/kraken/driver.html?testName=audio-dft) 17:29:36 INFO - PID 4194 | RSS: Main: 174309376 17:29:36 INFO - PID 4194 | 17:29:39 INFO - PID 4194 | Cycle 1(1): loaded http://localhost:37330/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37330/tests/kraken/driver.html?testName=audio-fft) 17:29:40 INFO - PID 4194 | RSS: Main: 169304064 17:29:40 INFO - PID 4194 | 17:29:42 INFO - PID 4194 | Cycle 1(1): loaded http://localhost:37330/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37330/tests/kraken/driver.html?testName=audio-oscillator) 17:29:42 INFO - PID 4194 | RSS: Main: 170098688 17:29:42 INFO - PID 4194 | 17:29:45 INFO - PID 4194 | Cycle 1(1): loaded http://localhost:37330/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37330/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:29:45 INFO - PID 4194 | RSS: Main: 170131456 17:29:45 INFO - PID 4194 | 17:29:48 INFO - PID 4194 | Cycle 1(1): loaded http://localhost:37330/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37330/tests/kraken/driver.html?testName=imaging-darkroom) 17:29:49 INFO - PID 4194 | RSS: Main: 172662784 17:29:49 INFO - PID 4194 | 17:29:53 INFO - PID 4194 | Cycle 1(1): loaded http://localhost:37330/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37330/tests/kraken/driver.html?testName=imaging-desaturate) 17:29:53 INFO - PID 4194 | RSS: Main: 172380160 17:29:53 INFO - PID 4194 | 17:29:56 INFO - PID 4194 | Cycle 1(1): loaded http://localhost:37330/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37330/tests/kraken/driver.html?testName=json-parse-financial) 17:29:57 INFO - PID 4194 | RSS: Main: 173490176 17:29:57 INFO - PID 4194 | 17:29:58 INFO - PID 4194 | Cycle 1(1): loaded http://localhost:37330/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37330/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:29:59 INFO - PID 4194 | RSS: Main: 173432832 17:29:59 INFO - PID 4194 | 17:30:01 INFO - PID 4194 | Cycle 1(1): loaded http://localhost:37330/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37330/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:30:02 INFO - PID 4194 | RSS: Main: 173481984 17:30:02 INFO - PID 4194 | 17:30:04 INFO - PID 4194 | Cycle 1(1): loaded http://localhost:37330/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37330/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:30:04 INFO - PID 4194 | RSS: Main: 173465600 17:30:04 INFO - PID 4194 | 17:30:07 INFO - PID 4194 | Cycle 1(1): loaded http://localhost:37330/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37330/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:30:08 INFO - PID 4194 | RSS: Main: 173965312 17:30:08 INFO - PID 4194 | 17:30:11 INFO - PID 4194 | Cycle 1(1): loaded http://localhost:37330/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37330/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:30:12 INFO - PID 4194 | RSS: Main: 173957120 17:30:12 INFO - PID 4194 | 17:30:13 INFO - PID 4194 | Cycle 1(1): loaded http://localhost:37330/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37330/tests/kraken/driver.html?testName=ai-astar) 17:30:14 INFO - PID 4194 | RSS: Main: 175169536 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | __start_tp_report 17:30:14 INFO - PID 4194 | _x_x_mozilla_page_load 17:30:14 INFO - PID 4194 | _x_x_mozilla_page_load_details 17:30:14 INFO - PID 4194 | |i|pagename|runs| 17:30:14 INFO - PID 4194 | |0;ai-astar;107;108;98;110;108;109;116;108;110;111 17:30:14 INFO - PID 4194 | |1;audio-beat-detection;145;117;144;184;127;143;149;130;178;117 17:30:14 INFO - PID 4194 | |2;audio-dft;168;161;163;170;162;166;166;172;167;164 17:30:14 INFO - PID 4194 | |3;audio-fft;83;75;80;164;77;76;77;75;158;76 17:30:14 INFO - PID 4194 | |4;audio-oscillator;79;85;138;79;81;82;138;79;80;137 17:30:14 INFO - PID 4194 | |5;imaging-gaussian-blur;115;113;114;114;113;113;114;114;115;115 17:30:14 INFO - PID 4194 | |6;imaging-darkroom;150;151;150;155;152;152;150;150;150;149 17:30:14 INFO - PID 4194 | |7;imaging-desaturate;94;94;95;94;94;96;94;94;94;98 17:30:14 INFO - PID 4194 | |8;json-parse-financial;63;54;54;55;54;57;56;54;54;58 17:30:14 INFO - PID 4194 | |9;json-stringify-tinderbox;55;64;55;56;57;55;55;55;55;62 17:30:14 INFO - PID 4194 | |10;stanford-crypto-aes;77;76;77;80;84;83;77;78;79;77 17:30:14 INFO - PID 4194 | |11;stanford-crypto-ccm;134;128;130;125;124;121;124;137;137;162 17:30:14 INFO - PID 4194 | |12;stanford-crypto-pbkdf2;174;176;175;187;183;184;180;174;178;184 17:30:14 INFO - PID 4194 | |13;stanford-crypto-sha256-iterative;77;68;67;69;67;71;70;70;70;67 17:30:14 INFO - PID 4194 | __end_tp_report 17:30:14 INFO - PID 4194 | __start_cc_report 17:30:14 INFO - PID 4194 | _x_x_mozilla_cycle_collect,949 17:30:14 INFO - PID 4194 | __end_cc_report 17:30:14 INFO - PID 4194 | __startTimestamp1502584214145__endTimestamp 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | ------- Summary: start ------- 17:30:14 INFO - PID 4194 | Number of tests: 14 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | [#0] ai-astar Cycles:10 Average:108.50 Median:110.00 stddev:4.48 (4.1%) stddev-sans-first:4.72 17:30:14 INFO - PID 4194 | Values: 107.0 108.0 98.0 110.0 108.0 109.0 116.0 108.0 110.0 111.0 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | [#1] audio-beat-detection Cycles:10 Average:143.40 Median:144.50 stddev:22.91 (15.9%) stddev-sans-first:24.29 17:30:14 INFO - PID 4194 | Values: 145.0 117.0 144.0 184.0 127.0 143.0 149.0 130.0 178.0 117.0 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | [#2] audio-dft Cycles:10 Average:165.90 Median:166.50 stddev:3.51 (2.1%) stddev-sans-first:3.64 17:30:14 INFO - PID 4194 | Values: 168.0 161.0 163.0 170.0 162.0 166.0 166.0 172.0 167.0 164.0 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | [#3] audio-fft Cycles:10 Average:94.10 Median:76.50 stddev:35.37 (46.2%) stddev-sans-first:37.29 17:30:14 INFO - PID 4194 | Values: 83.0 75.0 80.0 164.0 77.0 76.0 77.0 75.0 158.0 76.0 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:79.50 stddev:27.57 (34.7%) stddev-sans-first:28.39 17:30:14 INFO - PID 4194 | Values: 79.0 85.0 138.0 79.0 81.0 82.0 138.0 79.0 80.0 137.0 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.82 (0.7%) stddev-sans-first:0.78 17:30:14 INFO - PID 4194 | Values: 115.0 113.0 114.0 114.0 113.0 113.0 114.0 114.0 115.0 115.0 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:150.50 stddev:1.73 (1.1%) stddev-sans-first:1.80 17:30:14 INFO - PID 4194 | Values: 150.0 151.0 150.0 155.0 152.0 152.0 150.0 150.0 150.0 149.0 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.00 stddev:1.34 (1.4%) stddev-sans-first:1.39 17:30:14 INFO - PID 4194 | Values: 94.0 94.0 95.0 94.0 94.0 96.0 94.0 94.0 94.0 98.0 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:55.50 stddev:2.88 (5.2%) stddev-sans-first:1.54 17:30:14 INFO - PID 4194 | Values: 63.0 54.0 54.0 55.0 54.0 57.0 56.0 54.0 54.0 58.0 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | [#9] json-stringify-tinderbox Cycles:10 Average:56.90 Median:55.50 stddev:3.31 (6.0%) stddev-sans-first:3.44 17:30:14 INFO - PID 4194 | Values: 55.0 64.0 55.0 56.0 57.0 55.0 55.0 55.0 55.0 62.0 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:78.50 stddev:2.74 (3.5%) stddev-sans-first:2.83 17:30:14 INFO - PID 4194 | Values: 77.0 76.0 77.0 80.0 84.0 83.0 77.0 78.0 79.0 77.0 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | [#11] stanford-crypto-ccm Cycles:10 Average:132.20 Median:132.00 stddev:11.89 (9.0%) stddev-sans-first:12.59 17:30:14 INFO - PID 4194 | Values: 134.0 128.0 130.0 125.0 124.0 121.0 124.0 137.0 137.0 162.0 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.50 Median:181.50 stddev:4.77 (2.6%) stddev-sans-first:4.62 17:30:14 INFO - PID 4194 | Values: 174.0 176.0 175.0 187.0 183.0 184.0 180.0 174.0 178.0 184.0 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.60 Median:70.00 stddev:2.99 (4.3%) stddev-sans-first:1.56 17:30:14 INFO - PID 4194 | Values: 77.0 68.0 67.0 69.0 67.0 71.0 70.0 70.0 70.0 67.0 17:30:14 INFO - PID 4194 | -------- Summary: end -------- 17:30:14 INFO - PID 4194 | 17:30:14 INFO - PID 4194 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:30:14 INFO - TEST-INFO | 4194: exit 0 17:30:14 INFO - TEST-OK | kraken | took 63177ms 17:30:14 INFO - SUITE-END | took 1179s 17:30:15 INFO - Completed test suite (00:19:39) 17:30:15 INFO - javascript benchmark 17:30:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8612.900065432386, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3129.061281615515, 3141.418837515061, 3170.6512283648794, 3188.7608429367797, 3211.5985501812274, 6151.723172457239, 6171.0, 6182.598131121474, 6214.844628884274, 6226.377362263771, 6180.629162250269, 6200.875982480347, 6210.0, 6231.252249730027, 6235.0, 5723.599348045628, 5724.284464441945, 5730.111832665943, 5738.081906894898, 5801.6519008859395, 5728.369879313273, 5797.449242321973, 5823.184754134403, 5823.796167134151, 5824.796132135376, 5672.0, 5738.0532212184935, 5744.856378590527, 5760.539156867461, 5788.6237394569425, 5605.859853503654, 5662.235598194274, 5696.316442026942, 5699.772009119631, 5730.942690573124, 5616.269884914976, 5684.772609095631, 5714.628549144292, 5736.942630573682, 5756.395578464243, 6174.382561743834, 6217.937820621826, 6235.6258624482225, 6245.594036387665, 6274.686265686743, 6699.430548403391, 6700.865982680368, 6764.3573860483175, 6782.626955517445, 6802.387785099315, 6612.86774264512, 6738.663066846638, 6757.526973111884, 6819.420349270318, 6831.04365388846, 6333.303336632966, 6347.174867267272, 6358.39595238447, 6374.713137908805, 6374.968125159345, 6895.620740859301, 6902.516823822284, 6917.515773895779, 7004.544704594185, 7008.088948436671, 6622.03980422836, 6623.30455302196, 6697.263301036882, 6713.194416669933, 6714.764983225564, 6524.445422139076, 6624.304448032887, 6681.866362672817, 6785.185777706707, 6792.592444453348, 6505.251896032017, 6523.347665233438, 6528.771492997722, 6574.441172500391, 6695.497837662195, 6758.831029224309, 6811.3869751721595, 6817.5909445433435, 6853.862922741617, 6867.450603951672, 5397.460253974571, 5405.324334458192, 5472.91790623141, 5473.452654734574, 5475.0, 4554.567316104964, 4609.792559334838, 4639.0, 4684.344191813149, 4684.367610372624, 5733.426657334233, 5772.624779389326, 5772.740226689809, 5775.595708300379, 5797.420257974169, 6725.798226053226, 6742.797716068526, 6745.460363171032, 6755.864882702417, 6777.661116944231, 5711.628744131618, 5794.681292528951, 5826.41735826414, 5837.824865254049, 5856.033754430557, 5839.445252700986, 5859.0, 5949.672767997715, 5950.375210602824, 5966.0, 6399.072134540373, 6528.575642583217, 6597.802065937934, 6605.669716514155, 6606.636634985122, 6672.266050734318, 6680.0, 6689.130413046273, 6689.565178263398, 6693.330666933268, 6441.1304473895425, 6444.967775161094, 6451.70967306463, 6452.612843229421, 6460.12788273577, 6542.54202205841, 6545.2800191979795, 6612.603243805387, 6644.302348253364, 6657.234418041987, 6770.1537307836525, 6817.0, 6826.453883689193, 6835.93164068353, 6867.553609015398, 6392.52056095804, 6444.677766111582, 6452.709628066655, 6453.064305675557, 6496.415322620798, 6469.935300646934, 6483.448906842971, 6491.642959637265, 6493.123428337114, 6494.220693516808, 6167.753289868355, 6246.718897649707, 6252.0, 6330.778422755181, 6438.871222575616, 6431.5819471734185, 6599.934000659932, 6600.207975043026, 6658.334166583303, 6675.966620166868, 6382.744690212339, 6405.807825765234, 6406.423421892052, 6409.4231519163495, 6417.935820641734], "value": 6116.258176660531, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.4690457393546, 815.4821688227973, 851.2211326636128, 871.577285016767, 878.5343767803066, 41656.58343416569, 42395.57604423955, 42412.0, 42462.57537424625, 42658.7867060665, 37733.811330943376, 38077.61922380776, 38098.619013809854, 38237.23525529488, 38332.808335958354, 6730.192376914777, 6856.52004359695, 6860.55406398585, 6873.934540146285, 6876.243613202569, 6781.016752570874, 6879.449644028491, 6888.380045795878, 6894.172699276094, 6897.689603967833, 7481.738139165131, 7600.2779735925, 7659.042619672541, 7676.577788221645, 7682.385409167253, 7567.394608431312, 7574.962125189394, 7575.772726818204, 7623.123340815766, 7648.541087534738, 7439.814004649873, 7488.213737557532, 7619.504732192445, 7621.6570254338685, 7627.389808815337, 38724.225515489816, 39008.414873776914, 39072.0, 39084.80457597694, 39158.41262381067, 3560.2701446203464, 3585.4980302757635, 3600.225951420457, 3604.4052731299303, 3619.040954147153, 31847.681523184703, 31877.681223187705, 31891.52162717561, 31905.36189276225, 31980.840095799373, 5107.9744601277125, 5129.17933130701, 5144.228365745168, 5163.7676304566385, 5170.25031370453, 183072.0, 183392.0, 183436.0, 185287.0, 200184.0, 51016.0, 51189.74405128025, 51228.48771512312, 51233.743831280604, 51351.743241283555, 52909.0, 53058.46941530535, 53169.0, 53185.0, 53259.20111198335, 14642.0, 14751.704965900837, 14779.926100369645, 14783.408663653549, 14862.628434289012, 11942.880571194177, 11995.40022998864, 11996.520139194508, 12000.1599888007, 12002.699932501757, 14683.412663493555, 14756.335964881606, 14831.11013339203, 14836.406543738345, 14941.775873361908, 5834.620749651259, 5900.940990590125, 5950.345461999176, 6008.279006519158, 6088.908666370008, 981.7790997025612, 982.6069572171189, 1007.2948935745007, 1009.6516701737888, 1012.1295685710284, 22304.55390892173, 22310.888445557994, 23401.88299058528, 30507.389852202832, 30771.69228307733, 1102.3716481605409, 1128.6388355726253, 1128.8250321200228, 1135.1883403366467, 1135.2961164078324, 12678.366081695878, 12794.168379055332, 12884.0, 12923.030847559186, 12926.353682316034, 233158.0, 233963.0, 234782.0, 235721.0, 235908.0, 54283.0, 54337.45662543324, 54646.72676636671, 54670.0, 54796.17805732917, 1477.0842077911548, 1520.7338715724704, 1537.5233677559768, 1539.3688587678998, 1558.0729465967731, 23734.643980339963, 28400.0, 28869.989550365637, 28994.0, 29412.970546030785, 52195.0, 52612.47387526229, 52636.0, 52648.21027684664, 52649.736751316, 207673.0, 209523.0, 210896.0, 211669.0, 212048.0, 36017.63982360143, 36523.08692282714, 37870.0, 37882.62117378901, 37986.810065949496, 46430.53569464262, 46461.76769116133, 46683.76658116688, 46829.76585117052, 46842.76578617085, 34431.311373772885, 34550.30899382049, 35051.649483504836, 35052.82473587616, 35625.28749424945, 33402.66597333996, 34019.82990085034, 34122.658773411946, 34172.31655366928, 34176.14559636029, 37957.240855182186, 37972.62027379691, 37993.0, 38243.42634860424, 38251.80874095612, 33543.664563354054, 33576.1605959853, 33648.831755842046, 33697.326053478304, 33948.83025584856, 24489.877550612135, 24691.135810246557, 24749.0, 24992.250232493054, 25272.873635631706], "value": 17645.726211696237, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.9229254050701, 572.3646752105166, 575.7840809696365, 576.723172877019, 586.1295975476417, 22094.558108837813, 22835.20076797308, 22839.885800570995, 22849.0, 22987.42531436715, 25370.492590148187, 26042.609360859602, 26081.60877586838, 26081.73918260817, 26087.0, 6381.90427143592, 6505.0, 6525.836854078662, 6526.869462610745, 6529.249136349309, 6060.606060606068, 6464.256610489783, 6505.1543299371015, 6693.732250709966, 6708.027336036272, 6655.700493477805, 6662.733490660367, 6671.266160722316, 6672.132622759059, 6672.266050734415, 6641.601503909781, 6655.900161497581, 6659.300773418794, 6662.0, 6664.0670306156935, 6169.845753856144, 6176.96911515444, 6192.411720886508, 6207.286162091372, 6215.81352559424, 28211.858940705166, 28640.99269576706, 29251.70748292511, 29593.112206633836, 29817.552736708967, 1909.446260584429, 1937.3800383877165, 1938.738270333501, 1943.24213556713, 1943.2906988949073, 19308.420747377462, 19783.604327913505, 19787.30744423938, 19811.10850011753, 19818.405447836587, 2335.2643917166038, 2372.79831214347, 2377.738448770634, 2384.8688322142275, 2482.6027835546224, 136947.31526342305, 143232.28383858013, 143249.0, 144682.27658861745, 145291.0, 14489.971404003449, 14823.77764333536, 15030.924845375703, 15331.61670958235, 15404.0, 23725.0, 23779.643305350433, 23785.405364866016, 23822.523549529258, 23899.283021509036, 10038.698839034842, 10072.496375181212, 10156.187504999582, 10238.488075596191, 10273.691789246335, 9342.57958391874, 9397.060293970548, 9437.0, 9474.05259474061, 9605.511145772416, 7719.884201736979, 9264.147170565913, 9296.907030929604, 9314.767130821649, 9413.246940244764, 2724.8365098094177, 2796.566532187514, 2796.566532187514, 2834.489791837489, 2834.6173266609158, 950.9952450237705, 969.8981606931312, 970.1801977329145, 973.6981535723949, 980.6420656460341, 19319.0, 19866.90066549687, 20013.299534516515, 20046.298379556647, 23746.406339841644, 960.4429430930044, 1035.233926894093, 1035.321864178964, 1038.2939601071344, 1047.1047254597363, 11017.669469915916, 11089.390083545482, 11261.774764504664, 11269.66191014271, 11398.373089479992, 457824.0, 463832.0, 470083.0, 487231.0, 493261.0, 127199.72800272064, 129009.0, 130747.0, 131968.34015829858, 132710.0, 1221.4991853340089, 1233.3956361383036, 1257.063487701668, 1264.1151194164306, 1266.5250531050856, 17672.823271767633, 17714.645707086045, 17966.191521381308, 18190.181441834884, 18204.0, 52691.0, 54663.0, 55498.72250638721, 56529.15206271827, 57016.71491642515, 73360.26639733535, 73559.26440735524, 76405.61797190979, 81980.18019819725, 82826.17173828426, 12813.74372512526, 13345.86654133485, 14857.702845942804, 15319.157446340558, 15352.385904564138, 22933.54132917366, 23390.532189356458, 23790.286291411285, 23888.761112388653, 24537.509249814546, 18725.438236852915, 18969.0, 18990.335338263096, 19100.904495477433, 19117.330893418664, 16967.151642417582, 17326.22032008588, 17718.557036074202, 18050.81949180544, 18250.178741956377, 18346.357877474224, 18746.625067498848, 18875.90562047181, 19089.0, 19358.8064119357, 14812.629684257548, 15159.772603410736, 15162.696746065238, 15169.924150379178, 15191.696166076837, 18531.81468185355, 18667.346642867433, 18755.40579050796, 18872.811271887105, 18881.527961801065], "value": 12138.164352658276, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.849426563078, 1415.370160278675, 1427.3220220395308, 1431.6277767780373, 1447.2763618190904, 13902.0, 14120.505782297621, 14470.276486175702, 14478.927605361972, 14482.420703171874, 14713.779293310583, 14903.254837258148, 14903.478378256763, 14918.104913705158, 14930.402783888632, 2719.809613327068, 2748.8625568721486, 2749.134022782827, 2749.285185851683, 2758.9310267243377, 2715.945681086377, 2812.521871281877, 2818.7885908556837, 2819.3092692290415, 2819.6052552642645, 2724.5368287391093, 2754.6969833318317, 2759.7930155238337, 2762.047093752652, 2775.4726601945567, 2571.537123317802, 2624.8950041998296, 2695.7169497202804, 2716.5110280149665, 2736.904208352708, 2572.9099481518056, 2577.5102730481335, 2597.8701064946677, 2599.1552745357835, 2610.673665791776, 14054.789178162337, 14312.355943982442, 14346.64133396663, 14349.0, 14351.928240358835, 7310.926890731131, 7597.81005474862, 7597.810054748676, 7598.0, 7606.771796846104, 4993.425756038066, 5159.664621799571, 5201.479852014806, 5256.8685782855355, 5336.946630533723, 1879.2107314927764, 1893.6591413545557, 1914.9234030638759, 1953.8241558259824, 1960.607878424321, 24006.0, 24252.757472425226, 24272.150474733477, 24275.514489710105, 24285.7571424287, 6354.841128971812, 6504.772332968418, 6542.901856472156, 6655.168103987002, 6728.6635668216395, 16384.590385240463, 16415.179241037902, 16422.83577164237, 16428.342866285213, 16469.58826029359, 7036.824079398056, 7147.571145731273, 7154.821129471805, 7181.425485961111, 7201.0, 5422.864428389322, 6135.662538560333, 6209.627422354673, 6652.667366631649, 6680.632565208863, 4875.146849301358, 4893.486183950705, 4975.228839529878, 5144.0, 5159.638825282194, 1490.8967364150667, 1509.6150481627335, 1520.8022957015517, 1524.5959820647538, 1537.2851623994895, 894.6019021535354, 919.4437365393841, 929.0709290709291, 935.335911502827, 937.9869740680185, 7360.852782944418, 7366.77899663011, 7384.335409813035, 7419.629018549051, 7467.663955122032, 718.9784306470814, 733.886247631618, 734.5519233267703, 737.5500944423898, 740.9703611855465, 4628.861134165981, 4633.698809577367, 4811.711297322172, 4907.950920490821, 5399.055165346049, 19115.426537203904, 19504.90247548753, 19621.21515139407, 20046.298379556938, 20257.898710506648, 19176.137073831713, 19818.306359277645, 20057.498562536053, 20175.192992280437, 20181.899090504452, 10023.599056037678, 10594.470276486145, 10616.575336986589, 10625.73435664099, 10675.87903270161, 7918.259262034979, 8749.868751968854, 8753.240137246796, 8759.444868932793, 9033.187013168847, 17309.826901731325, 17353.913230433765, 17507.0, 17512.299508019536, 17580.0, 18073.81926180774, 18079.728804068214, 18087.728684069487, 18237.81762182361, 18340.991245481626, 11797.351145686725, 11974.401279936143, 12010.219335743148, 12037.398130093285, 12044.91595756386, 13244.668883277609, 13403.0, 13517.59447216585, 13568.118072325267, 13575.32123393846, 10605.628802991858, 10725.141988640735, 10744.892551074388, 10906.72733181645, 10984.286021408583, 10811.027007569357, 10888.67333979982, 10919.672409827717, 11009.504572294427, 11073.11415086775, 9716.9028309716, 9739.0, 9776.462294573867, 9867.11195992364, 9873.703788886345, 7383.372413344711, 7416.703331866881, 7417.072865891763, 7455.589942553212, 7455.6644950978425, 7583.544987300779, 7588.27911348421, 7605.429592780453, 7623.161452240138, 7707.614619269127], "value": 6425.711991334682, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.09083649431017, 510.9907931834625, 520.7344254430241, 523.6125267302195, 529.2986792499936, 6243.625382477049, 6254.8123556293385, 6257.0, 6268.373162683735, 6330.936690633092, 6197.721102550385, 6229.968850155754, 6293.716782744787, 6294.118823364733, 6324.545354568452, 5125.923111153335, 5133.6919784812935, 5133.717645529495, 5144.099782538041, 5163.302954101205, 4988.176950803131, 5012.598992080625, 5072.568831649296, 5112.284280200774, 5159.0, 5095.515925987044, 5117.13008788505, 5137.860256992895, 5161.406438259592, 5184.455632158626, 5100.622831835402, 5126.743662816844, 5138.229265610151, 5153.458886816868, 5154.407243167008, 4643.628509719214, 4734.621230301567, 4743.596794272491, 4776.522347765195, 4825.372701548811, 6102.938970610326, 6120.632762034292, 6139.478144357735, 6142.26292844857, 6145.293291271471, 5819.53443724501, 5911.763529458816, 5961.374055724174, 5967.134765459027, 6001.5198784097165, 6650.268470468293, 6670.933290667128, 6701.195856497252, 6725.966370168167, 6761.425278851303, 5240.34495688039, 5252.185911183735, 5264.973675131638, 5283.973580132075, 5290.0, 6717.932820671828, 6797.490188235902, 6926.653667316614, 6955.89566156508, 7073.469489788286, 5385.973070134624, 5394.217838413487, 5402.702851343214, 5407.729613519308, 5410.756515956791, 5460.672359658433, 5517.944820551743, 5518.0, 5526.806561770319, 5544.306961629796, 5924.755801281693, 5935.139404786368, 5940.524758019348, 5946.762129514771, 5998.730057147439, 6645.966770166215, 6654.833629159213, 6667.43326817226, 6697.0, 6822.897656535156, 1905.1522072677803, 1909.6180763847285, 1934.6420912131018, 1944.7763507196853, 1947.5423275530238, 1345.8586848380976, 1406.8030475733406, 1408.204364534035, 1427.864352886474, 1429.7426463236718, 5769.567282453832, 5782.306123265151, 5841.269841269841, 5851.21008663825, 5887.4701276884425, 6658.633775142317, 6678.465722742266, 6709.672261391375, 6758.594484330956, 6844.247132815386, 5730.598858079894, 5760.135979603009, 5774.971125144431, 5793.536517078624, 5839.416058394127, 3623.7644553103964, 3640.43573246142, 3648.4709717090873, 3650.762700424464, 3660.7803531788177, 6625.668716564152, 6640.402363787282, 6641.568298060611, 6648.40164385198, 6652.935530315126, 7024.754133605299, 7035.261297563682, 7035.6482175891, 7046.956382649077, 7052.7884163474155, 6600.2409722882485, 6637.767678131242, 6646.867062658623, 6652.201735791736, 6657.23441804189, 6855.320446490442, 6865.588064715933, 6874.346937040973, 6883.483738719516, 6906.0, 6719.966400167968, 6721.630310332783, 6743.629100399329, 6776.713294052282, 6790.7623233186605, 6599.568224523317, 6626.237982632059, 6628.304028077176, 6628.90056649141, 6635.0, 5440.7551660174595, 5455.9454405455435, 5467.687754938706, 5488.69812160335, 5489.451054894638, 6519.217693876765, 6710.798676039727, 6734.518405950683, 6795.422389096784, 6817.284185160487, 6434.806955791334, 6596.373344532261, 6634.336566343327, 6635.170603674541, 6654.70233304501, 6520.152380190734, 6550.934490655032, 6560.507961902972, 6616.0, 6643.60138391698, 6504.837379065561, 6521.152250207443, 6530.412262896362, 6536.346365363425, 6555.868882622416, 6560.770373036921, 6616.371444712745, 6654.105464123122, 6667.966660166668, 6679.499037572299, 6345.1434056401795, 6357.237131544244, 6407.807765767035, 6408.359164083555, 6431.716740557034], "value": 5213.828460692999, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [872.1278721278725, 892.4957399069525, 903.5030733096795, 905.9229965452936, 920.2270093121776, 20228.696569551452, 21429.57140857182, 22189.556208875812, 22543.210987615435, 22659.09363625455, 18802.24791008358, 19361.903190484027, 19372.0, 19376.61246775067, 19444.805551944475, 1032.3599368391608, 1057.4554104636118, 1063.1229235880392, 1066.0831684751108, 1070.6359837655195, 1014.1278500489573, 1058.2909450668033, 1070.7965486557569, 1071.5071067309048, 1075.2580719303694, 1110.744528758386, 1111.5609334312983, 1111.8665760108759, 1112.5382966069071, 1112.760756437366, 1112.7941330853819, 1118.3010618363523, 1119.026446991119, 1119.2221406122749, 1120.3501968858084, 812.7642983534768, 814.287498438866, 814.9348052155814, 815.6166601697192, 815.6981916690862, 19716.60566788656, 19936.302229422046, 19938.501537461536, 20513.0, 21186.258480953245, 21260.5747885043, 21636.134554617798, 21721.34835954924, 21788.782112178993, 22629.207977720864, 17138.485845424657, 17266.91366543172, 17304.13479326041, 17380.0, 17514.737278940825, 3538.7699799512948, 3539.7699149555456, 3562.6793588576893, 3566.6076731559506, 3566.6433356664384, 124832.0, 125502.37248813789, 128950.99999999907, 129200.35399823035, 129251.35374323068, 17393.739093913602, 17752.55618609545, 17790.822091778915, 17884.374046908295, 17984.190711418018, 31487.37025259482, 31532.36935261282, 32127.196820079684, 32742.0, 32908.0, 8833.0, 8873.689420870238, 8909.420887642282, 8914.375993680509, 8946.507942063248, 4126.91746165075, 4182.226288136643, 4226.387173859773, 4239.385289133058, 4244.38456423823, 5306.628536002443, 5371.382291036581, 5403.8108666196495, 5414.675119492843, 5416.133418652997, 996.3772642098688, 1094.5949998500605, 1135.432283858071, 1135.7558125003, 1137.4653912661036, 607.5686262753494, 615.1019511513151, 624.6002558362652, 626.6616027345274, 639.2616527910271, 11254.381009044417, 11565.421728913521, 11698.122640801974, 12090.455929483194, 12475.376231188404, 595.8510372406898, 601.1013534765553, 608.3186830749589, 614.1463365921372, 615.1971676961608, 5305.363356397257, 5329.440408757102, 5365.785368585291, 5378.569714422837, 5449.482299181572, 105565.0, 106397.0, 107384.0, 108574.0, 112179.0, 94423.52788236015, 95176.0, 101573.0, 102851.0, 106181.0, 87895.12104878997, 88352.0, 89011.0, 91629.0, 91865.54067229755, 10618.469076545987, 11918.0, 12023.519059237533, 12068.396580171131, 12447.502099916264, 40578.594214057484, 41854.37218441665, 42067.789661052724, 42164.0, 42734.786326069414, 70156.0, 73888.0, 74775.252247479, 75196.0, 75821.62089189519, 18511.074446277362, 18526.629467410847, 18882.71675924835, 19313.90343048276, 19445.0, 20180.192792288144, 20521.897390512953, 20761.273355432488, 20827.79172208319, 21119.683204752248, 18123.0, 18211.726824097914, 18235.270589176285, 18524.629507409507, 18571.81428185701, 18576.44270671882, 18869.245230191187, 18937.43187704371, 18939.526511837314, 19143.329983450512, 17624.206910689307, 17703.911480442515, 17766.911165444606, 17859.642807143526, 17879.28482860671, 13057.347132643139, 13583.456661733815, 13690.315484225948, 13879.0, 13930.79103813464, 13169.47322107105, 13595.252261125734, 13837.654058648613, 14282.07166534172, 14587.489437869624], "value": 9300.657835043461, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1542.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 108.0, 98.0, 110.0, 108.0, 109.0, 116.0, 108.0, 110.0, 111.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 117.0, 144.0, 184.0, 127.0, 143.0, 149.0, 130.0, 178.0, 117.0], "value": 143.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 161.0, 163.0, 170.0, 162.0, 166.0, 166.0, 172.0, 167.0, 164.0], "value": 165.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 75.0, 80.0, 164.0, 77.0, 76.0, 77.0, 75.0, 158.0, 76.0], "value": 94.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 85.0, 138.0, 79.0, 81.0, 82.0, 138.0, 79.0, 80.0, 137.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 114.0, 114.0, 113.0, 113.0, 114.0, 114.0, 115.0, 115.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 150.0, 155.0, 152.0, 152.0, 150.0, 150.0, 150.0, 149.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 96.0, 94.0, 94.0, 94.0, 98.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 54.0, 54.0, 55.0, 54.0, 57.0, 56.0, 54.0, 54.0, 58.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 64.0, 55.0, 56.0, 57.0, 55.0, 55.0, 55.0, 55.0, 62.0], "value": 56.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 80.0, 84.0, 83.0, 77.0, 78.0, 79.0, 77.0], "value": 78.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 128.0, 130.0, 125.0, 124.0, 121.0, 124.0, 137.0, 137.0, 162.0], "value": 132.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 176.0, 175.0, 187.0, 183.0, 184.0, 180.0, 174.0, 178.0, 184.0], "value": 179.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 68.0, 67.0, 69.0, 67.0, 71.0, 70.0, 70.0, 70.0, 67.0], "value": 69.6, "unit": "score"}]}]} 17:30:15 INFO - Return code: 0 17:30:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:30:15 INFO - # TBPL SUCCESS # 17:30:15 INFO - Running post-action listener: _package_coverage_data 17:30:15 INFO - Running post-action listener: _resource_record_post_action 17:30:15 INFO - [mozharness: 2017-08-13 00:30:15.435763Z] Finished run-tests step (success) 17:30:15 INFO - Running post-run listener: _resource_record_post_run 17:30:15 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 5443584; Write bytes: 441118720; Read time: 2272; Write time: 351104 17:30:15 INFO - TinderboxPrint: CPU usage
13.1% 17:30:15 INFO - TinderboxPrint: I/O read bytes / time
5,443,584 / 2,272 17:30:15 INFO - TinderboxPrint: I/O write bytes / time
441,118,720 / 351,104 17:30:15 INFO - TinderboxPrint: CPU idle
8,294.6 (86.8%) 17:30:15 INFO - TinderboxPrint: CPU user
1,202.9 (12.6%) 17:30:15 INFO - TinderboxPrint: Swap in / out
0 / 0 17:30:15 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 119529472; Read time: 0; Write time: 110528 17:30:15 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:30:15 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 2949120; Write bytes: 321589248; Read time: 1444; Write time: 240576 17:30:16 INFO - Running post-run listener: _upload_blobber_files 17:30:16 INFO - Blob upload gear active. 17:30:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:30:16 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 17:30:16 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'] 17:30:16 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 17:30:16 INFO - (blobuploader) - INFO - Open directory for files ... 17:30:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:30:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:30:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:17 INFO - (blobuploader) - INFO - Done attempting. 17:30:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:30:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:30:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:18 INFO - (blobuploader) - INFO - Done attempting. 17:30:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:30:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:30:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:20 INFO - (blobuploader) - INFO - Done attempting. 17:30:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:30:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:30:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:30:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:30:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:30:23 INFO - (blobuploader) - INFO - Done attempting. 17:30:23 INFO - (blobuploader) - INFO - Iteration through files over. 17:30:23 INFO - Return code: 0 17:30:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:30:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:30:23 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3712a8f73f5a34b0692c6d9e482fe8e7b8f7ab0c7374bdbc8afa8b5641dd9ebe656bf89f1745167861a5e8e711cdfec4d5a11bdb92fc69d14f243e9f83c2a7eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1307929c3ad93b08a1b0d925bf2d472ab8d6dece42dfa807b2496824eb202010bf2971cb9b56c037f306520e58905176a5bd228061dd59498133d269183cd0d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/053b2376e96ed4218286fd3812cb646efb73ae33494254cdaf57bd225a0bc5f8c11eff036872f981603887d76fe2241447682fbcd8e8a0c8d90963caf5892520", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e7d620560242ba90a32735a0b49d561b28161b2d05b3229ffd4c7928791879a1778dd3d7afea8ea8b068f7110d4f9280b29c72908cae2ec3a28f8a964347a20"} 17:30:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:30:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:30:23 INFO - Contents: 17:30:23 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3712a8f73f5a34b0692c6d9e482fe8e7b8f7ab0c7374bdbc8afa8b5641dd9ebe656bf89f1745167861a5e8e711cdfec4d5a11bdb92fc69d14f243e9f83c2a7eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1307929c3ad93b08a1b0d925bf2d472ab8d6dece42dfa807b2496824eb202010bf2971cb9b56c037f306520e58905176a5bd228061dd59498133d269183cd0d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/053b2376e96ed4218286fd3812cb646efb73ae33494254cdaf57bd225a0bc5f8c11eff036872f981603887d76fe2241447682fbcd8e8a0c8d90963caf5892520", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e7d620560242ba90a32735a0b49d561b28161b2d05b3229ffd4c7928791879a1778dd3d7afea8ea8b068f7110d4f9280b29c72908cae2ec3a28f8a964347a20"} 17:30:23 INFO - Running post-run listener: copy_logs_to_upload_dir 17:30:23 INFO - Copying logs to upload dir... 17:30:23 INFO - mkdir: /builds/slave/test/build/upload/logs 17:30:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.617050 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-08-12 17:30:23.013653) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-12 17:30:23.020985) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358 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/3712a8f73f5a34b0692c6d9e482fe8e7b8f7ab0c7374bdbc8afa8b5641dd9ebe656bf89f1745167861a5e8e711cdfec4d5a11bdb92fc69d14f243e9f83c2a7eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1307929c3ad93b08a1b0d925bf2d472ab8d6dece42dfa807b2496824eb202010bf2971cb9b56c037f306520e58905176a5bd228061dd59498133d269183cd0d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/053b2376e96ed4218286fd3812cb646efb73ae33494254cdaf57bd225a0bc5f8c11eff036872f981603887d76fe2241447682fbcd8e8a0c8d90963caf5892520", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e7d620560242ba90a32735a0b49d561b28161b2d05b3229ffd4c7928791879a1778dd3d7afea8ea8b068f7110d4f9280b29c72908cae2ec3a28f8a964347a20"} build_url:https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011172 build_url: 'https://queue.taskcluster.net/v1/task/CP2xHjBSS1Kh1OeG7_y_fA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3712a8f73f5a34b0692c6d9e482fe8e7b8f7ab0c7374bdbc8afa8b5641dd9ebe656bf89f1745167861a5e8e711cdfec4d5a11bdb92fc69d14f243e9f83c2a7eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1307929c3ad93b08a1b0d925bf2d472ab8d6dece42dfa807b2496824eb202010bf2971cb9b56c037f306520e58905176a5bd228061dd59498133d269183cd0d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/053b2376e96ed4218286fd3812cb646efb73ae33494254cdaf57bd225a0bc5f8c11eff036872f981603887d76fe2241447682fbcd8e8a0c8d90963caf5892520", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e7d620560242ba90a32735a0b49d561b28161b2d05b3229ffd4c7928791879a1778dd3d7afea8ea8b068f7110d4f9280b29c72908cae2ec3a28f8a964347a20"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-12 17:30:23.048559) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:30:23.048927) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1502578282.376289-2137942358 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004382 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 17:30:23.129265) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-12 17:30:23.132605) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-12 17:30:23.132900) ========= ========= Total master_lag: 0.24 =========