builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-048 starttime: 1502230284.38 results: success (0) revision: 40004a53d1d1a28b30f565ffc1329b1f28dfffd2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 15:11:24.382577) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 15:11:24.383043) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 15:11:24.383330) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 15:11:24.399793) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 15:11:24.400072) ========= 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=fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-08 15:11:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 33.5M=0s 2017-08-08 15:11:24 (33.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087051 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 15:11:24.518749) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 15:11:24.519066) ========= 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=fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018663 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 15:11:24.577248) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 15:11:24.580570) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 40004a53d1d1a28b30f565ffc1329b1f28dfffd2 --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 40004a53d1d1a28b30f565ffc1329b1f28dfffd2 --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=fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-08 15:11:24,758 truncating revision to first 12 chars 2017-08-08 15:11:24,758 Setting DEBUG logging. 2017-08-08 15:11:24,758 attempt 1/10 2017-08-08 15:11:24,758 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/40004a53d1d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-08 15:11:25,143 unpacking tar archive at: mozilla-central-40004a53d1d1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.698466 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 15:11:25.294709) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 15:11:25.295077) ========= script_repo_revision: 40004a53d1d1a28b30f565ffc1329b1f28dfffd2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 15:11:25.295471) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 15:11:25.295748) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 15:11:25.305339) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-08 15:11:25.305617) ========= /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=fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:11:25 INFO - MultiFileLogger online at 20170808 15:11:25 in /builds/slave/test 15:11:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:11:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:11:25 INFO - {'append_to_log': False, 15:11:25 INFO - 'base_work_dir': '/builds/slave/test', 15:11:25 INFO - 'blob_upload_branch': 'Firefox', 15:11:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:11:25 INFO - 'branch': 'Firefox', 15:11:25 INFO - 'buildbot_json_path': 'buildprops.json', 15:11:25 INFO - 'code_coverage': False, 15:11:25 INFO - 'config_files': ('talos/linux_config.py',), 15:11:25 INFO - 'default_actions': ('clobber', 15:11:25 INFO - 'read-buildbot-config', 15:11:25 INFO - 'download-and-extract', 15:11:25 INFO - 'populate-webroot', 15:11:25 INFO - 'create-virtualenv', 15:11:25 INFO - 'install', 15:11:25 INFO - 'setup-mitmproxy', 15:11:25 INFO - 'run-tests'), 15:11:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:11:25 INFO - 'disable_ccov_upload': False, 15:11:25 INFO - 'download_minidump_stackwalk': True, 15:11:25 INFO - 'download_symbols': 'ondemand', 15:11:25 INFO - 'enable_stylo': False, 15:11:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:11:25 INFO - 'tooltool.py': '/tools/tooltool.py', 15:11:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:11:25 INFO - '/tools/misc-python/virtualenv.py')}, 15:11:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:11:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:11:25 INFO - 'gecko_profile': False, 15:11:25 INFO - 'gecko_profile_interval': 0, 15:11:25 INFO - 'installer_path': 'installer.exe', 15:11:25 INFO - 'log_level': 'info', 15:11:25 INFO - 'log_name': 'talos', 15:11:25 INFO - 'log_to_console': True, 15:11:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:11:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:11:25 INFO - 'opt_config_files': (), 15:11:25 INFO - 'pip_index': False, 15:11:25 INFO - 'suite': 'dromaeojs-e10s', 15:11:25 INFO - 'system_bits': '32', 15:11:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:11:25 INFO - 'title': 'talos-linux64-ix-048', 15:11:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:11:25 INFO - 'use_talos_json': True, 15:11:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:11:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:11:25 INFO - 'work_dir': 'build'} 15:11:25 INFO - [mozharness: 2017-08-08 22:11:25.590590Z] Running clobber step. 15:11:25 INFO - Running pre-action listener: _resource_record_pre_action 15:11:25 INFO - Running main action method: clobber 15:11:25 INFO - rmtree: /builds/slave/test/build 15:11:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:11:27 INFO - Running post-action listener: _resource_record_post_action 15:11:27 INFO - [mozharness: 2017-08-08 22:11:27.119847Z] Finished clobber step (success) 15:11:27 INFO - [mozharness: 2017-08-08 22:11:27.119948Z] Running read-buildbot-config step. 15:11:27 INFO - Running pre-action listener: _resource_record_pre_action 15:11:27 INFO - Running main action method: read_buildbot_config 15:11:27 INFO - Using buildbot properties: 15:11:27 INFO - { 15:11:27 INFO - "project": "", 15:11:27 INFO - "product": "firefox", 15:11:27 INFO - "who": "kwierso@gmail.com", 15:11:27 INFO - "installer_path": "public/build/target.tar.bz2", 15:11:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:11:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:11:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 15:11:27 INFO - "stage_platform": "linux64-pgo", 15:11:27 INFO - "basedir": "/builds/slave/test", 15:11:27 INFO - "buildnumber": 104, 15:11:27 INFO - "platform": "ubuntu64_hw", 15:11:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:11:27 INFO - "slavebuilddir": "test", 15:11:27 INFO - "taskId": "QjIPg4MrS-C3qk9mV6cxFA", 15:11:27 INFO - "branch": "mozilla-central", 15:11:27 INFO - "script_repo_revision": "production", 15:11:27 INFO - "revision": "40004a53d1d1a28b30f565ffc1329b1f28dfffd2", 15:11:27 INFO - "slavename": "talos-linux64-ix-048", 15:11:27 INFO - "repo_path": "mozilla-central" 15:11:27 INFO - } 15:11:27 INFO - Finding installer, test and symbols from parent task. 15:11:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QjIPg4MrS-C3qk9mV6cxFA'}, attempt #1 15:11:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QjIPg4MrS-C3qk9mV6cxFA'}, attempt #1 15:11:28 INFO - Task dependencies: IZpbupGoTCKssKG-erB1Lg 15:11:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg'}, attempt #1 15:11:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.tar.bz2 15:11:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.test_packages.json 15:11:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.crashreporter-symbols.zip 15:11:28 INFO - Running post-action listener: _resource_record_post_action 15:11:28 INFO - [mozharness: 2017-08-08 22:11:28.517892Z] Finished read-buildbot-config step (success) 15:11:28 INFO - [mozharness: 2017-08-08 22:11:28.518113Z] Running download-and-extract step. 15:11:28 INFO - Running pre-action listener: _resource_record_pre_action 15:11:28 INFO - Running main action method: download_and_extract 15:11:28 INFO - mkdir: /builds/slave/test/build/tests 15:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:11:28 INFO - trying https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.test_packages.json 15:11:28 INFO - Downloading https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:11:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:11:29 INFO - Downloaded 989 bytes. 15:11:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:11:29 INFO - Using the following test package requirements: 15:11:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:11:29 INFO - u'common': [u'target.common.tests.zip'], 15:11:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:11:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:11:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:11:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:11:29 INFO - u'mozbase': [u'target.common.tests.zip'], 15:11:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:11:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:11:29 INFO - u'web-platform': [u'target.common.tests.zip', 15:11:29 INFO - u'target.web-platform.tests.tar.gz'], 15:11:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:11:29 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:11:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.common.tests.zip 15:11:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:11:29 INFO - Fetch https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.common.tests.zip into memory 15:11:30 INFO - Content-Length response header: 54885967 15:11:30 INFO - Bytes received: 54885967 15:11:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:11:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.common.tests.zip 15:11:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:11:41 INFO - Fetch https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.common.tests.zip into memory 15:11:42 INFO - Content-Length response header: 54885967 15:11:42 INFO - Bytes received: 54885967 15:11:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.talos.tests.zip 15:11:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:11:54 INFO - Fetch https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.talos.tests.zip into memory 15:11:55 INFO - Content-Length response header: 14496008 15:11:55 INFO - Bytes received: 14496008 15:11:55 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')]} 15:11:55 INFO - trying https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.tar.bz2 15:11:55 INFO - Downloading https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:11:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:11:57 INFO - Downloaded 62678224 bytes. 15:11:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.tar.bz2 15:11:57 INFO - mkdir: /builds/slave/test/properties 15:11:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:11:57 INFO - Writing to file /builds/slave/test/properties/build_url 15:11:57 INFO - Contents: 15:11:57 INFO - build_url:https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.tar.bz2 15:11:57 INFO - Running post-action listener: _resource_record_post_action 15:11:57 INFO - Running post-action listener: set_extra_try_arguments 15:11:57 INFO - [mozharness: 2017-08-08 22:11:57.660150Z] Finished download-and-extract step (success) 15:11:57 INFO - [mozharness: 2017-08-08 22:11:57.660248Z] Running populate-webroot step. 15:11:57 INFO - Running pre-action listener: _resource_record_pre_action 15:11:57 INFO - Running main action method: populate_webroot 15:11:57 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:11:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:11:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:11:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:11:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:11:57 INFO - 'tests': ['damp', 'tps']}, 15:11:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:11:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:11:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:11:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:11:57 INFO - 'other-e10s': {'tests': ['a11yr', 15:11:57 INFO - 'ts_paint', 15:11:57 INFO - 'tpaint', 15:11:57 INFO - 'sessionrestore', 15:11:57 INFO - 'sessionrestore_many_windows', 15:11:57 INFO - 'sessionrestore_no_auto_restore', 15:11:57 INFO - 'tabpaint']}, 15:11:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:11:57 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:11:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:11:57 INFO - 'tsvgr_opacity', 15:11:57 INFO - 'tart', 15:11:57 INFO - 'tscrollx', 15:11:57 INFO - 'tsvg_static']}, 15:11:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:11:57 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:11:57 INFO - 'talos_options': ['--mitmproxy', 15:11:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:11:57 INFO - '--firstNonBlankPaint'], 15:11:57 INFO - 'tests': ['tp6_google', 15:11:57 INFO - 'tp6_youtube', 15:11:57 INFO - 'tp6_amazon', 15:11:57 INFO - 'tp6_facebook']}, 15:11:57 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:11:57 INFO - 'talos_options': ['--stylo', 15:11:57 INFO - '--mitmproxy', 15:11:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:11:57 INFO - '--firstNonBlankPaint'], 15:11:57 INFO - 'tests': ['tp6_google', 15:11:57 INFO - 'tp6_youtube', 15:11:57 INFO - 'tp6_amazon', 15:11:57 INFO - 'tp6_facebook']}, 15:11:57 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:11:57 INFO - 'talos_options': ['--stylo', 15:11:57 INFO - '--stylo-threads=1', 15:11:57 INFO - '--mitmproxy', 15:11:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:11:57 INFO - '--firstNonBlankPaint'], 15:11:57 INFO - 'tests': ['tp6_google', 15:11:57 INFO - 'tp6_youtube', 15:11:57 INFO - 'tp6_amazon', 15:11:57 INFO - 'tp6_facebook']}, 15:11:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:11:57 INFO - 'talos_options': ['--xperf_path', 15:11:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:11:57 INFO - 'tests': ['tp5n']}}} 15:11:57 INFO - Running post-action listener: _resource_record_post_action 15:11:57 INFO - [mozharness: 2017-08-08 22:11:57.669928Z] Finished populate-webroot step (success) 15:11:57 INFO - [mozharness: 2017-08-08 22:11:57.670032Z] Running create-virtualenv step. 15:11:57 INFO - Running pre-action listener: _resource_record_pre_action 15:11:57 INFO - Running main action method: create_virtualenv 15:11:57 INFO - Creating virtualenv /builds/slave/test/build/venv 15:11:57 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 15:11:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:11:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:11:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:11:57 INFO - Using real prefix '/usr' 15:11:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:11:58 INFO - Installing distribute.............................................................................................................................................................................................done. 15:12:00 INFO - Installing pip.................done. 15:12:00 INFO - Return code: 0 15:12:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:12:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:12:00 INFO - Reading from file tmpfile_stdout 15:12:00 INFO - Output received: 15:12:00 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) 15:12:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:00 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 0x1ab71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c83a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e26d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23eb0>, '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 0x1e24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e25240>, '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': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', '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 15:12:00 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 15:12:00 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 15:12:00 INFO - Using env: {'DISPLAY': ':0', 15:12:00 INFO - 'HOME': '/home/cltbld', 15:12:00 INFO - 'LANG': 'en_US.UTF-8', 15:12:00 INFO - 'LANGUAGE': 'en_US:en', 15:12:00 INFO - 'LOGNAME': 'cltbld', 15:12:00 INFO - 'MAIL': '/var/mail/cltbld', 15:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:00 INFO - 'MOZ_NO_REMOTE': '1', 15:12:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:00 INFO - 'NO_EM_RESTART': '1', 15:12:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:00 INFO - 'PWD': '/builds/slave/test', 15:12:00 INFO - 'SHELL': '/bin/bash', 15:12:00 INFO - 'SHLVL': '1', 15:12:00 INFO - 'TERM': 'linux', 15:12:00 INFO - 'TMOUT': '86400', 15:12:00 INFO - 'USER': 'cltbld', 15:12:00 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', 15:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:00 INFO - '_': '/tools/buildbot/bin/python'} 15:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:00 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 15:12:00 INFO - Cleaning up... 15:12:00 INFO - Return code: 0 15:12:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:00 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 0x1ab71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c83a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e26d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23eb0>, '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 0x1e24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e25240>, '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': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', '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 15:12:00 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 15:12:00 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 15:12:00 INFO - Using env: {'DISPLAY': ':0', 15:12:00 INFO - 'HOME': '/home/cltbld', 15:12:00 INFO - 'LANG': 'en_US.UTF-8', 15:12:00 INFO - 'LANGUAGE': 'en_US:en', 15:12:00 INFO - 'LOGNAME': 'cltbld', 15:12:00 INFO - 'MAIL': '/var/mail/cltbld', 15:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:00 INFO - 'MOZ_NO_REMOTE': '1', 15:12:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:00 INFO - 'NO_EM_RESTART': '1', 15:12:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:00 INFO - 'PWD': '/builds/slave/test', 15:12:00 INFO - 'SHELL': '/bin/bash', 15:12:00 INFO - 'SHLVL': '1', 15:12:00 INFO - 'TERM': 'linux', 15:12:00 INFO - 'TMOUT': '86400', 15:12:00 INFO - 'USER': 'cltbld', 15:12:00 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', 15:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:00 INFO - '_': '/tools/buildbot/bin/python'} 15:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:00 INFO - Downloading/unpacking psutil>=3.1.1 15:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:12:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:12:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:12:02 INFO - Installing collected packages: psutil 15:12:02 INFO - Running setup.py install for psutil 15:12:02 INFO - building 'psutil._psutil_linux' extension 15:12:02 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 15:12:02 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 15:12:02 INFO - building 'psutil._psutil_posix' extension 15:12:02 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 15:12:02 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 15:12:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:12:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:12:02 INFO - Successfully installed psutil 15:12:02 INFO - Cleaning up... 15:12:02 INFO - Return code: 0 15:12:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c83a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e26d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23eb0>, '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 0x1e24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e25240>, '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': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', '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 15:12:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:12:02 INFO - Using env: {'DISPLAY': ':0', 15:12:02 INFO - 'HOME': '/home/cltbld', 15:12:02 INFO - 'LANG': 'en_US.UTF-8', 15:12:02 INFO - 'LANGUAGE': 'en_US:en', 15:12:02 INFO - 'LOGNAME': 'cltbld', 15:12:02 INFO - 'MAIL': '/var/mail/cltbld', 15:12:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:02 INFO - 'MOZ_NO_REMOTE': '1', 15:12:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:02 INFO - 'NO_EM_RESTART': '1', 15:12:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:02 INFO - 'PWD': '/builds/slave/test', 15:12:02 INFO - 'SHELL': '/bin/bash', 15:12:02 INFO - 'SHLVL': '1', 15:12:02 INFO - 'TERM': 'linux', 15:12:02 INFO - 'TMOUT': '86400', 15:12:02 INFO - 'USER': 'cltbld', 15:12:02 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', 15:12:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:02 INFO - '_': '/tools/buildbot/bin/python'} 15:12:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:12: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 15:12: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 15:12: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 15:12: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 15:12:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:12:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:12:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:12:04 INFO - Installing collected packages: mozsystemmonitor 15:12:04 INFO - Running setup.py install for mozsystemmonitor 15:12:04 INFO - Successfully installed mozsystemmonitor 15:12:04 INFO - Cleaning up... 15:12:04 INFO - Return code: 0 15:12:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:12:04 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')]} 15:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:04 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')]} 15:12:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:04 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 0x1ab71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c83a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e26d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23eb0>, '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 0x1e24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e25240>, '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': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', '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 15:12:04 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 15:12:04 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 15:12:04 INFO - Using env: {'DISPLAY': ':0', 15:12:04 INFO - 'HOME': '/home/cltbld', 15:12:04 INFO - 'LANG': 'en_US.UTF-8', 15:12:04 INFO - 'LANGUAGE': 'en_US:en', 15:12:04 INFO - 'LOGNAME': 'cltbld', 15:12:04 INFO - 'MAIL': '/var/mail/cltbld', 15:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:04 INFO - 'MOZ_NO_REMOTE': '1', 15:12:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:04 INFO - 'NO_EM_RESTART': '1', 15:12:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:04 INFO - 'PWD': '/builds/slave/test', 15:12:04 INFO - 'SHELL': '/bin/bash', 15:12:04 INFO - 'SHLVL': '1', 15:12:04 INFO - 'TERM': 'linux', 15:12:04 INFO - 'TMOUT': '86400', 15:12:04 INFO - 'USER': 'cltbld', 15:12:04 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', 15:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:04 INFO - '_': '/tools/buildbot/bin/python'} 15:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:04 INFO - Downloading/unpacking jsonschema==2.5.1 15:12: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 15:12: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 15:12: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 15:12: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 15:12:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:12:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:12: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 15:12: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 15:12: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 15:12: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 15:12:05 INFO - Downloading functools32-3.2.3-2.tar.gz 15:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:12:05 INFO - warning: no files found matching '*.txt' 15:12:05 INFO - no previously-included directories found matching 'build' 15:12:05 INFO - no previously-included directories found matching 'dist' 15:12:05 INFO - no previously-included directories found matching '.git*' 15:12:05 INFO - Installing collected packages: jsonschema, functools32 15:12:05 INFO - Running setup.py install for functools32 15:12:05 INFO - warning: no files found matching '*.txt' 15:12:05 INFO - no previously-included directories found matching 'build' 15:12:05 INFO - no previously-included directories found matching 'dist' 15:12:05 INFO - no previously-included directories found matching '.git*' 15:12:05 INFO - Successfully installed jsonschema functools32 15:12:05 INFO - Cleaning up... 15:12:05 INFO - Return code: 0 15:12:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:12: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')]} 15:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12: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')]} 15:12:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12: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 0x1ab71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c83a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e26d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23eb0>, '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 0x1e24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e25240>, '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': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', '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 15:12: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 15:12: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 15:12:05 INFO - Using env: {'DISPLAY': ':0', 15:12:05 INFO - 'HOME': '/home/cltbld', 15:12:05 INFO - 'LANG': 'en_US.UTF-8', 15:12:05 INFO - 'LANGUAGE': 'en_US:en', 15:12:05 INFO - 'LOGNAME': 'cltbld', 15:12:05 INFO - 'MAIL': '/var/mail/cltbld', 15:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:05 INFO - 'MOZ_NO_REMOTE': '1', 15:12:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:05 INFO - 'NO_EM_RESTART': '1', 15:12:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:05 INFO - 'PWD': '/builds/slave/test', 15:12:05 INFO - 'SHELL': '/bin/bash', 15:12:05 INFO - 'SHLVL': '1', 15:12:05 INFO - 'TERM': 'linux', 15:12:05 INFO - 'TMOUT': '86400', 15:12:05 INFO - 'USER': 'cltbld', 15:12:05 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', 15:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:05 INFO - '_': '/tools/buildbot/bin/python'} 15:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:12:06 INFO - Cleaning up... 15:12:06 INFO - Return code: 0 15:12:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:12:06 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')]} 15:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:06 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')]} 15:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:06 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 0x1ab71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c83a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e26d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23eb0>, '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 0x1e24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e25240>, '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': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', '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 15:12:06 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 15:12:06 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 15:12:06 INFO - Using env: {'DISPLAY': ':0', 15:12:06 INFO - 'HOME': '/home/cltbld', 15:12:06 INFO - 'LANG': 'en_US.UTF-8', 15:12:06 INFO - 'LANGUAGE': 'en_US:en', 15:12:06 INFO - 'LOGNAME': 'cltbld', 15:12:06 INFO - 'MAIL': '/var/mail/cltbld', 15:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:06 INFO - 'MOZ_NO_REMOTE': '1', 15:12:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:06 INFO - 'NO_EM_RESTART': '1', 15:12:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:06 INFO - 'PWD': '/builds/slave/test', 15:12:06 INFO - 'SHELL': '/bin/bash', 15:12:06 INFO - 'SHLVL': '1', 15:12:06 INFO - 'TERM': 'linux', 15:12:06 INFO - 'TMOUT': '86400', 15:12:06 INFO - 'USER': 'cltbld', 15:12:06 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', 15:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:06 INFO - '_': '/tools/buildbot/bin/python'} 15:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:06 INFO - Downloading/unpacking blobuploader==1.2.4 15:12: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 15:12: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 15:12: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 15:12: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 15:12:08 INFO - Downloading blobuploader-1.2.4.tar.gz 15:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:12:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:12:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:08 INFO - Downloading docopt-0.6.1.tar.gz 15:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:12:09 INFO - Installing collected packages: blobuploader, requests, docopt 15:12:09 INFO - Running setup.py install for blobuploader 15:12:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:12:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:12:09 INFO - Running setup.py install for requests 15:12:09 INFO - Running setup.py install for docopt 15:12:09 INFO - Successfully installed blobuploader requests docopt 15:12:09 INFO - Cleaning up... 15:12:09 INFO - Return code: 0 15:12:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:12:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:09 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 0x1ab71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c83a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e26d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23eb0>, '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 0x1e24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e25240>, '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': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', '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 15:12:09 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 15:12:09 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 15:12:09 INFO - Using env: {'DISPLAY': ':0', 15:12:09 INFO - 'HOME': '/home/cltbld', 15:12:09 INFO - 'LANG': 'en_US.UTF-8', 15:12:09 INFO - 'LANGUAGE': 'en_US:en', 15:12:09 INFO - 'LOGNAME': 'cltbld', 15:12:09 INFO - 'MAIL': '/var/mail/cltbld', 15:12:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:09 INFO - 'MOZ_NO_REMOTE': '1', 15:12:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:09 INFO - 'NO_EM_RESTART': '1', 15:12:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:09 INFO - 'PWD': '/builds/slave/test', 15:12:09 INFO - 'SHELL': '/bin/bash', 15:12:09 INFO - 'SHLVL': '1', 15:12:09 INFO - 'TERM': 'linux', 15:12:09 INFO - 'TMOUT': '86400', 15:12:09 INFO - 'USER': 'cltbld', 15:12:09 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', 15:12:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:09 INFO - '_': '/tools/buildbot/bin/python'} 15:12:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:12:09 INFO - Running setup.py (path:/tmp/pip-t_iHDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:12:09 INFO - Running setup.py (path:/tmp/pip-AUME6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:12:09 INFO - Running setup.py (path:/tmp/pip-FLDrXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:12:10 INFO - Running setup.py (path:/tmp/pip-VNuAmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:12:10 INFO - Running setup.py (path:/tmp/pip-o798xk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:12:10 INFO - Running setup.py (path:/tmp/pip-ljTtTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:12:10 INFO - Running setup.py (path:/tmp/pip-qZFdYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:12:10 INFO - Running setup.py (path:/tmp/pip-sPf9f3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:12:10 INFO - Running setup.py (path:/tmp/pip-BrVFDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:12:10 INFO - Running setup.py (path:/tmp/pip-4rJg_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:12:10 INFO - Running setup.py (path:/tmp/pip-Y_cbX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:12:10 INFO - Running setup.py (path:/tmp/pip-jT954f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:12:10 INFO - Running setup.py (path:/tmp/pip-tmFAdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:12:10 INFO - Running setup.py (path:/tmp/pip-_vVOCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:10 INFO - Running setup.py (path:/tmp/pip-vW8gVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:12:10 INFO - Running setup.py (path:/tmp/pip-LThzfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:12:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:12:10 INFO - Running setup.py (path:/tmp/pip-4JeZ7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:12:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:12:11 INFO - Running setup.py install for manifestparser 15:12:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:12:11 INFO - Running setup.py install for mozcrash 15:12:11 INFO - Running setup.py install for mozdebug 15:12:11 INFO - Running setup.py install for mozdevice 15:12:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:12:11 INFO - Running setup.py install for mozfile 15:12:11 INFO - Running setup.py install for mozhttpd 15:12:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:12:11 INFO - Running setup.py install for mozinfo 15:12:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:12:11 INFO - Running setup.py install for mozInstall 15:12:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:12:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:12:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:12:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:12:11 INFO - Running setup.py install for mozleak 15:12:11 INFO - Running setup.py install for mozlog 15:12:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:12:12 INFO - Running setup.py install for moznetwork 15:12:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:12:12 INFO - Running setup.py install for mozprocess 15:12:12 INFO - Running setup.py install for mozprofile 15:12:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:12:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:12:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:12:12 INFO - Running setup.py install for mozrunner 15:12:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:12:12 INFO - Running setup.py install for mozscreenshot 15:12:12 INFO - Running setup.py install for moztest 15:12:12 INFO - Running setup.py install for mozversion 15:12:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:12:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:12:12 INFO - Cleaning up... 15:12:12 INFO - Return code: 0 15:12:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:12 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 0x1ab71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c83a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e26d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23eb0>, '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 0x1e24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e25240>, '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': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', '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 15:12:12 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 15:12:12 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 15:12:12 INFO - Using env: {'DISPLAY': ':0', 15:12:12 INFO - 'HOME': '/home/cltbld', 15:12:12 INFO - 'LANG': 'en_US.UTF-8', 15:12:12 INFO - 'LANGUAGE': 'en_US:en', 15:12:12 INFO - 'LOGNAME': 'cltbld', 15:12:12 INFO - 'MAIL': '/var/mail/cltbld', 15:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:12 INFO - 'MOZ_NO_REMOTE': '1', 15:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:12 INFO - 'NO_EM_RESTART': '1', 15:12:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:12 INFO - 'PWD': '/builds/slave/test', 15:12:12 INFO - 'SHELL': '/bin/bash', 15:12:12 INFO - 'SHLVL': '1', 15:12:12 INFO - 'TERM': 'linux', 15:12:12 INFO - 'TMOUT': '86400', 15:12:12 INFO - 'USER': 'cltbld', 15:12:12 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', 15:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:12 INFO - '_': '/tools/buildbot/bin/python'} 15:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:12:13 INFO - Running setup.py (path:/tmp/pip-pZMfDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:12:13 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)) 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:12:13 INFO - Running setup.py (path:/tmp/pip-Q5TVoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:12:13 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)) 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:12:13 INFO - Running setup.py (path:/tmp/pip-mS2MAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:12:13 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)) 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:12:13 INFO - Running setup.py (path:/tmp/pip-XdJSzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:12:13 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)) 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:12:13 INFO - Running setup.py (path:/tmp/pip-Z4HJR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:12:13 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)) 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:12:13 INFO - Running setup.py (path:/tmp/pip-0yGOAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:12:13 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)) 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:12:13 INFO - Running setup.py (path:/tmp/pip-fXf7Y7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:12:13 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)) 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:12:13 INFO - Running setup.py (path:/tmp/pip-M1VYeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:12:13 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)) 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:12:13 INFO - Running setup.py (path:/tmp/pip-oPOI_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:12:13 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)) 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:12:13 INFO - Running setup.py (path:/tmp/pip-ICdSZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:12:13 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)) 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:12:13 INFO - Running setup.py (path:/tmp/pip-8oCYvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:12:13 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)) 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:12:13 INFO - Running setup.py (path:/tmp/pip-UFjBCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:12:13 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)) 15:12:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:12:13 INFO - Running setup.py (path:/tmp/pip-JKAa4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:12:14 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)) 15:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:12:14 INFO - Running setup.py (path:/tmp/pip-AKP24_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:12:14 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)) 15:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:14 INFO - Running setup.py (path:/tmp/pip-XtGUlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:12:14 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)) 15:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:12:14 INFO - Running setup.py (path:/tmp/pip-rGYztg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:12:14 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)) 15:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:12:14 INFO - Running setup.py (path:/tmp/pip-czSXtp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:12:14 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)) 15:12:14 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)) 15:12:14 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)) 15:12:14 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)) 15:12:14 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)) 15:12:14 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)) 15:12:14 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)) 15:12:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:15 INFO - Downloading blessings-1.6.tar.gz 15:12:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:12:15 INFO - Installing collected packages: blessings 15:12:15 INFO - Running setup.py install for blessings 15:12:15 INFO - Successfully installed blessings 15:12:15 INFO - Cleaning up... 15:12:15 INFO - Return code: 0 15:12:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:12:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:12:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:12:15 INFO - Reading from file tmpfile_stdout 15:12:15 INFO - Current package versions: 15:12:15 INFO - argparse == 1.2.1 15:12:15 INFO - blessings == 1.6 15:12:15 INFO - blobuploader == 1.2.4 15:12:15 INFO - docopt == 0.6.1 15:12:15 INFO - functools32 == 3.2.3-2 15:12:15 INFO - jsonschema == 2.5.1 15:12:15 INFO - manifestparser == 1.1 15:12:15 INFO - mozInstall == 1.13 15:12:15 INFO - mozcrash == 1.0 15:12:15 INFO - mozdebug == 0.1 15:12:15 INFO - mozdevice == 0.51 15:12:15 INFO - mozfile == 1.2 15:12:15 INFO - mozhttpd == 0.7 15:12:15 INFO - mozinfo == 0.10 15:12:15 INFO - mozleak == 0.1 15:12:15 INFO - mozlog == 3.5 15:12:15 INFO - moznetwork == 0.27 15:12:15 INFO - mozprocess == 0.25 15:12:15 INFO - mozprofile == 0.28 15:12:15 INFO - mozrunner == 6.13 15:12:15 INFO - mozscreenshot == 0.1 15:12:15 INFO - mozsystemmonitor == 0.3 15:12:15 INFO - moztest == 0.8 15:12:15 INFO - mozversion == 1.4 15:12:15 INFO - psutil == 3.1.1 15:12:15 INFO - requests == 1.2.3 15:12:15 INFO - wsgiref == 0.1.2 15:12:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:12:15 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')]} 15:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:15 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')]} 15:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:15 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 0x1ab71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c83a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e26d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23eb0>, '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 0x1e24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e25240>, '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': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', '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 15:12:15 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 15:12:15 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 15:12:15 INFO - Using env: {'DISPLAY': ':0', 15:12:15 INFO - 'HOME': '/home/cltbld', 15:12:15 INFO - 'LANG': 'en_US.UTF-8', 15:12:15 INFO - 'LANGUAGE': 'en_US:en', 15:12:15 INFO - 'LOGNAME': 'cltbld', 15:12:15 INFO - 'MAIL': '/var/mail/cltbld', 15:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:15 INFO - 'MOZ_NO_REMOTE': '1', 15:12:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:15 INFO - 'NO_EM_RESTART': '1', 15:12:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:15 INFO - 'PWD': '/builds/slave/test', 15:12:15 INFO - 'SHELL': '/bin/bash', 15:12:15 INFO - 'SHLVL': '1', 15:12:15 INFO - 'TERM': 'linux', 15:12:15 INFO - 'TMOUT': '86400', 15:12:15 INFO - 'USER': 'cltbld', 15:12:15 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', 15:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:15 INFO - '_': '/tools/buildbot/bin/python'} 15:12:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:16 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)) 15:12:16 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)) 15:12:16 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)) 15:12:16 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)) 15:12:16 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)) 15:12:16 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)) 15:12:16 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)) 15:12:16 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)) 15:12:16 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)) 15:12:16 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:12:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:12:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:12:17 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)) 15:12:17 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)) 15:12:17 INFO - Installing collected packages: simplejson 15:12:17 INFO - Running setup.py install for simplejson 15:12:17 INFO - building 'simplejson._speedups' extension 15:12:17 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 15:12:18 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 15:12:18 INFO - Successfully installed simplejson 15:12:18 INFO - Cleaning up... 15:12:18 INFO - Return code: 0 15:12:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:12:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:12:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:12:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:12:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ab71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c83a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e26d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e23eb0>, '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 0x1e24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e25240>, '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': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', '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 15:12:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:12:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:12:18 INFO - Using env: {'DISPLAY': ':0', 15:12:18 INFO - 'HOME': '/home/cltbld', 15:12:18 INFO - 'LANG': 'en_US.UTF-8', 15:12:18 INFO - 'LANGUAGE': 'en_US:en', 15:12:18 INFO - 'LOGNAME': 'cltbld', 15:12:18 INFO - 'MAIL': '/var/mail/cltbld', 15:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:18 INFO - 'MOZ_NO_REMOTE': '1', 15:12:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:18 INFO - 'NO_EM_RESTART': '1', 15:12:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:18 INFO - 'PWD': '/builds/slave/test', 15:12:18 INFO - 'SHELL': '/bin/bash', 15:12:18 INFO - 'SHLVL': '1', 15:12:18 INFO - 'TERM': 'linux', 15:12:18 INFO - 'TMOUT': '86400', 15:12:18 INFO - 'USER': 'cltbld', 15:12:18 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', 15:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:18 INFO - '_': '/tools/buildbot/bin/python'} 15:12:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:12:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:12:18 INFO - Cleaning up... 15:12:18 INFO - Return code: 0 15:12:18 INFO - Running post-action listener: _resource_record_post_action 15:12:18 INFO - Running post-action listener: _start_resource_monitoring 15:12:18 INFO - Starting resource monitoring. 15:12:18 INFO - [mozharness: 2017-08-08 22:12:18.570133Z] Finished create-virtualenv step (success) 15:12:18 INFO - [mozharness: 2017-08-08 22:12:18.570605Z] Running install step. 15:12:18 INFO - Running pre-action listener: _resource_record_pre_action 15:12:18 INFO - Running main action method: install 15:12:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:12:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:12:18 INFO - Reading from file tmpfile_stdout 15:12:18 INFO - Detecting whether we're running mozinstall >=1.0... 15:12:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:12:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:12:18 INFO - Reading from file tmpfile_stdout 15:12:18 INFO - Output received: 15:12:18 INFO - Usage: mozinstall [options] installer 15:12:18 INFO - Options: 15:12:18 INFO - -h, --help show this help message and exit 15:12:18 INFO - -d DEST, --destination=DEST 15:12:18 INFO - Directory to install application into. [default: 15:12:18 INFO - "/builds/slave/test"] 15:12:18 INFO - --app=APP Application being installed. [default: firefox] 15:12:18 INFO - mkdir: /builds/slave/test/build/application 15:12:18 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'] 15:12:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:12:36 INFO - Reading from file tmpfile_stdout 15:12:36 INFO - Output received: 15:12:36 INFO - /builds/slave/test/build/application/firefox/firefox 15:12:36 INFO - Running post-action listener: _resource_record_post_action 15:12:36 INFO - [mozharness: 2017-08-08 22:12:36.691104Z] Finished install step (success) 15:12:36 INFO - [mozharness: 2017-08-08 22:12:36.691385Z] Running setup-mitmproxy step. 15:12:36 INFO - Running pre-action listener: _resource_record_pre_action 15:12:36 INFO - Running main action method: setup_mitmproxy 15:12:36 INFO - Skipping: mitmproxy is not required 15:12:36 INFO - Running post-action listener: _resource_record_post_action 15:12:36 INFO - [mozharness: 2017-08-08 22:12:36.692530Z] Finished setup-mitmproxy step (success) 15:12:36 INFO - [mozharness: 2017-08-08 22:12:36.692753Z] Running run-tests step. 15:12:36 INFO - Running pre-action listener: _resource_record_pre_action 15:12:36 INFO - Running pre-action listener: _set_gcov_prefix 15:12:36 INFO - Running main action method: run_tests 15:12:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:12:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:12:36 INFO - Python 2.7.3 15:12:36 INFO - Return code: 0 15:12:36 INFO - grabbing minidump binary from tooltool 15:12:36 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')]} 15:12:36 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 0x1e23eb0>, '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 0x1e24db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e25240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:12:36 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 15:12:36 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 15:12:36 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 15:12:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:12:36 INFO - Return code: 0 15:12:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:12:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:12:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:12:36 INFO - ENV: RUST_BACKTRACE is now 1 15:12:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:12:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:12:36 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/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 15:12:36 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/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-048 --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 15:12:36 INFO - Using env: {'DISPLAY': ':0', 15:12:36 INFO - 'HOME': '/home/cltbld', 15:12:36 INFO - 'LANG': 'en_US.UTF-8', 15:12:36 INFO - 'LANGUAGE': 'en_US:en', 15:12:36 INFO - 'LOGNAME': 'cltbld', 15:12:36 INFO - 'MAIL': '/var/mail/cltbld', 15:12:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:12:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:12:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:12:36 INFO - 'MOZ_NO_REMOTE': '1', 15:12:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:12:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:12:36 INFO - 'NO_EM_RESTART': '1', 15:12:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:12:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:12:36 INFO - 'PWD': '/builds/slave/test', 15:12:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:12:36 INFO - 'RUST_BACKTRACE': '1', 15:12:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:12:36 INFO - 'SHELL': '/bin/bash', 15:12:36 INFO - 'SHLVL': '1', 15:12:36 INFO - 'TERM': 'linux', 15:12:36 INFO - 'TMOUT': '86400', 15:12:36 INFO - 'USER': 'cltbld', 15:12:36 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195', 15:12:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:12:36 INFO - '_': '/tools/buildbot/bin/python'} 15:12:36 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/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 15:12:37 INFO - mozversion application_buildid: 20170808210507 15:12:37 INFO - mozversion application_changeset: 40004a53d1d1a28b30f565ffc1329b1f28dfffd2 15:12:37 INFO - mozversion application_display_name: Nightly 15:12:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:12:37 INFO - mozversion application_name: Firefox 15:12:37 INFO - mozversion application_remotingname: firefox 15:12:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:12:37 INFO - mozversion application_vendor: Mozilla 15:12:37 INFO - mozversion application_version: 57.0a1 15:12:37 INFO - mozversion platform_buildid: 20170808210507 15:12:37 INFO - mozversion platform_changeset: 40004a53d1d1a28b30f565ffc1329b1f28dfffd2 15:12:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:12:37 INFO - mozversion platform_version: 57.0a1 15:12:37 INFO - using testdate: 1502230357 15:12:37 INFO - actual date: 1502230357 15:12:37 INFO - starting webserver on 'localhost:57961' 15:12:37 INFO - SUITE-START | Running 2 tests 15:12:37 INFO - TEST-START | dromaeo_css 15:12:37 INFO - Initialising browser for dromaeo_css test... 15:12:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57961/getInfo.html -profile /tmp/tmpap3KXV/profile 15:12:37 INFO - TEST-INFO | started process 23975 (/builds/slave/test/build/application/firefox/firefox http://localhost:57961/getInfo.html) 15:12:43 INFO - TEST-INFO | 23975: exit 0 15:12:43 INFO - Browser initialized. 15:12:43 INFO - Running cycle 1/1 for dromaeo_css test... 15:12:43 INFO - TEST-INFO | started process 24187 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpap3KXV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:12:44 INFO - PID 24187 | 15:12:44 INFO - PID 24187 | (/builds/slave/test/build/application/firefox/firefox:24237): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:12:44 INFO - PID 24187 | 15:12:54 INFO - PID 24187 | RSS: Main: 164921344 15:12:54 INFO - PID 24187 | 15:15:53 INFO - PID 24187 | Cycle 1(1): loaded http://localhost:57961/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57961/tests/dromaeo/cssquery-ext.html) 15:15:54 INFO - PID 24187 | RSS: Main: 166846464 15:15:54 INFO - PID 24187 | 15:18:55 INFO - PID 24187 | Cycle 1(1): loaded http://localhost:57961/tests/dromaeo/cssquery-ext.html (next: http://localhost:57961/tests/dromaeo/cssquery-jquery.html) 15:18:56 INFO - PID 24187 | RSS: Main: 164335616 15:18:56 INFO - PID 24187 | 15:21:57 INFO - PID 24187 | Cycle 1(1): loaded http://localhost:57961/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57961/tests/dromaeo/cssquery-mootools.html) 15:21:58 INFO - PID 24187 | RSS: Main: 166199296 15:21:58 INFO - PID 24187 | 15:25:00 INFO - PID 24187 | Cycle 1(1): loaded http://localhost:57961/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57961/tests/dromaeo/cssquery-prototype.html) 15:25:00 INFO - PID 24187 | RSS: Main: 164737024 15:25:00 INFO - PID 24187 | 15:28:04 INFO - PID 24187 | Cycle 1(1): loaded http://localhost:57961/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57961/tests/dromaeo/cssquery-yui.html) 15:28:04 INFO - PID 24187 | RSS: Main: 167923712 15:28:04 INFO - PID 24187 | 15:31:06 INFO - PID 24187 | Cycle 1(1): loaded http://localhost:57961/tests/dromaeo/cssquery-yui.html (next: http://localhost:57961/tests/dromaeo/cssquery-dojo.html) 15:31:06 INFO - PID 24187 | RSS: Main: 168460288 15:31:06 INFO - PID 24187 | 15:31:06 INFO - PID 24187 | __start_tp_report 15:31:06 INFO - PID 24187 | _x_x_mozilla_page_load 15:31:06 INFO - PID 24187 | _x_x_mozilla_page_load_details 15:31:06 INFO - PID 24187 | |i|pagename|runs| 15:31:06 INFO - PID 24187 | |0;dojo.html;3391.4743214801697;3475.131217195701;3504.334176506464;3516.367053930293;3573.7498375113737;6174.536909731772;6257.8122656320265;6267.153934218879;6275.121482992385;6305.337939516354;6049.939500605004;6152.5693201475915;6158.261008678954;6185.6597887116195;6207.565470417073;5397.649152805075;5478.178273259004;5490.176473528963;5540.113581826909;5569.554435645138;5469.699166545838;5547.44525547444;5550.944490555084;5577.637453565526;5604.495595396414;5182.974085129588;5312.335958005249;5388.407275199725;5406.02691515527;5614.719264036782;5441.646292990982;5479.808206712746;5518.696471694056;5553.639013464152;5557;5328.6802791832615;5450.04624190765;5489.258950041733;5495.972520137374;5532.861678458071;6105.206323178003;6131.754729810802;6154.169187159721;6183.536234782365;6235;6723.899141512832;6790.864182716367;6799.456043516507;6804.9319506804795;6817.113775209191;6827.146606674166;6838.760643377507;6894.310568943116;6898.275681053467;6912.896306555405;6121.234845644294;6161.322254551996;6197.504199664016;6212.06818977157;6305.8108256752375;6821.829454263584;6846.349596788297;6865.347791959756;6924.584524928419;7015.719371225196;6611.570247933869;6645.468362530986;6702.932970670328;6707.5975441473665;6710.396064354231;6758.797236082925;6805.285445028299;6819.897701534481;6875.3124687531845;6886;6478.319776423502;6635.369639884299;6721.22705888829;6788.422984046312;6800.591964482147;6695.096162018162;6752.5948443093575;6770.187577490733;6774.356436138559;6776.69504872282;4716.95283047172;4760.952390476051;4762.404699412574;4858.271259311132;4880.487548807395;4337.804798784062;4353.717008394444;4378.1900348435;4399.802008909607;4419.40338054365;6319.842003949846;6359.332270111571;6387.297397286295;6390.041493775878;6390.265119511223;6729.293424190488;6741.8651626967185;6809.489288303397;6814.659267036628;6824.829379265558;6168.8766224675255;6198.977168767193;6261.843453913689;6265.71804268809;6426;5345.1447768356875;5359.383670877821;5379.058664733656;5396.325459317585;5463.69949652765;6913.688884000232;7011.649417529103;7014.894776578355;7016.7544135954995;7023.122109736283;6650.3682150195655;6700.430463410665;6706.631135287606;6719.731210751515;6733.562318449285;6961.164660240601;6961.582305061713;7047.189573199148;7048.118985126859;7060.682269297997;6585.242697089896;6588.637624930483;6666.600003999775;6668.466522678271;6738.696758645775;6794.388505034571;6804.625745583842;6812.284710105368;6870.656467176521;6885.8967115492305;6600.80197594073;6649.46804255668;6678.632675202911;6688.163979502562;6689.464842812466;6576.112224849775;6582.506312026713;6610.206775187008;6661.134052573135;6661.433778128913;6341.8731625368155;6444.420002199637;6517.120188774455;6550.279469258473;6614.900776488453;6609.966950165219;6619.371159739817;6676.566023208476;6715.4627629790475;6743.35938085881;6213.130161777445;6337.334579869047;6342.429181373698;6437.163168788027;6517.608943463408 15:31:06 INFO - PID 24187 | |1;ext.html;907.7821322882506;918.1690570034117;939.7932454859931;954.6515521834533;959.6833045095115;35239.11902202446;35773.642263577356;42819.57180428195;42935.570644293584;43227.13545729091;43999;44414.555854441445;44417.55582444175;44420.33369499452;44531.3320300195;7516.586587737672;7640;7657.119431265392;7658.617069146549;7662.310392064713;7351.4486413518935;7476.5514069156025;7483.962580187119;7509.4743367964265;7527.435442341819;7485.176630570633;7506.887396689054;7546.509476884013;7571.280728330799;7605.163432022473;7507.549547027196;7546.660400281973;7546.8113297167465;7551.660175292098;7552.39580833532;7385.66764495599;7425.1832298447125;7470.4397170211905;7481.7007319707145;7486.962565187139;39845;40008.199836003114;40026;40082.79958600218;40200;3451.758376913617;3478.5477887874413;3490.197254631449;3490.4764285357146;3503.807290599016;31281.374372512422;31298.843505782326;31416.685833141833;31458.05625831229;31499.527507087412;5046.419661738911;5051.9494805051845;5075.670081444731;5084.3644544431945;5109.718965456898;207634;207814;207935;208139;208878.9999999985;51618.22572661413;51841.48158518442;51918.48081519212;53667.463325367025;54849.725751370985;55723.16415253774;55729;56339.43660563342;56359.43640563542;56398.436015639316;15619.921900390653;15810.525684229262;15826.28781704826;16831.74752378715;16922.746158807382;12401.193922395016;12466.937665311796;12483.06377021727;12501.874981250254;12504.49982000728;15477.5356739298;15506.379744810081;15548.689026219412;15673.608159796097;15771.29029193689;6177;6180.6600636965395;6249.7187626556915;6278.905816412757;6355.491560675135;1072.8122578548723;1077.488193108283;1092.2463500184847;1094.3871431998107;1102.9724256893642;24137.637935430983;24681.62977555338;24849.875750621493;24862.627060594106;24933.252002439956;1183.6271574454192;1189.1794661683412;1192.1105048498055;1193.91045671575;1199.2624535910527;13142.474301028044;13158.736825263633;13165.868341316656;13231.73536529264;13282.468701251843;280893;282757;283297;296019;297235;62849.371506284355;63319.6834015827;63337.68331158315;63758.68120659367;63846.36153638404;1499.5426394949427;1508.3815635589356;1514.3034204265941;1515.3484001879299;1526.9236538173047;29099.656836621663;29304.7069529302;29519.85240073786;29567.260818478848;29777.25556861096;39733;40341;40367;40372.1925561493;40424;225257;225938;226683;227041;227486;35914.10214744652;37080.44379334258;37095.814520927226;37275.44086838754;37327.25345493021;45750;46796;47017.52982470131;47027.29459058048;47314;33551.83224083961;34389.48415773815;34539.13652158716;34614.30771384609;34686.65313346834;34169.145771355914;34208.82895585506;34300.828495857364;34364;34988.47517287192;39877.40183897302;39905.800470997456;39964;43412.13175736404;44333;36023.639763603074;36153.819230903675;36202.45696314505;36491.817540912125;37514.249715005004;26287.73712262853;26717;26813.329666758487;26918.865405672845;27414.725852741216 15:31:06 INFO - PID 24187 | |2;jquery.html;573.7291898444944;584.6842704939332;589.3958692340351;592.7391947543078;603.8309273403446;23236.8838155809;23348.416289592747;24180.15369462069;24216.27351179463;24276.514469710593;26843;27321.180364589094;27408.5888711669;27410;27426.862865685645;6475.93524064758;6586.110875031881;6627.1716035495565;6640.800775976729;6643.202815662103;6194.473469755054;6495.415412612864;6500.609963402211;6529.640869752162;6584.242812076601;6537.346265373472;6540.901886471706;6541.247756507991;6544.967275163617;6545.37818907203;6479.3520647935065;6523.478121750272;6534.901976470357;6540.70566824494;6547.738090476375;6058.878822423571;6080.087986801971;6091;6093.238345206849;6107.053406721965;30241;30633;32028.839855800572;32221;32238.35523289521;2227.487677834107;2295.3458264394567;2298.1726578431753;2299.2182657896396;2299.7700229976867;20289.492762680904;20759.89620051905;20773;20787.58424831495;20873.791262087336;2427.963580546293;2513.195777351248;2545.1473756291666;2553.017088420953;2555.6549865768256;139522;142333.2883335587;142803;143039.2848035753;155079;15433.382664693287;15957.361705531648;15998.280077396545;16194.271257793427;16643;25257.368565786004;25291;25356.112536061148;25363.87318063398;25401.74598253994;10057;10098.596056157672;10118.342307749972;10193;10369.274150809371;8700.91299087001;9194.678186263449;9330.533473326306;9334.693142959992;9389.46484449195;9255.398399104171;9823.410595364157;9823.410595364157;9877.753056173653;9927.751806204758;2650.1916915341008;2726.2775364528416;2729.9317517061836;2759.18604011817;2834.3622684896145;1029.2229366827996;1029.2846471702244;1036.7356324137297;1053.1087631202633;1065.0787069185099;21138.365849024554;21553.67669484959;21621.459463513536;21683.241086561895;26046.73953260481;1117.7987962166887;1131.2081542919989;1144.13046084976;1146.9827952580717;1154.6189757380043;11843.861431502668;12144.453499592539;12189.939050304693;12195.634130976085;12312.938435307766;624526;624859;632568;633365;636997;143317;147729;148032;148352.2582387081;150845.24577377044;1313.2645718397537;1317.3544962968076;1351.3716122003316;1376.2361889151616;1390.4855203574539;23012.42468938223;23215.187468438522;23226.651600225672;23349.53300933938;23500.294991150295;68252.658736706;68491.65754171197;70175;71973.64013179901;72037;86973.56513217393;87102.5644871797;87495;87857.56071219603;88051;15127.924360378127;15555.76666349983;15641.687166256383;15662.608434789221;15692.607684807972;24191;24711.50576988486;24731.381715457257;24734.010639574215;24905;18982.905085474486;19210.13554390028;19367;19464.902675486534;19548.31580894662;15799.289031993358;15903.125328107066;16104.516864494084;16707.331706732082;17471.30114795394;20423.183072677522;21498.24756133528;21554.6766798495;21578.568428631654;21677;15531.689366212839;15559.222038898237;15559.688806224038;15591.376344946075;15730.842691572938;18282.634347313244;18617.90691046536;20019.799802001795;20154;20292.188312467337 15:31:06 INFO - PID 24187 | |3;mootools.html;1433.4767809749442;1499.8875084368676;1500.557335586002;1529.7246495630782;1534.447598864409;14643.853561464382;15446.999999999985;15456.845431545682;15458.381664733426;15495;14982.400703971854;16036.198190090507;16053.277602507886;16055.678886422265;16066.116363600027;2692.5691889297764;2735.9179224623194;2736.1244401791437;2738.411241583059;2738.4796888591204;2352.741198468167;2375.6911601491784;2634.7760440362495;2701.75684188423;2729.6451461310003;2624.1340357682016;2644.4446666200024;2649.8807553660035;2650.60240963856;2658.1626787561854;2677.2102229842144;2705.526532856752;2705.702372738997;2713.4166154276827;2715.158300926719;2465.7657522535333;2467.124170919316;2515.9119430820015;2522.6216067590008;2523.8864251108744;15100.169490678009;15339.156346400943;15439.922800386039;15452.613684657861;15488.922555387264;8302.875456868152;8520.74437766868;8565.357598180099;8568.957155214184;8571.314294856396;5372.892542149135;5481.780728770845;5505.064139096323;5626.268585083953;5666.716664166775;1807.204829874851;1824.1153040775214;1832.7159290310024;1857.8420834229107;1872.4663470910862;26504.204873853814;27147.592786108224;27290;27305.726942730715;27318.5902211465;6918;7125.679344429514;7147.142342918779;7297.087864016998;7309.269073092648;18239.63520729578;18241.45275641733;18246.635067298845;18261.178246978918;18264.45206643803;8561.82876342482;8852.291816654651;8870.778230544289;8871;8872.512011839282;7809.180036096242;7859.6463159157975;7860.292573668283;7908.683652653945;7942.483738556975;5196.740162991835;5410.3778065522965;5435.809746658848;5460.235567020621;5502.174673798882;1495.7756336549605;1502.4140585171792;1574.740167872311;1609.8390160983809;1626.1950334584456;950.4677380666849;963.713442554198;976.6295826177798;986.0287616697548;990.4898977026837;8358.665653373797;8858.468491890508;8896.955515222382;8904.154105359981;8927.642894284285;1037.8495118207968;1043.4678314059731;1071.8124328242682;1074.6507385099874;1083.4636854756945;6446.258680251737;6487.481001519867;6489.059086432441;6544.738210471528;6614;22294.777052229594;23143.18998835033;23166.65250021251;23211.651825222634;23226;21341.573168536852;22510.437239068826;22914.770852291596;23031.539369212856;23041.769582304296;12239;12403.379831008413;12476.750464990831;12477.93761031189;12509.624711258677;8971.327150463872;9365.377694245622;9414.858777118212;9766.267529935425;9946.950265248917;19162.904185478983;19756.407307780788;20077.598448030665;20102.698459522828;20172;19555.902220488806;19606.215751369786;19965.20139194416;19975.101120449905;20376.79623203749;12599.118061735591;12705.17416367933;12839.422225999666;12851.293178875252;12852.614421567368;14262.144271343752;14324.355404007054;14396.712065758835;14421.351039203471;14500.129992200502;11490.54037838477;11596.884031159918;11654.592089276835;11678.824817627572;11852.051835852939;11387.715307117389;11471.942640286745;11535.596254131066;11676.474558644712;11743.060555155738;9951.950240248752;10014.098731114234;10121.89878101239;10141.695749127539;10191.898081019392;7785.922140778746;7882.330001949898;7893.526388416713;8016.318612917968;8357.791055223668;7938.3252423542335;7961.363090952594;7961.960190199012;8025.518468891885;8071.192880711882 15:31:06 INFO - PID 24187 | |4;prototype.html;509.4065413792931;520.5757307794147;536.4501386079265;540.2868213957576;543.9809606663766;6234.285571467843;6242.344553821851;6280.524076841938;6315.33688962658;6316.968415157923;6151.784687535937;6170.197874276338;6202.1006953991755;6214.161088253085;6226.346233645459;4964.056829202449;4978.925316120261;4982.17794063979;4984.825531106412;5003.874128321117;4679.812807487697;4903.068417000776;4908.680935739183;4928.137575924216;4952.455229924705;4733.5503127202865;4751.337032038776;4782.904341913159;4797.680637824591;4800.687955282913;4779.426468823729;4784.354112194844;4789.353437285957;4792.952070479303;4823.421189457287;4301.892452688677;4321.503027151886;4334.45819272591;4346.500152482474;4353.825846966118;5929.258842644669;5991.580589358747;6006.579539432241;6049.637021778663;6054.636721796706;5758.79844205455;5767.682777447239;5801.361850196475;5826.708664566755;5851.444112809277;6488.6106833589665;6529.183852018497;6555.147830782015;6557.967210163966;6580.835479113012;4902.460729319772;4908.852734417973;4909.729964851932;4947.925781113287;4974.179260422026;6657.600543967329;6824.18109826824;6835.111435513401;6863.176418829722;6886.311368863174;5087.287779710769;5087.847364579068;5099.4390617032095;5100.132977393778;5122.974385128051;5152.613553983466;5161.632167475622;5170.870728231825;5171.456997015259;5179.7928082877015;5644.915326270109;5674.971625141848;5721.542276617861;5729.656220626775;5740.598158128974;6557.868842623217;6605.207375115017;6616.636085015274;6638.302978187221;6661.833454163685;1776.289484206302;1786.0533917023997;1806.9638607227782;1807.6384723055178;1820.717788742747;1182.633383651071;1245.887870091678;1251.8998480121568;1256.5099611151659;1278.8081787731915;6179.969100154561;6184.721687524072;6207.858288890326;6242.469390101802;6286.114165750257;6622.701978410983;6704.742488519728;6784.389404953479;6811.148606424197;6817.897731534031;6076.605020673642;6162.22972128484;6232.158658581124;6276.529260305495;6316.652584107826;3492.4586689063235;3516.156122530574;3534.6995505381815;3598.4962105305276;3601.2617413430185;7034.2262351141335;7061.82345441368;7073.328033836778;7130.643467826588;7179.425645948311;6288.717007734572;6859.9314006859295;6883.827904302432;6887.070245516891;6907.309269073053;6852.657367131624;6880;6924.545845372528;6971.640530096583;6990.580565166107;6546.149000629887;6553.968269836366;6555.836104097245;6625.337466253336;6678.599284042973;6562.1283442769;6569.57297775643;6584.8024559263295;6603.448189675519;6641.26946035926;6928.618925959121;7039.225685174729;7043.788686339418;7121.46589005837;7133.964330178316;5132.281480592795;5138.665986710852;5140.588752899834;5144.6655967363495;5155.742212889266;6541.149650545399;6628.966855165693;6680.231773346128;6838.384545390939;6843.828904277433;6497.155369802084;6519.315471875385;6519.608823470607;6528.902066469102;6565.606063636197;6468;6514.641694706647;6530.453123281829;6553.246376666744;6566.573172743756;6469.644169570719;6473.640535487412;6504.121943537752;6558.475321974326;6581.243157036811;6415.743370265137;6423.743050277936;6470.676466176578;6591.110200122922;6602.372774586406;6412.551121421456;6417.39034791694;6437.839054023687;6448;6471.611703297629 15:31:06 INFO - PID 24187 | |5;yui.html;938.5213541094042;943.4716558727114;950.9144177024067;956.9377990430621;967.3905439573068;17832.64334713305;18477.2609095636;18498.907505462455;18503.814961850378;18513.35203267886;17779.288828446846;18565.350212742556;21025.264115755952;21243.256486022994;21298;963.3593660215965;979.3242662562809;979.9020097990215;981.8429051351785;984.2126298960802;948.6821914658598;1023.918086553074;1040.6825918094978;1051.8527406163143;1052.0584077250855;1080.2708171983918;1082.2153938394679;1084.4794498640658;1086.16365398643;1101.6694991502557;1076.2358725305044;1076.7092884921105;1079.6059438305;1081.0594782539163;1093.8960798724108;802.5626033811586;803.048387660624;803.056408719752;806.5160903457937;817.7464985854405;18643.06784660775;18652.25390984359;18755;18851.62296754071;19605.411837644893;21678.24126155577;21928.89035554812;22159.889200554055;22378.2167624134;22432.88783556088;16622.58443538909;16737.66524669512;16744.665106697797;17003.829961700347;17048.57378565546;3756.455313979485;3792.6207379262128;3794.1083845296334;3797.9240415191543;3806.448065030555;148242.51757482396;149509;149677;149699;150387;15020.849791502163;15413.306401211972;15510.224488775517;15534.611634708997;15534.922325388301;32360.35279294448;32828.67171328256;33110;33537.3292534148;33583.83208083944;8723.781905452415;8758.912410875937;8783.560821958876;8800.8679869802;8807.163319484638;4355.324924636687;4418.646508279394;4422.380866678667;4439.178751930903;4439.711418757771;5476.91784623231;5553.611247212737;5584.078627026495;5659.6321239119325;5678.68767217807;1006.3207335048813;1023.9641612543675;1035.0580971316067;1041.994790026045;1043.212374657129;708.652760147524;724.9420046396276;727.1419724724875;740.8629403560359;745.2584678245072;11838.585649502398;11846.229995050295;11960.40197990097;12106.939465302617;12622.747545049047;723.05641138315;723.2839488906051;726.6221564786282;726.8509955459118;746.9551826890404;5797.47822695951;5881.764729410861;5987.880242395215;6102.755889764359;6107.175531303306;126309.47381052327;126911;127803;130453.34773326073;131002;111031;111287;113642;115740.42129789297;117298.41350793363;96555;96788.51605741926;98294.5085274569;98809;99158.5042074785;11446.656600302003;11546.76906461883;11558.653240402802;11852.940735296268;11854.52581896749;45329.546704532535;45450.090998180516;45709.54290457053;45746.77126614479;47080.529194708986;56823.71588142033;57209.71395142998;57211;58424;60387.09419358801;15620.453284135456;15840.049597024215;15954;16149.75775363394;16255.268512916711;15546;15895.841041589436;16071.35714571404;16080.035197887697;16255.349786008428;14652.340644670801;14733;14820.481283155037;14844.925775371486;14980.700385992437;14973.1016139032;15001.849981500045;15006;15155.848441515443;15171.924140379228;15960.281787319367;16072.758908616146;16119.274632641795;16320.755188671941;16438.506844794676;8242.17578242171;8267.669293228444;8370.372222083246;8384.035835878709;8416.747497575083;12725.363731813557;12743.235405875677;12859.614211573667;12999.350032498149;13138.540151094665 15:31:06 INFO - PID 24187 | __end_tp_report 15:31:06 INFO - PID 24187 | __start_cc_report 15:31:06 INFO - PID 24187 | _x_x_mozilla_cycle_collect,311 15:31:06 INFO - PID 24187 | __end_cc_report 15:31:06 INFO - PID 24187 | __startTimestamp1502231466707__endTimestamp 15:31:06 INFO - PID 24187 | 15:31:06 INFO - PID 24187 | ------- Summary: start ------- 15:31:06 INFO - PID 24187 | Number of tests: 6 15:31:06 INFO - PID 24187 | 15:31:06 INFO - PID 24187 | [#0] dojo.html Cycles:165 Average:6186.32 Median:6517.12 stddev:813.79 (12.5%) stddev-sans-first:786.19 15:31:06 INFO - PID 24187 | Values: 3391.5 3475.1 3504.3 3516.4 3573.7 6174.5 6257.8 6267.2 6275.1 6305.3 6049.9 6152.6 6158.3 6185.7 6207.6 5397.6 5478.2 5490.2 5540.1 5569.6 5469.7 5547.4 5550.9 5577.6 5604.5 5183.0 5312.3 5388.4 5406.0 5614.7 5441.6 5479.8 5518.7 5553.6 5557.0 5328.7 5450.0 5489.3 5496.0 5532.9 6105.2 6131.8 6154.2 6183.5 6235.0 6723.9 6790.9 6799.5 6804.9 6817.1 6827.1 6838.8 6894.3 6898.3 6912.9 6121.2 6161.3 6197.5 6212.1 6305.8 6821.8 6846.3 6865.3 6924.6 7015.7 6611.6 6645.5 6702.9 6707.6 6710.4 6758.8 6805.3 6819.9 6875.3 6886.0 6478.3 6635.4 6721.2 6788.4 6800.6 6695.1 6752.6 6770.2 6774.4 6776.7 4717.0 4761.0 4762.4 4858.3 4880.5 4337.8 4353.7 4378.2 4399.8 4419.4 6319.8 6359.3 6387.3 6390.0 6390.3 6729.3 6741.9 6809.5 6814.7 6824.8 6168.9 6199.0 6261.8 6265.7 6426.0 5345.1 5359.4 5379.1 5396.3 5463.7 6913.7 7011.6 7014.9 7016.8 7023.1 6650.4 6700.4 6706.6 6719.7 6733.6 6961.2 6961.6 7047.2 7048.1 7060.7 6585.2 6588.6 6666.6 6668.5 6738.7 6794.4 6804.6 6812.3 6870.7 6885.9 6600.8 6649.5 6678.6 6688.2 6689.5 6576.1 6582.5 6610.2 6661.1 6661.4 6341.9 6444.4 6517.1 6550.3 6614.9 6610.0 6619.4 6676.6 6715.5 6743.4 6213.1 6337.3 6342.4 6437.2 6517.6 15:31:06 INFO - PID 24187 | 15:31:06 INFO - PID 24187 | [#1] ext.html Cycles:180 Average:42138.99 Median:36347.14 stddev:63378.97 (174.4%) stddev-sans-first:63481.15 15:31:06 INFO - PID 24187 | Values: 907.8 918.2 939.8 954.7 959.7 35239.1 35773.6 42819.6 42935.6 43227.1 43999.0 44414.6 44417.6 44420.3 44531.3 7516.6 7640.0 7657.1 7658.6 7662.3 7351.4 7476.6 7484.0 7509.5 7527.4 7485.2 7506.9 7546.5 7571.3 7605.2 7507.5 7546.7 7546.8 7551.7 7552.4 7385.7 7425.2 7470.4 7481.7 7487.0 39845.0 40008.2 40026.0 40082.8 40200.0 3451.8 3478.5 3490.2 3490.5 3503.8 31281.4 31298.8 31416.7 31458.1 31499.5 5046.4 5051.9 5075.7 5084.4 5109.7 207634.0 207814.0 207935.0 208139.0 208879.0 51618.2 51841.5 51918.5 53667.5 54849.7 55723.2 55729.0 56339.4 56359.4 56398.4 15619.9 15810.5 15826.3 16831.7 16922.7 12401.2 12466.9 12483.1 12501.9 12504.5 15477.5 15506.4 15548.7 15673.6 15771.3 6177.0 6180.7 6249.7 6278.9 6355.5 1072.8 1077.5 1092.2 1094.4 1103.0 24137.6 24681.6 24849.9 24862.6 24933.3 1183.6 1189.2 1192.1 1193.9 1199.3 13142.5 13158.7 13165.9 13231.7 13282.5 280893.0 282757.0 283297.0 296019.0 297235.0 62849.4 63319.7 63337.7 63758.7 63846.4 1499.5 1508.4 1514.3 1515.3 1526.9 29099.7 29304.7 29519.9 29567.3 29777.3 39733.0 40341.0 40367.0 40372.2 40424.0 225257.0 225938.0 226683.0 227041.0 227486.0 35914.1 37080.4 37095.8 37275.4 37327.3 45750.0 46796.0 47017.5 47027.3 47314.0 33551.8 34389.5 34539.1 34614.3 34686.7 34169.1 34208.8 34300.8 34364.0 34988.5 39877.4 39905.8 39964.0 43412.1 44333.0 36023.6 36153.8 36202.5 36491.8 37514.2 26287.7 26717.0 26813.3 26918.9 27414.7 15:31:06 INFO - PID 24187 | 15:31:06 INFO - PID 24187 | [#2] jquery.html Cycles:180 Average:41336.99 Median:23840.22 stddev:105600.48 (443.0%) stddev-sans-first:105852.37 15:31:06 INFO - PID 24187 | Values: 573.7 584.7 589.4 592.7 603.8 23236.9 23348.4 24180.2 24216.3 24276.5 26843.0 27321.2 27408.6 27410.0 27426.9 6475.9 6586.1 6627.2 6640.8 6643.2 6194.5 6495.4 6500.6 6529.6 6584.2 6537.3 6540.9 6541.2 6545.0 6545.4 6479.4 6523.5 6534.9 6540.7 6547.7 6058.9 6080.1 6091.0 6093.2 6107.1 30241.0 30633.0 32028.8 32221.0 32238.4 2227.5 2295.3 2298.2 2299.2 2299.8 20289.5 20759.9 20773.0 20787.6 20873.8 2428.0 2513.2 2545.1 2553.0 2555.7 139522.0 142333.3 142803.0 143039.3 155079.0 15433.4 15957.4 15998.3 16194.3 16643.0 25257.4 25291.0 25356.1 25363.9 25401.7 10057.0 10098.6 10118.3 10193.0 10369.3 8700.9 9194.7 9330.5 9334.7 9389.5 9255.4 9823.4 9823.4 9877.8 9927.8 2650.2 2726.3 2729.9 2759.2 2834.4 1029.2 1029.3 1036.7 1053.1 1065.1 21138.4 21553.7 21621.5 21683.2 26046.7 1117.8 1131.2 1144.1 1147.0 1154.6 11843.9 12144.5 12189.9 12195.6 12312.9 624526.0 624859.0 632568.0 633365.0 636997.0 143317.0 147729.0 148032.0 148352.3 150845.2 1313.3 1317.4 1351.4 1376.2 1390.5 23012.4 23215.2 23226.7 23349.5 23500.3 68252.7 68491.7 70175.0 71973.6 72037.0 86973.6 87102.6 87495.0 87857.6 88051.0 15127.9 15555.8 15641.7 15662.6 15692.6 24191.0 24711.5 24731.4 24734.0 24905.0 18982.9 19210.1 19367.0 19464.9 19548.3 15799.3 15903.1 16104.5 16707.3 17471.3 20423.2 21498.2 21554.7 21578.6 21677.0 15531.7 15559.2 15559.7 15591.4 15730.8 18282.6 18617.9 20019.8 20154.0 20292.2 15:31:06 INFO - PID 24187 | 15:31:06 INFO - PID 24187 | [#3] mootools.html Cycles:180 Average:9838.73 Median:2420.73 stddev:6962.01 (287.6%) stddev-sans-first:6952.89 15:31:06 INFO - PID 24187 | Values: 1433.5 1499.9 1500.6 1529.7 1534.4 14643.9 15447.0 15456.8 15458.4 15495.0 14982.4 16036.2 16053.3 16055.7 16066.1 2692.6 2735.9 2736.1 2738.4 2738.5 2352.7 2375.7 2634.8 2701.8 2729.6 2624.1 2644.4 2649.9 2650.6 2658.2 2677.2 2705.5 2705.7 2713.4 2715.2 2465.8 2467.1 2515.9 2522.6 2523.9 15100.2 15339.2 15439.9 15452.6 15488.9 8302.9 8520.7 8565.4 8569.0 8571.3 5372.9 5481.8 5505.1 5626.3 5666.7 1807.2 1824.1 1832.7 1857.8 1872.5 26504.2 27147.6 27290.0 27305.7 27318.6 6918.0 7125.7 7147.1 7297.1 7309.3 18239.6 18241.5 18246.6 18261.2 18264.5 8561.8 8852.3 8870.8 8871.0 8872.5 7809.2 7859.6 7860.3 7908.7 7942.5 5196.7 5410.4 5435.8 5460.2 5502.2 1495.8 1502.4 1574.7 1609.8 1626.2 950.5 963.7 976.6 986.0 990.5 8358.7 8858.5 8897.0 8904.2 8927.6 1037.8 1043.5 1071.8 1074.7 1083.5 6446.3 6487.5 6489.1 6544.7 6614.0 22294.8 23143.2 23166.7 23211.7 23226.0 21341.6 22510.4 22914.8 23031.5 23041.8 12239.0 12403.4 12476.8 12477.9 12509.6 8971.3 9365.4 9414.9 9766.3 9947.0 19162.9 19756.4 20077.6 20102.7 20172.0 19555.9 19606.2 19965.2 19975.1 20376.8 12599.1 12705.2 12839.4 12851.3 12852.6 14262.1 14324.4 14396.7 14421.4 14500.1 11490.5 11596.9 11654.6 11678.8 11852.1 11387.7 11471.9 11535.6 11676.5 11743.1 9952.0 10014.1 10121.9 10141.7 10191.9 7785.9 7882.3 7893.5 8016.3 8357.8 7938.3 7961.4 7962.0 8025.5 8071.2 15:31:06 INFO - PID 24187 | 15:31:06 INFO - PID 24187 | [#4] prototype.html Cycles:180 Average:5571.84 Median:6233.22 stddev:1582.20 (25.4%) stddev-sans-first:1540.34 15:31:06 INFO - PID 24187 | Values: 509.4 520.6 536.5 540.3 544.0 6234.3 6242.3 6280.5 6315.3 6317.0 6151.8 6170.2 6202.1 6214.2 6226.3 4964.1 4978.9 4982.2 4984.8 5003.9 4679.8 4903.1 4908.7 4928.1 4952.5 4733.6 4751.3 4782.9 4797.7 4800.7 4779.4 4784.4 4789.4 4793.0 4823.4 4301.9 4321.5 4334.5 4346.5 4353.8 5929.3 5991.6 6006.6 6049.6 6054.6 5758.8 5767.7 5801.4 5826.7 5851.4 6488.6 6529.2 6555.1 6558.0 6580.8 4902.5 4908.9 4909.7 4947.9 4974.2 6657.6 6824.2 6835.1 6863.2 6886.3 5087.3 5087.8 5099.4 5100.1 5123.0 5152.6 5161.6 5170.9 5171.5 5179.8 5644.9 5675.0 5721.5 5729.7 5740.6 6557.9 6605.2 6616.6 6638.3 6661.8 1776.3 1786.1 1807.0 1807.6 1820.7 1182.6 1245.9 1251.9 1256.5 1278.8 6180.0 6184.7 6207.9 6242.5 6286.1 6622.7 6704.7 6784.4 6811.1 6817.9 6076.6 6162.2 6232.2 6276.5 6316.7 3492.5 3516.2 3534.7 3598.5 3601.3 7034.2 7061.8 7073.3 7130.6 7179.4 6288.7 6859.9 6883.8 6887.1 6907.3 6852.7 6880.0 6924.5 6971.6 6990.6 6546.1 6554.0 6555.8 6625.3 6678.6 6562.1 6569.6 6584.8 6603.4 6641.3 6928.6 7039.2 7043.8 7121.5 7134.0 5132.3 5138.7 5140.6 5144.7 5155.7 6541.1 6629.0 6680.2 6838.4 6843.8 6497.2 6519.3 6519.6 6528.9 6565.6 6468.0 6514.6 6530.5 6553.2 6566.6 6469.6 6473.6 6504.1 6558.5 6581.2 6415.7 6423.7 6470.7 6591.1 6602.4 6412.6 6417.4 6437.8 6448.0 6471.6 15:31:06 INFO - PID 24187 | 15:31:06 INFO - PID 24187 | [#5] yui.html Cycles:180 Average:24949.42 Median:18604.21 stddev:37220.59 (200.1%) stddev-sans-first:37281.34 15:31:06 INFO - PID 24187 | Values: 938.5 943.5 950.9 956.9 967.4 17832.6 18477.3 18498.9 18503.8 18513.4 17779.3 18565.4 21025.3 21243.3 21298.0 963.4 979.3 979.9 981.8 984.2 948.7 1023.9 1040.7 1051.9 1052.1 1080.3 1082.2 1084.5 1086.2 1101.7 1076.2 1076.7 1079.6 1081.1 1093.9 802.6 803.0 803.1 806.5 817.7 18643.1 18652.3 18755.0 18851.6 19605.4 21678.2 21928.9 22159.9 22378.2 22432.9 16622.6 16737.7 16744.7 17003.8 17048.6 3756.5 3792.6 3794.1 3797.9 3806.4 148242.5 149509.0 149677.0 149699.0 150387.0 15020.8 15413.3 15510.2 15534.6 15534.9 32360.4 32828.7 33110.0 33537.3 33583.8 8723.8 8758.9 8783.6 8800.9 8807.2 4355.3 4418.6 4422.4 4439.2 4439.7 5476.9 5553.6 5584.1 5659.6 5678.7 1006.3 1024.0 1035.1 1042.0 1043.2 708.7 724.9 727.1 740.9 745.3 11838.6 11846.2 11960.4 12106.9 12622.7 723.1 723.3 726.6 726.9 747.0 5797.5 5881.8 5987.9 6102.8 6107.2 126309.5 126911.0 127803.0 130453.3 131002.0 111031.0 111287.0 113642.0 115740.4 117298.4 96555.0 96788.5 98294.5 98809.0 99158.5 11446.7 11546.8 11558.7 11852.9 11854.5 45329.5 45450.1 45709.5 45746.8 47080.5 56823.7 57209.7 57211.0 58424.0 60387.1 15620.5 15840.0 15954.0 16149.8 16255.3 15546.0 15895.8 16071.4 16080.0 16255.3 14652.3 14733.0 14820.5 14844.9 14980.7 14973.1 15001.8 15006.0 15155.8 15171.9 15960.3 16072.8 16119.3 16320.8 16438.5 8242.2 8267.7 8370.4 8384.0 8416.7 12725.4 12743.2 12859.6 12999.4 13138.5 15:31:06 INFO - PID 24187 | -------- Summary: end -------- 15:31:06 INFO - PID 24187 | 15:31:07 INFO - TEST-INFO | 24187: exit 0 15:31:07 INFO - TEST-OK | dromaeo_css | took 1110374ms 15:31:07 INFO - TEST-START | kraken 15:31:07 INFO - Initialising browser for kraken test... 15:31:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57961/getInfo.html -profile /tmp/tmpBuTAAs/profile 15:31:07 INFO - TEST-INFO | started process 24553 (/builds/slave/test/build/application/firefox/firefox http://localhost:57961/getInfo.html) 15:31:13 INFO - TEST-INFO | 24553: exit 0 15:31:13 INFO - Browser initialized. 15:31:13 INFO - Running cycle 1/1 for kraken test... 15:31:13 INFO - TEST-INFO | started process 24764 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBuTAAs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:31:14 INFO - PID 24764 | 15:31:14 INFO - PID 24764 | (/builds/slave/test/build/application/firefox/firefox:24814): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:31:14 INFO - PID 24764 | 15:31:24 INFO - PID 24764 | RSS: Main: 161718272 15:31:24 INFO - PID 24764 | 15:31:27 INFO - PID 24764 | Cycle 1(1): loaded http://localhost:57961/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57961/tests/kraken/driver.html?testName=audio-beat-detection) 15:31:28 INFO - PID 24764 | RSS: Main: 165777408 15:31:28 INFO - PID 24764 | 15:31:30 INFO - PID 24764 | Cycle 1(1): loaded http://localhost:57961/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57961/tests/kraken/driver.html?testName=audio-dft) 15:31:31 INFO - PID 24764 | RSS: Main: 166916096 15:31:31 INFO - PID 24764 | 15:31:34 INFO - PID 24764 | Cycle 1(1): loaded http://localhost:57961/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57961/tests/kraken/driver.html?testName=audio-fft) 15:31:34 INFO - PID 24764 | RSS: Main: 160661504 15:31:34 INFO - PID 24764 | 15:31:36 INFO - PID 24764 | Cycle 1(1): loaded http://localhost:57961/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57961/tests/kraken/driver.html?testName=audio-oscillator) 15:31:37 INFO - PID 24764 | RSS: Main: 161726464 15:31:37 INFO - PID 24764 | 15:31:39 INFO - PID 24764 | Cycle 1(1): loaded http://localhost:57961/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57961/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:31:40 INFO - PID 24764 | RSS: Main: 161849344 15:31:40 INFO - PID 24764 | 15:31:43 INFO - PID 24764 | Cycle 1(1): loaded http://localhost:57961/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57961/tests/kraken/driver.html?testName=imaging-darkroom) 15:31:43 INFO - PID 24764 | RSS: Main: 164163584 15:31:43 INFO - PID 24764 | 15:31:47 INFO - PID 24764 | Cycle 1(1): loaded http://localhost:57961/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57961/tests/kraken/driver.html?testName=imaging-desaturate) 15:31:47 INFO - PID 24764 | RSS: Main: 166797312 15:31:47 INFO - PID 24764 | 15:31:50 INFO - PID 24764 | Cycle 1(1): loaded http://localhost:57961/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57961/tests/kraken/driver.html?testName=json-parse-financial) 15:31:51 INFO - PID 24764 | RSS: Main: 165220352 15:31:51 INFO - PID 24764 | 15:31:52 INFO - PID 24764 | Cycle 1(1): loaded http://localhost:57961/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57961/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:31:53 INFO - PID 24764 | RSS: Main: 164986880 15:31:53 INFO - PID 24764 | 15:31:55 INFO - PID 24764 | Cycle 1(1): loaded http://localhost:57961/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57961/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:31:55 INFO - PID 24764 | RSS: Main: 165539840 15:31:55 INFO - PID 24764 | 15:31:57 INFO - PID 24764 | Cycle 1(1): loaded http://localhost:57961/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57961/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:31:58 INFO - PID 24764 | RSS: Main: 165318656 15:31:58 INFO - PID 24764 | 15:32:01 INFO - PID 24764 | Cycle 1(1): loaded http://localhost:57961/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57961/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:32:01 INFO - PID 24764 | RSS: Main: 165523456 15:32:01 INFO - PID 24764 | 15:32:04 INFO - PID 24764 | Cycle 1(1): loaded http://localhost:57961/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57961/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:32:05 INFO - PID 24764 | RSS: Main: 165724160 15:32:05 INFO - PID 24764 | 15:32:06 INFO - PID 24764 | Cycle 1(1): loaded http://localhost:57961/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57961/tests/kraken/driver.html?testName=ai-astar) 15:32:07 INFO - PID 24764 | RSS: Main: 167735296 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | __start_tp_report 15:32:07 INFO - PID 24764 | _x_x_mozilla_page_load 15:32:07 INFO - PID 24764 | _x_x_mozilla_page_load_details 15:32:07 INFO - PID 24764 | |i|pagename|runs| 15:32:07 INFO - PID 24764 | |0;ai-astar;98;107;100;105;105;107;107;97;106;109 15:32:07 INFO - PID 24764 | |1;audio-beat-detection;144;114;137;172;116;141;146;117;165;114 15:32:07 INFO - PID 24764 | |2;audio-dft;162;167;165;162;163;161;162;163;164;172 15:32:07 INFO - PID 24764 | |3;audio-fft;75;76;74;168;75;73;73;90;97;74 15:32:07 INFO - PID 24764 | |4;audio-oscillator;79;79;138;78;77;78;131;79;78;130 15:32:07 INFO - PID 24764 | |5;imaging-gaussian-blur;113;113;114;114;114;114;114;113;114;114 15:32:07 INFO - PID 24764 | |6;imaging-darkroom;149;149;156;149;149;149;147;148;148;149 15:32:07 INFO - PID 24764 | |7;imaging-desaturate;96;94;94;93;94;95;94;94;94;94 15:32:07 INFO - PID 24764 | |8;json-parse-financial;47;50;49;46;47;50;47;46;46;46 15:32:07 INFO - PID 24764 | |9;json-stringify-tinderbox;55;54;55;55;54;55;55;55;54;55 15:32:07 INFO - PID 24764 | |10;stanford-crypto-aes;76;75;75;74;75;73;74;74;76;82 15:32:07 INFO - PID 24764 | |11;stanford-crypto-ccm;131;125;129;121;118;118;116;127;118;159 15:32:07 INFO - PID 24764 | |12;stanford-crypto-pbkdf2;175;179;166;168;162;171;176;162;163;162 15:32:07 INFO - PID 24764 | |13;stanford-crypto-sha256-iterative;61;63;61;63;60;61;62;61;69;60 15:32:07 INFO - PID 24764 | __end_tp_report 15:32:07 INFO - PID 24764 | __start_cc_report 15:32:07 INFO - PID 24764 | _x_x_mozilla_cycle_collect,882 15:32:07 INFO - PID 24764 | __end_cc_report 15:32:07 INFO - PID 24764 | __startTimestamp1502231527204__endTimestamp 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | ------- Summary: start ------- 15:32:07 INFO - PID 24764 | Number of tests: 14 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | [#0] ai-astar Cycles:10 Average:104.10 Median:107.00 stddev:4.20 (3.9%) stddev-sans-first:3.83 15:32:07 INFO - PID 24764 | Values: 98.0 107.0 100.0 105.0 105.0 107.0 107.0 97.0 106.0 109.0 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | [#1] audio-beat-detection Cycles:10 Average:136.60 Median:142.50 stddev:21.22 (14.9%) stddev-sans-first:22.34 15:32:07 INFO - PID 24764 | Values: 144.0 114.0 137.0 172.0 116.0 141.0 146.0 117.0 165.0 114.0 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | [#2] audio-dft Cycles:10 Average:164.10 Median:163.50 stddev:3.28 (2.0%) stddev-sans-first:3.39 15:32:07 INFO - PID 24764 | Values: 162.0 167.0 165.0 162.0 163.0 161.0 162.0 163.0 164.0 172.0 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | [#3] audio-fft Cycles:10 Average:87.50 Median:75.00 stddev:29.45 (39.3%) stddev-sans-first:30.89 15:32:07 INFO - PID 24764 | Values: 75.0 76.0 74.0 168.0 75.0 73.0 73.0 90.0 97.0 74.0 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:78.00 stddev:26.52 (34.0%) stddev-sans-first:27.51 15:32:07 INFO - PID 24764 | Values: 79.0 79.0 138.0 78.0 77.0 78.0 131.0 79.0 78.0 130.0 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 15:32:07 INFO - PID 24764 | Values: 113.0 113.0 114.0 114.0 114.0 114.0 114.0 113.0 114.0 114.0 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:149.00 stddev:2.45 (1.6%) stddev-sans-first:2.60 15:32:07 INFO - PID 24764 | Values: 149.0 149.0 156.0 149.0 149.0 149.0 147.0 148.0 148.0 149.0 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.79 (0.8%) stddev-sans-first:0.50 15:32:07 INFO - PID 24764 | Values: 96.0 94.0 94.0 93.0 94.0 95.0 94.0 94.0 94.0 94.0 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | [#8] json-parse-financial Cycles:10 Average:47.40 Median:47.00 stddev:1.65 (3.5%) stddev-sans-first:1.74 15:32:07 INFO - PID 24764 | Values: 47.0 50.0 49.0 46.0 47.0 50.0 47.0 46.0 46.0 46.0 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | [#9] json-stringify-tinderbox Cycles:10 Average:54.70 Median:55.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 15:32:07 INFO - PID 24764 | Values: 55.0 54.0 55.0 55.0 54.0 55.0 55.0 55.0 54.0 55.0 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:75.00 stddev:2.50 (3.3%) stddev-sans-first:2.65 15:32:07 INFO - PID 24764 | Values: 76.0 75.0 75.0 74.0 75.0 73.0 74.0 74.0 76.0 82.0 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | [#11] stanford-crypto-ccm Cycles:10 Average:126.20 Median:126.00 stddev:12.66 (10.0%) stddev-sans-first:13.30 15:32:07 INFO - PID 24764 | Values: 131.0 125.0 129.0 121.0 118.0 118.0 116.0 127.0 118.0 159.0 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:169.50 stddev:6.48 (3.8%) stddev-sans-first:6.42 15:32:07 INFO - PID 24764 | Values: 175.0 179.0 166.0 168.0 162.0 171.0 176.0 162.0 163.0 162.0 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.10 Median:61.50 stddev:2.64 (4.3%) stddev-sans-first:2.77 15:32:07 INFO - PID 24764 | Values: 61.0 63.0 61.0 63.0 60.0 61.0 62.0 61.0 69.0 60.0 15:32:07 INFO - PID 24764 | -------- Summary: end -------- 15:32:07 INFO - PID 24764 | 15:32:07 INFO - PID 24764 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:32:07 INFO - TEST-INFO | 24764: exit 0 15:32:07 INFO - TEST-OK | kraken | took 60387ms 15:32:07 INFO - SUITE-END | took 1170s 15:32:07 INFO - Completed test suite (00:19:30) 15:32:07 INFO - javascript benchmark 15:32:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8815.967398013823, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3391.4743214801697, 3475.131217195701, 3504.334176506464, 3516.367053930293, 3573.7498375113737, 6174.536909731772, 6257.8122656320265, 6267.153934218879, 6275.121482992385, 6305.337939516354, 6049.939500605004, 6152.5693201475915, 6158.261008678954, 6185.6597887116195, 6207.565470417073, 5397.649152805075, 5478.178273259004, 5490.176473528963, 5540.113581826909, 5569.554435645138, 5469.699166545838, 5547.44525547444, 5550.944490555084, 5577.637453565526, 5604.495595396414, 5182.974085129588, 5312.335958005249, 5388.407275199725, 5406.02691515527, 5614.719264036782, 5441.646292990982, 5479.808206712746, 5518.696471694056, 5553.639013464152, 5557.0, 5328.6802791832615, 5450.04624190765, 5489.258950041733, 5495.972520137374, 5532.861678458071, 6105.206323178003, 6131.754729810802, 6154.169187159721, 6183.536234782365, 6235.0, 6723.899141512832, 6790.864182716367, 6799.456043516507, 6804.9319506804795, 6817.113775209191, 6827.146606674166, 6838.760643377507, 6894.310568943116, 6898.275681053467, 6912.896306555405, 6121.234845644294, 6161.322254551996, 6197.504199664016, 6212.06818977157, 6305.8108256752375, 6821.829454263584, 6846.349596788297, 6865.347791959756, 6924.584524928419, 7015.719371225196, 6611.570247933869, 6645.468362530986, 6702.932970670328, 6707.5975441473665, 6710.396064354231, 6758.797236082925, 6805.285445028299, 6819.897701534481, 6875.3124687531845, 6886.0, 6478.319776423502, 6635.369639884299, 6721.22705888829, 6788.422984046312, 6800.591964482147, 6695.096162018162, 6752.5948443093575, 6770.187577490733, 6774.356436138559, 6776.69504872282, 4716.95283047172, 4760.952390476051, 4762.404699412574, 4858.271259311132, 4880.487548807395, 4337.804798784062, 4353.717008394444, 4378.1900348435, 4399.802008909607, 4419.40338054365, 6319.842003949846, 6359.332270111571, 6387.297397286295, 6390.041493775878, 6390.265119511223, 6729.293424190488, 6741.8651626967185, 6809.489288303397, 6814.659267036628, 6824.829379265558, 6168.8766224675255, 6198.977168767193, 6261.843453913689, 6265.71804268809, 6426.0, 5345.1447768356875, 5359.383670877821, 5379.058664733656, 5396.325459317585, 5463.69949652765, 6913.688884000232, 7011.649417529103, 7014.894776578355, 7016.7544135954995, 7023.122109736283, 6650.3682150195655, 6700.430463410665, 6706.631135287606, 6719.731210751515, 6733.562318449285, 6961.164660240601, 6961.582305061713, 7047.189573199148, 7048.118985126859, 7060.682269297997, 6585.242697089896, 6588.637624930483, 6666.600003999775, 6668.466522678271, 6738.696758645775, 6794.388505034571, 6804.625745583842, 6812.284710105368, 6870.656467176521, 6885.8967115492305, 6600.80197594073, 6649.46804255668, 6678.632675202911, 6688.163979502562, 6689.464842812466, 6576.112224849775, 6582.506312026713, 6610.206775187008, 6661.134052573135, 6661.433778128913, 6341.8731625368155, 6444.420002199637, 6517.120188774455, 6550.279469258473, 6614.900776488453, 6609.966950165219, 6619.371159739817, 6676.566023208476, 6715.4627629790475, 6743.35938085881, 6213.130161777445, 6337.334579869047, 6342.429181373698, 6437.163168788027, 6517.608943463408], "value": 6123.357035807035, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [907.7821322882506, 918.1690570034117, 939.7932454859931, 954.6515521834533, 959.6833045095115, 35239.11902202446, 35773.642263577356, 42819.57180428195, 42935.570644293584, 43227.13545729091, 43999.0, 44414.555854441445, 44417.55582444175, 44420.33369499452, 44531.3320300195, 7516.586587737672, 7640.0, 7657.119431265392, 7658.617069146549, 7662.310392064713, 7351.4486413518935, 7476.5514069156025, 7483.962580187119, 7509.4743367964265, 7527.435442341819, 7485.176630570633, 7506.887396689054, 7546.509476884013, 7571.280728330799, 7605.163432022473, 7507.549547027196, 7546.660400281973, 7546.8113297167465, 7551.660175292098, 7552.39580833532, 7385.66764495599, 7425.1832298447125, 7470.4397170211905, 7481.7007319707145, 7486.962565187139, 39845.0, 40008.199836003114, 40026.0, 40082.79958600218, 40200.0, 3451.758376913617, 3478.5477887874413, 3490.197254631449, 3490.4764285357146, 3503.807290599016, 31281.374372512422, 31298.843505782326, 31416.685833141833, 31458.05625831229, 31499.527507087412, 5046.419661738911, 5051.9494805051845, 5075.670081444731, 5084.3644544431945, 5109.718965456898, 207634.0, 207814.0, 207935.0, 208139.0, 208878.9999999985, 51618.22572661413, 51841.48158518442, 51918.48081519212, 53667.463325367025, 54849.725751370985, 55723.16415253774, 55729.0, 56339.43660563342, 56359.43640563542, 56398.436015639316, 15619.921900390653, 15810.525684229262, 15826.28781704826, 16831.74752378715, 16922.746158807382, 12401.193922395016, 12466.937665311796, 12483.06377021727, 12501.874981250254, 12504.49982000728, 15477.5356739298, 15506.379744810081, 15548.689026219412, 15673.608159796097, 15771.29029193689, 6177.0, 6180.6600636965395, 6249.7187626556915, 6278.905816412757, 6355.491560675135, 1072.8122578548723, 1077.488193108283, 1092.2463500184847, 1094.3871431998107, 1102.9724256893642, 24137.637935430983, 24681.62977555338, 24849.875750621493, 24862.627060594106, 24933.252002439956, 1183.6271574454192, 1189.1794661683412, 1192.1105048498055, 1193.91045671575, 1199.2624535910527, 13142.474301028044, 13158.736825263633, 13165.868341316656, 13231.73536529264, 13282.468701251843, 280893.0, 282757.0, 283297.0, 296019.0, 297235.0, 62849.371506284355, 63319.6834015827, 63337.68331158315, 63758.68120659367, 63846.36153638404, 1499.5426394949427, 1508.3815635589356, 1514.3034204265941, 1515.3484001879299, 1526.9236538173047, 29099.656836621663, 29304.7069529302, 29519.85240073786, 29567.260818478848, 29777.25556861096, 39733.0, 40341.0, 40367.0, 40372.1925561493, 40424.0, 225257.0, 225938.0, 226683.0, 227041.0, 227486.0, 35914.10214744652, 37080.44379334258, 37095.814520927226, 37275.44086838754, 37327.25345493021, 45750.0, 46796.0, 47017.52982470131, 47027.29459058048, 47314.0, 33551.83224083961, 34389.48415773815, 34539.13652158716, 34614.30771384609, 34686.65313346834, 34169.145771355914, 34208.82895585506, 34300.828495857364, 34364.0, 34988.47517287192, 39877.40183897302, 39905.800470997456, 39964.0, 43412.13175736404, 44333.0, 36023.639763603074, 36153.819230903675, 36202.45696314505, 36491.817540912125, 37514.249715005004, 26287.73712262853, 26717.0, 26813.329666758487, 26918.865405672845, 27414.725852741216], "value": 18302.26569877001, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.7291898444944, 584.6842704939332, 589.3958692340351, 592.7391947543078, 603.8309273403446, 23236.8838155809, 23348.416289592747, 24180.15369462069, 24216.27351179463, 24276.514469710593, 26843.0, 27321.180364589094, 27408.5888711669, 27410.0, 27426.862865685645, 6475.93524064758, 6586.110875031881, 6627.1716035495565, 6640.800775976729, 6643.202815662103, 6194.473469755054, 6495.415412612864, 6500.609963402211, 6529.640869752162, 6584.242812076601, 6537.346265373472, 6540.901886471706, 6541.247756507991, 6544.967275163617, 6545.37818907203, 6479.3520647935065, 6523.478121750272, 6534.901976470357, 6540.70566824494, 6547.738090476375, 6058.878822423571, 6080.087986801971, 6091.0, 6093.238345206849, 6107.053406721965, 30241.0, 30633.0, 32028.839855800572, 32221.0, 32238.35523289521, 2227.487677834107, 2295.3458264394567, 2298.1726578431753, 2299.2182657896396, 2299.7700229976867, 20289.492762680904, 20759.89620051905, 20773.0, 20787.58424831495, 20873.791262087336, 2427.963580546293, 2513.195777351248, 2545.1473756291666, 2553.017088420953, 2555.6549865768256, 139522.0, 142333.2883335587, 142803.0, 143039.2848035753, 155079.0, 15433.382664693287, 15957.361705531648, 15998.280077396545, 16194.271257793427, 16643.0, 25257.368565786004, 25291.0, 25356.112536061148, 25363.87318063398, 25401.74598253994, 10057.0, 10098.596056157672, 10118.342307749972, 10193.0, 10369.274150809371, 8700.91299087001, 9194.678186263449, 9330.533473326306, 9334.693142959992, 9389.46484449195, 9255.398399104171, 9823.410595364157, 9823.410595364157, 9877.753056173653, 9927.751806204758, 2650.1916915341008, 2726.2775364528416, 2729.9317517061836, 2759.18604011817, 2834.3622684896145, 1029.2229366827996, 1029.2846471702244, 1036.7356324137297, 1053.1087631202633, 1065.0787069185099, 21138.365849024554, 21553.67669484959, 21621.459463513536, 21683.241086561895, 26046.73953260481, 1117.7987962166887, 1131.2081542919989, 1144.13046084976, 1146.9827952580717, 1154.6189757380043, 11843.861431502668, 12144.453499592539, 12189.939050304693, 12195.634130976085, 12312.938435307766, 624526.0, 624859.0, 632568.0, 633365.0, 636997.0, 143317.0, 147729.0, 148032.0, 148352.2582387081, 150845.24577377044, 1313.2645718397537, 1317.3544962968076, 1351.3716122003316, 1376.2361889151616, 1390.4855203574539, 23012.42468938223, 23215.187468438522, 23226.651600225672, 23349.53300933938, 23500.294991150295, 68252.658736706, 68491.65754171197, 70175.0, 71973.64013179901, 72037.0, 86973.56513217393, 87102.5644871797, 87495.0, 87857.56071219603, 88051.0, 15127.924360378127, 15555.76666349983, 15641.687166256383, 15662.608434789221, 15692.607684807972, 24191.0, 24711.50576988486, 24731.381715457257, 24734.010639574215, 24905.0, 18982.905085474486, 19210.13554390028, 19367.0, 19464.902675486534, 19548.31580894662, 15799.289031993358, 15903.125328107066, 16104.516864494084, 16707.331706732082, 17471.30114795394, 20423.183072677522, 21498.24756133528, 21554.6766798495, 21578.568428631654, 21677.0, 15531.689366212839, 15559.222038898237, 15559.688806224038, 15591.376344946075, 15730.842691572938, 18282.634347313244, 18617.90691046536, 20019.799802001795, 20154.0, 20292.188312467337], "value": 12938.764368457558, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1433.4767809749442, 1499.8875084368676, 1500.557335586002, 1529.7246495630782, 1534.447598864409, 14643.853561464382, 15446.999999999985, 15456.845431545682, 15458.381664733426, 15495.0, 14982.400703971854, 16036.198190090507, 16053.277602507886, 16055.678886422265, 16066.116363600027, 2692.5691889297764, 2735.9179224623194, 2736.1244401791437, 2738.411241583059, 2738.4796888591204, 2352.741198468167, 2375.6911601491784, 2634.7760440362495, 2701.75684188423, 2729.6451461310003, 2624.1340357682016, 2644.4446666200024, 2649.8807553660035, 2650.60240963856, 2658.1626787561854, 2677.2102229842144, 2705.526532856752, 2705.702372738997, 2713.4166154276827, 2715.158300926719, 2465.7657522535333, 2467.124170919316, 2515.9119430820015, 2522.6216067590008, 2523.8864251108744, 15100.169490678009, 15339.156346400943, 15439.922800386039, 15452.613684657861, 15488.922555387264, 8302.875456868152, 8520.74437766868, 8565.357598180099, 8568.957155214184, 8571.314294856396, 5372.892542149135, 5481.780728770845, 5505.064139096323, 5626.268585083953, 5666.716664166775, 1807.204829874851, 1824.1153040775214, 1832.7159290310024, 1857.8420834229107, 1872.4663470910862, 26504.204873853814, 27147.592786108224, 27290.0, 27305.726942730715, 27318.5902211465, 6918.0, 7125.679344429514, 7147.142342918779, 7297.087864016998, 7309.269073092648, 18239.63520729578, 18241.45275641733, 18246.635067298845, 18261.178246978918, 18264.45206643803, 8561.82876342482, 8852.291816654651, 8870.778230544289, 8871.0, 8872.512011839282, 7809.180036096242, 7859.6463159157975, 7860.292573668283, 7908.683652653945, 7942.483738556975, 5196.740162991835, 5410.3778065522965, 5435.809746658848, 5460.235567020621, 5502.174673798882, 1495.7756336549605, 1502.4140585171792, 1574.740167872311, 1609.8390160983809, 1626.1950334584456, 950.4677380666849, 963.713442554198, 976.6295826177798, 986.0287616697548, 990.4898977026837, 8358.665653373797, 8858.468491890508, 8896.955515222382, 8904.154105359981, 8927.642894284285, 1037.8495118207968, 1043.4678314059731, 1071.8124328242682, 1074.6507385099874, 1083.4636854756945, 6446.258680251737, 6487.481001519867, 6489.059086432441, 6544.738210471528, 6614.0, 22294.777052229594, 23143.18998835033, 23166.65250021251, 23211.651825222634, 23226.0, 21341.573168536852, 22510.437239068826, 22914.770852291596, 23031.539369212856, 23041.769582304296, 12239.0, 12403.379831008413, 12476.750464990831, 12477.93761031189, 12509.624711258677, 8971.327150463872, 9365.377694245622, 9414.858777118212, 9766.267529935425, 9946.950265248917, 19162.904185478983, 19756.407307780788, 20077.598448030665, 20102.698459522828, 20172.0, 19555.902220488806, 19606.215751369786, 19965.20139194416, 19975.101120449905, 20376.79623203749, 12599.118061735591, 12705.17416367933, 12839.422225999666, 12851.293178875252, 12852.614421567368, 14262.144271343752, 14324.355404007054, 14396.712065758835, 14421.351039203471, 14500.129992200502, 11490.54037838477, 11596.884031159918, 11654.592089276835, 11678.824817627572, 11852.051835852939, 11387.715307117389, 11471.942640286745, 11535.596254131066, 11676.474558644712, 11743.060555155738, 9951.950240248752, 10014.098731114234, 10121.89878101239, 10141.695749127539, 10191.898081019392, 7785.922140778746, 7882.330001949898, 7893.526388416713, 8016.318612917968, 8357.791055223668, 7938.3252423542335, 7961.363090952594, 7961.960190199012, 8025.518468891885, 8071.192880711882], "value": 7014.637388154237, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.4065413792931, 520.5757307794147, 536.4501386079265, 540.2868213957576, 543.9809606663766, 6234.285571467843, 6242.344553821851, 6280.524076841938, 6315.33688962658, 6316.968415157923, 6151.784687535937, 6170.197874276338, 6202.1006953991755, 6214.161088253085, 6226.346233645459, 4964.056829202449, 4978.925316120261, 4982.17794063979, 4984.825531106412, 5003.874128321117, 4679.812807487697, 4903.068417000776, 4908.680935739183, 4928.137575924216, 4952.455229924705, 4733.5503127202865, 4751.337032038776, 4782.904341913159, 4797.680637824591, 4800.687955282913, 4779.426468823729, 4784.354112194844, 4789.353437285957, 4792.952070479303, 4823.421189457287, 4301.892452688677, 4321.503027151886, 4334.45819272591, 4346.500152482474, 4353.825846966118, 5929.258842644669, 5991.580589358747, 6006.579539432241, 6049.637021778663, 6054.636721796706, 5758.79844205455, 5767.682777447239, 5801.361850196475, 5826.708664566755, 5851.444112809277, 6488.6106833589665, 6529.183852018497, 6555.147830782015, 6557.967210163966, 6580.835479113012, 4902.460729319772, 4908.852734417973, 4909.729964851932, 4947.925781113287, 4974.179260422026, 6657.600543967329, 6824.18109826824, 6835.111435513401, 6863.176418829722, 6886.311368863174, 5087.287779710769, 5087.847364579068, 5099.4390617032095, 5100.132977393778, 5122.974385128051, 5152.613553983466, 5161.632167475622, 5170.870728231825, 5171.456997015259, 5179.7928082877015, 5644.915326270109, 5674.971625141848, 5721.542276617861, 5729.656220626775, 5740.598158128974, 6557.868842623217, 6605.207375115017, 6616.636085015274, 6638.302978187221, 6661.833454163685, 1776.289484206302, 1786.0533917023997, 1806.9638607227782, 1807.6384723055178, 1820.717788742747, 1182.633383651071, 1245.887870091678, 1251.8998480121568, 1256.5099611151659, 1278.8081787731915, 6179.969100154561, 6184.721687524072, 6207.858288890326, 6242.469390101802, 6286.114165750257, 6622.701978410983, 6704.742488519728, 6784.389404953479, 6811.148606424197, 6817.897731534031, 6076.605020673642, 6162.22972128484, 6232.158658581124, 6276.529260305495, 6316.652584107826, 3492.4586689063235, 3516.156122530574, 3534.6995505381815, 3598.4962105305276, 3601.2617413430185, 7034.2262351141335, 7061.82345441368, 7073.328033836778, 7130.643467826588, 7179.425645948311, 6288.717007734572, 6859.9314006859295, 6883.827904302432, 6887.070245516891, 6907.309269073053, 6852.657367131624, 6880.0, 6924.545845372528, 6971.640530096583, 6990.580565166107, 6546.149000629887, 6553.968269836366, 6555.836104097245, 6625.337466253336, 6678.599284042973, 6562.1283442769, 6569.57297775643, 6584.8024559263295, 6603.448189675519, 6641.26946035926, 6928.618925959121, 7039.225685174729, 7043.788686339418, 7121.46589005837, 7133.964330178316, 5132.281480592795, 5138.665986710852, 5140.588752899834, 5144.6655967363495, 5155.742212889266, 6541.149650545399, 6628.966855165693, 6680.231773346128, 6838.384545390939, 6843.828904277433, 6497.155369802084, 6519.315471875385, 6519.608823470607, 6528.902066469102, 6565.606063636197, 6468.0, 6514.641694706647, 6530.453123281829, 6553.246376666744, 6566.573172743756, 6469.644169570719, 6473.640535487412, 6504.121943537752, 6558.475321974326, 6581.243157036811, 6415.743370265137, 6423.743050277936, 6470.676466176578, 6591.110200122922, 6602.372774586406, 6412.551121421456, 6417.39034791694, 6437.839054023687, 6448.0, 6471.611703297629], "value": 5110.51290024536, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [938.5213541094042, 943.4716558727114, 950.9144177024067, 956.9377990430621, 967.3905439573068, 17832.64334713305, 18477.2609095636, 18498.907505462455, 18503.814961850378, 18513.35203267886, 17779.288828446846, 18565.350212742556, 21025.264115755952, 21243.256486022994, 21298.0, 963.3593660215965, 979.3242662562809, 979.9020097990215, 981.8429051351785, 984.2126298960802, 948.6821914658598, 1023.918086553074, 1040.6825918094978, 1051.8527406163143, 1052.0584077250855, 1080.2708171983918, 1082.2153938394679, 1084.4794498640658, 1086.16365398643, 1101.6694991502557, 1076.2358725305044, 1076.7092884921105, 1079.6059438305, 1081.0594782539163, 1093.8960798724108, 802.5626033811586, 803.048387660624, 803.056408719752, 806.5160903457937, 817.7464985854405, 18643.06784660775, 18652.25390984359, 18755.0, 18851.62296754071, 19605.411837644893, 21678.24126155577, 21928.89035554812, 22159.889200554055, 22378.2167624134, 22432.88783556088, 16622.58443538909, 16737.66524669512, 16744.665106697797, 17003.829961700347, 17048.57378565546, 3756.455313979485, 3792.6207379262128, 3794.1083845296334, 3797.9240415191543, 3806.448065030555, 148242.51757482396, 149509.0, 149677.0, 149699.0, 150387.0, 15020.849791502163, 15413.306401211972, 15510.224488775517, 15534.611634708997, 15534.922325388301, 32360.35279294448, 32828.67171328256, 33110.0, 33537.3292534148, 33583.83208083944, 8723.781905452415, 8758.912410875937, 8783.560821958876, 8800.8679869802, 8807.163319484638, 4355.324924636687, 4418.646508279394, 4422.380866678667, 4439.178751930903, 4439.711418757771, 5476.91784623231, 5553.611247212737, 5584.078627026495, 5659.6321239119325, 5678.68767217807, 1006.3207335048813, 1023.9641612543675, 1035.0580971316067, 1041.994790026045, 1043.212374657129, 708.652760147524, 724.9420046396276, 727.1419724724875, 740.8629403560359, 745.2584678245072, 11838.585649502398, 11846.229995050295, 11960.40197990097, 12106.939465302617, 12622.747545049047, 723.05641138315, 723.2839488906051, 726.6221564786282, 726.8509955459118, 746.9551826890404, 5797.47822695951, 5881.764729410861, 5987.880242395215, 6102.755889764359, 6107.175531303306, 126309.47381052327, 126911.0, 127803.0, 130453.34773326073, 131002.0, 111031.0, 111287.0, 113642.0, 115740.42129789297, 117298.41350793363, 96555.0, 96788.51605741926, 98294.5085274569, 98809.0, 99158.5042074785, 11446.656600302003, 11546.76906461883, 11558.653240402802, 11852.940735296268, 11854.52581896749, 45329.546704532535, 45450.090998180516, 45709.54290457053, 45746.77126614479, 47080.529194708986, 56823.71588142033, 57209.71395142998, 57211.0, 58424.0, 60387.09419358801, 15620.453284135456, 15840.049597024215, 15954.0, 16149.75775363394, 16255.268512916711, 15546.0, 15895.841041589436, 16071.35714571404, 16080.035197887697, 16255.349786008428, 14652.340644670801, 14733.0, 14820.481283155037, 14844.925775371486, 14980.700385992437, 14973.1016139032, 15001.849981500045, 15006.0, 15155.848441515443, 15171.924140379228, 15960.281787319367, 16072.758908616146, 16119.274632641795, 16320.755188671941, 16438.506844794676, 8242.17578242171, 8267.669293228444, 8370.372222083246, 8384.035835878709, 8416.747497575083, 12725.363731813557, 12743.235405875677, 12859.614211573667, 12999.350032498149, 13138.540151094665], "value": 9030.988194015317, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1478.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 100.0, 105.0, 105.0, 107.0, 107.0, 97.0, 106.0, 109.0], "value": 104.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 114.0, 137.0, 172.0, 116.0, 141.0, 146.0, 117.0, 165.0, 114.0], "value": 136.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 167.0, 165.0, 162.0, 163.0, 161.0, 162.0, 163.0, 164.0, 172.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 168.0, 75.0, 73.0, 73.0, 90.0, 97.0, 74.0], "value": 87.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 138.0, 78.0, 77.0, 78.0, 131.0, 79.0, 78.0, 130.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 156.0, 149.0, 149.0, 149.0, 147.0, 148.0, 148.0, 149.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 94.0, 93.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 50.0, 49.0, 46.0, 47.0, 50.0, 47.0, 46.0, 46.0, 46.0], "value": 47.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 55.0, 54.0, 55.0, 55.0, 55.0, 54.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 74.0, 75.0, 73.0, 74.0, 74.0, 76.0, 82.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 125.0, 129.0, 121.0, 118.0, 118.0, 116.0, 127.0, 118.0, 159.0], "value": 126.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 179.0, 166.0, 168.0, 162.0, 171.0, 176.0, 162.0, 163.0, 162.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 61.0, 63.0, 60.0, 61.0, 62.0, 61.0, 69.0, 60.0], "value": 62.1, "unit": "score"}]}]} 15:32:07 INFO - Return code: 0 15:32:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:32:07 INFO - # TBPL SUCCESS # 15:32:07 INFO - Running post-action listener: _package_coverage_data 15:32:07 INFO - Running post-action listener: _resource_record_post_action 15:32:07 INFO - [mozharness: 2017-08-08 22:32:07.976491Z] Finished run-tests step (success) 15:32:07 INFO - Running post-run listener: _resource_record_post_run 15:32:08 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 8192; Write bytes: 429043712; Read time: 20; Write time: 445476 15:32:08 INFO - TinderboxPrint: CPU usage
13.1% 15:32:08 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 20 15:32:08 INFO - TinderboxPrint: I/O write bytes / time
429,043,712 / 445,476 15:32:08 INFO - TinderboxPrint: CPU idle
8,241.0 (86.9%) 15:32:08 INFO - TinderboxPrint: CPU user
1,189.9 (12.5%) 15:32:08 INFO - TinderboxPrint: Swap in / out
0 / 0 15:32:08 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 108244992; Read time: 0; Write time: 141540 15:32:08 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:32:08 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 8192; Write bytes: 320798720; Read time: 20; Write time: 303936 15:32:08 INFO - Running post-run listener: _upload_blobber_files 15:32:08 INFO - Blob upload gear active. 15:32:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:32:08 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 15:32:08 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'] 15:32:08 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 15:32:09 INFO - (blobuploader) - INFO - Open directory for files ... 15:32:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:32:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:32:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:09 INFO - (blobuploader) - INFO - Done attempting. 15:32:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:32:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:32:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:10 INFO - (blobuploader) - INFO - Done attempting. 15:32:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:32:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:32:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:17 INFO - (blobuploader) - INFO - Done attempting. 15:32:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:32:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:32:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:32:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:32:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:32:18 INFO - (blobuploader) - INFO - Done attempting. 15:32:18 INFO - (blobuploader) - INFO - Iteration through files over. 15:32:18 INFO - Return code: 0 15:32:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:32:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:32:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7baba50b32474eb2f61efc9d44207d1a6527b98e726d8600150744e18ad253f91d2f299d6cac18c31ff5d91e45d3b0a09cf533e6b63c5e38099038817283f68b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f16c7d0ed56b19fc77d6a1fa9ae654373d90bae717183294bc71b746b0421acc51b37eb7d276a4a7ae15b76a2f19c6ed91e7dba82941f2025942bad046159fc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49567ed14e0dd6ba1e247ea67682e5b1bdb59d6dd4539208b64810a847e94dc725783c6bd7d147598a413e43b7ed24acdf6b7eaa8f34a6365870d5934e46d586", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/588990231c273fe76fcca9273a783a50f656e259977b532fe5e9ad788214ed965c523049eadbb34124f27361a75e1fe473de1e295e70d5e6f959f6e8d1748e82"} 15:32:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:32:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:32:18 INFO - Contents: 15:32:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7baba50b32474eb2f61efc9d44207d1a6527b98e726d8600150744e18ad253f91d2f299d6cac18c31ff5d91e45d3b0a09cf533e6b63c5e38099038817283f68b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f16c7d0ed56b19fc77d6a1fa9ae654373d90bae717183294bc71b746b0421acc51b37eb7d276a4a7ae15b76a2f19c6ed91e7dba82941f2025942bad046159fc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49567ed14e0dd6ba1e247ea67682e5b1bdb59d6dd4539208b64810a847e94dc725783c6bd7d147598a413e43b7ed24acdf6b7eaa8f34a6365870d5934e46d586", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/588990231c273fe76fcca9273a783a50f656e259977b532fe5e9ad788214ed965c523049eadbb34124f27361a75e1fe473de1e295e70d5e6f959f6e8d1748e82"} 15:32:18 INFO - Running post-run listener: copy_logs_to_upload_dir 15:32:18 INFO - Copying logs to upload dir... 15:32:18 INFO - mkdir: /builds/slave/test/build/upload/logs 15:32:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.888164 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-08 15:32:18.215419) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 15:32:18.220048) ========= 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=fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7baba50b32474eb2f61efc9d44207d1a6527b98e726d8600150744e18ad253f91d2f299d6cac18c31ff5d91e45d3b0a09cf533e6b63c5e38099038817283f68b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f16c7d0ed56b19fc77d6a1fa9ae654373d90bae717183294bc71b746b0421acc51b37eb7d276a4a7ae15b76a2f19c6ed91e7dba82941f2025942bad046159fc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49567ed14e0dd6ba1e247ea67682e5b1bdb59d6dd4539208b64810a847e94dc725783c6bd7d147598a413e43b7ed24acdf6b7eaa8f34a6365870d5934e46d586", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/588990231c273fe76fcca9273a783a50f656e259977b532fe5e9ad788214ed965c523049eadbb34124f27361a75e1fe473de1e295e70d5e6f959f6e8d1748e82"} build_url:https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011384 build_url: 'https://queue.taskcluster.net/v1/task/IZpbupGoTCKssKG-erB1Lg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7baba50b32474eb2f61efc9d44207d1a6527b98e726d8600150744e18ad253f91d2f299d6cac18c31ff5d91e45d3b0a09cf533e6b63c5e38099038817283f68b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f16c7d0ed56b19fc77d6a1fa9ae654373d90bae717183294bc71b746b0421acc51b37eb7d276a4a7ae15b76a2f19c6ed91e7dba82941f2025942bad046159fc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49567ed14e0dd6ba1e247ea67682e5b1bdb59d6dd4539208b64810a847e94dc725783c6bd7d147598a413e43b7ed24acdf6b7eaa8f34a6365870d5934e46d586", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/588990231c273fe76fcca9273a783a50f656e259977b532fe5e9ad788214ed965c523049eadbb34124f27361a75e1fe473de1e295e70d5e6f959f6e8d1748e82"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 15:32:18.247757) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 15:32:18.248123) ========= 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=fa44defc139c4ba8574fe040000001db-1502230011.286958-392783195 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 15:32:18.327789) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 15:32:18.328132) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 15:32:18.328456) ========= ========= Total master_lag: 0.20 =========