builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-048 starttime: 1502576730.55 results: success (0) revision: 6062341662fcee683434ee71539bfa10964c022e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-12 15:25:30.547270) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-12 15:25:30.547810) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-12 15:25:30.548110) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-12 15:25:30.565158) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 15:25:30.565483) ========= 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-1502556599.908738-475162972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-12 15:25:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2017-08-12 15:25:31 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.076719 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 15:25:30.660198) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 15:25:30.660558) ========= 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-1502556599.908738-475162972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020872 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 15:25:30.719003) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-12 15:25:30.719455) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6062341662fcee683434ee71539bfa10964c022e --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 6062341662fcee683434ee71539bfa10964c022e --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-1502556599.908738-475162972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-12 15:25:31,444 truncating revision to first 12 chars 2017-08-12 15:25:31,444 Setting DEBUG logging. 2017-08-12 15:25:31,444 attempt 1/10 2017-08-12 15:25:31,444 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6062341662fc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-12 15:25:32,780 unpacking tar archive at: mozilla-central-6062341662fc/testing/mozharness/ program finished with exit code 0 elapsedTime=1.742257 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-12 15:25:32.493678) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-12 15:25:32.493987) ========= script_repo_revision: 6062341662fcee683434ee71539bfa10964c022e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-12 15:25:32.494381) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-12 15:25:32.494654) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-12 15:25:32.507820) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-08-12 15:25:32.508122) ========= /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-1502556599.908738-475162972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:25:33 INFO - MultiFileLogger online at 20170812 15:25:33 in /builds/slave/test 15:25:33 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:25:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:25:33 INFO - {'append_to_log': False, 15:25:33 INFO - 'base_work_dir': '/builds/slave/test', 15:25:33 INFO - 'blob_upload_branch': 'Firefox', 15:25:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:25:33 INFO - 'branch': 'Firefox', 15:25:33 INFO - 'buildbot_json_path': 'buildprops.json', 15:25:33 INFO - 'code_coverage': False, 15:25:33 INFO - 'config_files': ('talos/linux_config.py',), 15:25:33 INFO - 'default_actions': ('clobber', 15:25:33 INFO - 'read-buildbot-config', 15:25:33 INFO - 'download-and-extract', 15:25:33 INFO - 'populate-webroot', 15:25:33 INFO - 'create-virtualenv', 15:25:33 INFO - 'install', 15:25:33 INFO - 'setup-mitmproxy', 15:25:33 INFO - 'run-tests'), 15:25:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:25:33 INFO - 'disable_ccov_upload': False, 15:25:33 INFO - 'download_minidump_stackwalk': True, 15:25:33 INFO - 'download_symbols': 'ondemand', 15:25:33 INFO - 'enable_stylo': False, 15:25:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:25:33 INFO - 'tooltool.py': '/tools/tooltool.py', 15:25:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:25:33 INFO - '/tools/misc-python/virtualenv.py')}, 15:25:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:25:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:25:33 INFO - 'gecko_profile': False, 15:25:33 INFO - 'gecko_profile_interval': 0, 15:25:33 INFO - 'installer_path': 'installer.exe', 15:25:33 INFO - 'log_level': 'info', 15:25:33 INFO - 'log_name': 'talos', 15:25:33 INFO - 'log_to_console': True, 15:25:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:25:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:25:33 INFO - 'opt_config_files': (), 15:25:33 INFO - 'pip_index': False, 15:25:33 INFO - 'suite': 'dromaeojs-e10s', 15:25:33 INFO - 'system_bits': '32', 15:25:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:25:33 INFO - 'title': 'talos-linux64-ix-048', 15:25:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:25:33 INFO - 'use_talos_json': True, 15:25:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:25:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:25:33 INFO - 'work_dir': 'build'} 15:25:33 INFO - [mozharness: 2017-08-12 22:25:33.315884Z] Running clobber step. 15:25:33 INFO - Running pre-action listener: _resource_record_pre_action 15:25:33 INFO - Running main action method: clobber 15:25:33 INFO - rmtree: /builds/slave/test/build 15:25:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:25:34 INFO - Running post-action listener: _resource_record_post_action 15:25:34 INFO - [mozharness: 2017-08-12 22:25:34.331865Z] Finished clobber step (success) 15:25:34 INFO - [mozharness: 2017-08-12 22:25:34.331967Z] Running read-buildbot-config step. 15:25:34 INFO - Running pre-action listener: _resource_record_pre_action 15:25:34 INFO - Running main action method: read_buildbot_config 15:25:34 INFO - Using buildbot properties: 15:25:34 INFO - { 15:25:34 INFO - "project": "", 15:25:34 INFO - "product": "firefox", 15:25:34 INFO - "who": "archaeopteryx@coole-files.de", 15:25:34 INFO - "installer_path": "public/build/target.tar.bz2", 15:25:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 15:25:34 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:25:34 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 15:25:34 INFO - "stage_platform": "linux64-stylosequential", 15:25:34 INFO - "basedir": "/builds/slave/test", 15:25:34 INFO - "buildnumber": 145, 15:25:34 INFO - "platform": "ubuntu64_hw_styloseq", 15:25:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:25:34 INFO - "slavebuilddir": "test", 15:25:34 INFO - "taskId": "DBLabj7EQjaXecZh89jiRA", 15:25:34 INFO - "branch": "mozilla-central", 15:25:34 INFO - "script_repo_revision": "production", 15:25:34 INFO - "revision": "6062341662fcee683434ee71539bfa10964c022e", 15:25:34 INFO - "slavename": "talos-linux64-ix-048", 15:25:34 INFO - "repo_path": "mozilla-central" 15:25:34 INFO - } 15:25:34 INFO - Finding installer, test and symbols from parent task. 15:25:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DBLabj7EQjaXecZh89jiRA'}, attempt #1 15:25:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DBLabj7EQjaXecZh89jiRA'}, attempt #1 15:25:35 INFO - Task dependencies: Yx2HVl5oTtyjs-iuTJgx8w 15:25:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w'}, attempt #1 15:25:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.tar.bz2 15:25:35 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.test_packages.json 15:25:35 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.crashreporter-symbols.zip 15:25:35 INFO - Running post-action listener: _resource_record_post_action 15:25:35 INFO - [mozharness: 2017-08-12 22:25:35.688114Z] Finished read-buildbot-config step (success) 15:25:35 INFO - [mozharness: 2017-08-12 22:25:35.688334Z] Running download-and-extract step. 15:25:35 INFO - Running pre-action listener: _resource_record_pre_action 15:25:35 INFO - Running main action method: download_and_extract 15:25:35 INFO - mkdir: /builds/slave/test/build/tests 15:25:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:35 INFO - trying https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.test_packages.json 15:25:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:25:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:25:36 INFO - Downloaded 989 bytes. 15:25:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:25:36 INFO - Using the following test package requirements: 15:25:36 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:25:36 INFO - u'common': [u'target.common.tests.zip'], 15:25:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:25:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:25:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:25:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:25:36 INFO - u'mozbase': [u'target.common.tests.zip'], 15:25:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:25:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:25:36 INFO - u'web-platform': [u'target.common.tests.zip', 15:25:36 INFO - u'target.web-platform.tests.tar.gz'], 15:25:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:25:36 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:25:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.common.tests.zip 15:25:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:25:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.common.tests.zip into memory 15:25:37 INFO - Content-Length response header: 54034459 15:25:37 INFO - Bytes received: 54034459 15:25:48 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:25:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.common.tests.zip 15:25:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:25:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.common.tests.zip into memory 15:25:49 INFO - Content-Length response header: 54034459 15:25:49 INFO - Bytes received: 54034459 15:26:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.talos.tests.zip 15:26:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:26:03 INFO - Fetch https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.talos.tests.zip into memory 15:26:04 INFO - Content-Length response header: 14496050 15:26:04 INFO - Bytes received: 14496050 15:26: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:26:04 INFO - trying https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.tar.bz2 15:26:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:26:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:26:05 INFO - Downloaded 61812574 bytes. 15:26:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.tar.bz2 15:26:05 INFO - mkdir: /builds/slave/test/properties 15:26:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:26:05 INFO - Writing to file /builds/slave/test/properties/build_url 15:26:05 INFO - Contents: 15:26:05 INFO - build_url:https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.tar.bz2 15:26:05 INFO - Running post-action listener: _resource_record_post_action 15:26:05 INFO - Running post-action listener: set_extra_try_arguments 15:26:05 INFO - [mozharness: 2017-08-12 22:26:05.572454Z] Finished download-and-extract step (success) 15:26:05 INFO - [mozharness: 2017-08-12 22:26:05.572552Z] Running populate-webroot step. 15:26:05 INFO - Running pre-action listener: _resource_record_pre_action 15:26:05 INFO - Running main action method: populate_webroot 15:26:05 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:26:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:26:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:26:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:26:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:26:05 INFO - 'tests': ['damp', 'tps']}, 15:26:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:26:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:26:05 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:26:05 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:26:05 INFO - 'other-e10s': {'tests': ['a11yr', 15:26:05 INFO - 'ts_paint', 15:26:05 INFO - 'tpaint', 15:26:05 INFO - 'sessionrestore', 15:26:05 INFO - 'sessionrestore_many_windows', 15:26:05 INFO - 'sessionrestore_no_auto_restore', 15:26:05 INFO - 'tabpaint']}, 15:26:05 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:26:05 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:26:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:26:05 INFO - 'tsvgr_opacity', 15:26:05 INFO - 'tart', 15:26:05 INFO - 'tscrollx', 15:26:05 INFO - 'tsvg_static']}, 15:26:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:26:05 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:26:05 INFO - 'talos_options': ['--mitmproxy', 15:26:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:26:05 INFO - '--firstNonBlankPaint'], 15:26:05 INFO - 'tests': ['tp6_google', 15:26:05 INFO - 'tp6_youtube', 15:26:05 INFO - 'tp6_amazon', 15:26:05 INFO - 'tp6_facebook']}, 15:26:05 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:26:05 INFO - 'talos_options': ['--stylo', 15:26:05 INFO - '--mitmproxy', 15:26:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:26:05 INFO - '--firstNonBlankPaint'], 15:26:05 INFO - 'tests': ['tp6_google', 15:26:05 INFO - 'tp6_youtube', 15:26:05 INFO - 'tp6_amazon', 15:26:05 INFO - 'tp6_facebook']}, 15:26:05 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:26:05 INFO - 'talos_options': ['--stylo', 15:26:05 INFO - '--stylo-threads=1', 15:26:05 INFO - '--mitmproxy', 15:26:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:26:05 INFO - '--firstNonBlankPaint'], 15:26:05 INFO - 'tests': ['tp6_google', 15:26:05 INFO - 'tp6_youtube', 15:26:05 INFO - 'tp6_amazon', 15:26:05 INFO - 'tp6_facebook']}, 15:26:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:26:05 INFO - 'talos_options': ['--xperf_path', 15:26:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:26:05 INFO - 'tests': ['tp5n']}}} 15:26:05 INFO - Running post-action listener: _resource_record_post_action 15:26:05 INFO - [mozharness: 2017-08-12 22:26:05.580878Z] Finished populate-webroot step (success) 15:26:05 INFO - [mozharness: 2017-08-12 22:26:05.580967Z] Running create-virtualenv step. 15:26:05 INFO - Running pre-action listener: _resource_record_pre_action 15:26:05 INFO - Running main action method: create_virtualenv 15:26:05 INFO - Creating virtualenv /builds/slave/test/build/venv 15:26:05 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:26:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:26:05 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:26:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:26:05 INFO - Using real prefix '/usr' 15:26:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:26:06 INFO - Installing distribute.............................................................................................................................................................................................done. 15:26:08 INFO - Installing pip.................done. 15:26:08 INFO - Return code: 0 15:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:26:08 INFO - Reading from file tmpfile_stdout 15:26:08 INFO - Output received: 15:26:08 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:26:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11a61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1372a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1513b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1516f00>, '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 0x1511ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1512050>, '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-1502556599.908738-475162972', '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:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:26:08 INFO - Using env: {'DISPLAY': ':0', 15:26:08 INFO - 'HOME': '/home/cltbld', 15:26:08 INFO - 'LANG': 'en_US.UTF-8', 15:26:08 INFO - 'LANGUAGE': 'en_US:en', 15:26:08 INFO - 'LOGNAME': 'cltbld', 15:26:08 INFO - 'MAIL': '/var/mail/cltbld', 15:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:08 INFO - 'MOZ_NO_REMOTE': '1', 15:26:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:08 INFO - 'NO_EM_RESTART': '1', 15:26:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:08 INFO - 'PWD': '/builds/slave/test', 15:26:08 INFO - 'SHELL': '/bin/bash', 15:26:08 INFO - 'SHLVL': '1', 15:26:08 INFO - 'TERM': 'linux', 15:26:08 INFO - 'TMOUT': '86400', 15:26:08 INFO - 'USER': 'cltbld', 15:26:08 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502556599.908738-475162972', 15:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:08 INFO - '_': '/tools/buildbot/bin/python'} 15:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:08 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:26:08 INFO - Cleaning up... 15:26:08 INFO - Return code: 0 15:26:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11a61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1372a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1513b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1516f00>, '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 0x1511ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1512050>, '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-1502556599.908738-475162972', '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:26:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:26:08 INFO - Using env: {'DISPLAY': ':0', 15:26:08 INFO - 'HOME': '/home/cltbld', 15:26:08 INFO - 'LANG': 'en_US.UTF-8', 15:26:08 INFO - 'LANGUAGE': 'en_US:en', 15:26:08 INFO - 'LOGNAME': 'cltbld', 15:26:08 INFO - 'MAIL': '/var/mail/cltbld', 15:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:08 INFO - 'MOZ_NO_REMOTE': '1', 15:26:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:08 INFO - 'NO_EM_RESTART': '1', 15:26:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:08 INFO - 'PWD': '/builds/slave/test', 15:26:08 INFO - 'SHELL': '/bin/bash', 15:26:08 INFO - 'SHLVL': '1', 15:26:08 INFO - 'TERM': 'linux', 15:26:08 INFO - 'TMOUT': '86400', 15:26:08 INFO - 'USER': 'cltbld', 15:26:08 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502556599.908738-475162972', 15:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:08 INFO - '_': '/tools/buildbot/bin/python'} 15:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:08 INFO - Downloading/unpacking psutil>=3.1.1 15:26: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:26: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:26: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:26: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:26:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:26:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:26:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:26:10 INFO - Installing collected packages: psutil 15:26:10 INFO - Running setup.py install for psutil 15:26:10 INFO - building 'psutil._psutil_linux' extension 15:26:10 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:26:10 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:26:10 INFO - building 'psutil._psutil_posix' extension 15:26:10 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:26:10 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:26:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:26:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:26:10 INFO - Successfully installed psutil 15:26:10 INFO - Cleaning up... 15:26:10 INFO - Return code: 0 15:26:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:26:10 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:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:10 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:26:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:10 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 0x11a61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1372a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1513b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1516f00>, '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 0x1511ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1512050>, '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-1502556599.908738-475162972', '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:26:10 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:26:10 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:26:10 INFO - Using env: {'DISPLAY': ':0', 15:26:10 INFO - 'HOME': '/home/cltbld', 15:26:10 INFO - 'LANG': 'en_US.UTF-8', 15:26:10 INFO - 'LANGUAGE': 'en_US:en', 15:26:10 INFO - 'LOGNAME': 'cltbld', 15:26:10 INFO - 'MAIL': '/var/mail/cltbld', 15:26:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:10 INFO - 'MOZ_NO_REMOTE': '1', 15:26:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:10 INFO - 'NO_EM_RESTART': '1', 15:26:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:10 INFO - 'PWD': '/builds/slave/test', 15:26:10 INFO - 'SHELL': '/bin/bash', 15:26:10 INFO - 'SHLVL': '1', 15:26:10 INFO - 'TERM': 'linux', 15:26:10 INFO - 'TMOUT': '86400', 15:26:10 INFO - 'USER': 'cltbld', 15:26:10 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502556599.908738-475162972', 15:26:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:10 INFO - '_': '/tools/buildbot/bin/python'} 15:26:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:26:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:26:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:26:11 INFO - Installing collected packages: mozsystemmonitor 15:26:11 INFO - Running setup.py install for mozsystemmonitor 15:26:12 INFO - Successfully installed mozsystemmonitor 15:26:12 INFO - Cleaning up... 15:26:12 INFO - Return code: 0 15:26:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:26: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:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26: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:26:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11a61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1372a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1513b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1516f00>, '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 0x1511ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1512050>, '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-1502556599.908738-475162972', '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:26:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:26:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:26:12 INFO - Using env: {'DISPLAY': ':0', 15:26:12 INFO - 'HOME': '/home/cltbld', 15:26:12 INFO - 'LANG': 'en_US.UTF-8', 15:26:12 INFO - 'LANGUAGE': 'en_US:en', 15:26:12 INFO - 'LOGNAME': 'cltbld', 15:26:12 INFO - 'MAIL': '/var/mail/cltbld', 15:26:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:12 INFO - 'MOZ_NO_REMOTE': '1', 15:26:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:12 INFO - 'NO_EM_RESTART': '1', 15:26:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:12 INFO - 'PWD': '/builds/slave/test', 15:26:12 INFO - 'SHELL': '/bin/bash', 15:26:12 INFO - 'SHLVL': '1', 15:26:12 INFO - 'TERM': 'linux', 15:26:12 INFO - 'TMOUT': '86400', 15:26:12 INFO - 'USER': 'cltbld', 15:26:12 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502556599.908738-475162972', 15:26:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:12 INFO - '_': '/tools/buildbot/bin/python'} 15:26:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:12 INFO - Downloading/unpacking jsonschema==2.5.1 15:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:26:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:26:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:26:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:26:13 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:26:13 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:26:13 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:26:13 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:26:13 INFO - Downloading functools32-3.2.3-2.tar.gz 15:26:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:26:13 INFO - warning: no files found matching '*.txt' 15:26:13 INFO - no previously-included directories found matching 'build' 15:26:13 INFO - no previously-included directories found matching 'dist' 15:26:13 INFO - no previously-included directories found matching '.git*' 15:26:13 INFO - Installing collected packages: jsonschema, functools32 15:26:13 INFO - Running setup.py install for functools32 15:26:13 INFO - warning: no files found matching '*.txt' 15:26:13 INFO - no previously-included directories found matching 'build' 15:26:13 INFO - no previously-included directories found matching 'dist' 15:26:13 INFO - no previously-included directories found matching '.git*' 15:26:13 INFO - Successfully installed jsonschema functools32 15:26:13 INFO - Cleaning up... 15:26:13 INFO - Return code: 0 15:26:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:26:13 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:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:13 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:26:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:13 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 0x11a61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1372a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1513b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1516f00>, '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 0x1511ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1512050>, '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-1502556599.908738-475162972', '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:26:13 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:26:13 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:26:13 INFO - Using env: {'DISPLAY': ':0', 15:26:13 INFO - 'HOME': '/home/cltbld', 15:26:13 INFO - 'LANG': 'en_US.UTF-8', 15:26:13 INFO - 'LANGUAGE': 'en_US:en', 15:26:13 INFO - 'LOGNAME': 'cltbld', 15:26:13 INFO - 'MAIL': '/var/mail/cltbld', 15:26:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:13 INFO - 'MOZ_NO_REMOTE': '1', 15:26:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:13 INFO - 'NO_EM_RESTART': '1', 15:26:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:13 INFO - 'PWD': '/builds/slave/test', 15:26:13 INFO - 'SHELL': '/bin/bash', 15:26:13 INFO - 'SHLVL': '1', 15:26:13 INFO - 'TERM': 'linux', 15:26:13 INFO - 'TMOUT': '86400', 15:26:13 INFO - 'USER': 'cltbld', 15:26:13 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502556599.908738-475162972', 15:26:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:13 INFO - '_': '/tools/buildbot/bin/python'} 15:26:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:26:13 INFO - Cleaning up... 15:26:14 INFO - Return code: 0 15:26:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:26:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11a61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1372a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1513b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1516f00>, '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 0x1511ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1512050>, '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-1502556599.908738-475162972', '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:26:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:26:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:26:14 INFO - Using env: {'DISPLAY': ':0', 15:26:14 INFO - 'HOME': '/home/cltbld', 15:26:14 INFO - 'LANG': 'en_US.UTF-8', 15:26:14 INFO - 'LANGUAGE': 'en_US:en', 15:26:14 INFO - 'LOGNAME': 'cltbld', 15:26:14 INFO - 'MAIL': '/var/mail/cltbld', 15:26:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:14 INFO - 'MOZ_NO_REMOTE': '1', 15:26:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:14 INFO - 'NO_EM_RESTART': '1', 15:26:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:14 INFO - 'PWD': '/builds/slave/test', 15:26:14 INFO - 'SHELL': '/bin/bash', 15:26:14 INFO - 'SHLVL': '1', 15:26:14 INFO - 'TERM': 'linux', 15:26:14 INFO - 'TMOUT': '86400', 15:26:14 INFO - 'USER': 'cltbld', 15:26:14 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502556599.908738-475162972', 15:26:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:14 INFO - '_': '/tools/buildbot/bin/python'} 15:26:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:14 INFO - Downloading/unpacking blobuploader==1.2.4 15:26: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:26: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:26: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:26: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:26:15 INFO - Downloading blobuploader-1.2.4.tar.gz 15:26:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:26:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:26:15 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:26:15 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:26:15 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:26:15 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:26:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:26:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:26:15 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:26:15 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:26:15 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:26:15 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:26:15 INFO - Downloading docopt-0.6.1.tar.gz 15:26:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:26:15 INFO - Installing collected packages: blobuploader, requests, docopt 15:26:15 INFO - Running setup.py install for blobuploader 15:26:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:26:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:26:15 INFO - Running setup.py install for requests 15:26:16 INFO - Running setup.py install for docopt 15:26:16 INFO - Successfully installed blobuploader requests docopt 15:26:16 INFO - Cleaning up... 15:26:16 INFO - Return code: 0 15:26:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:16 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 0x11a61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1372a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1513b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1516f00>, '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 0x1511ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1512050>, '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-1502556599.908738-475162972', '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:26:16 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:26:16 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:26:16 INFO - Using env: {'DISPLAY': ':0', 15:26:16 INFO - 'HOME': '/home/cltbld', 15:26:16 INFO - 'LANG': 'en_US.UTF-8', 15:26:16 INFO - 'LANGUAGE': 'en_US:en', 15:26:16 INFO - 'LOGNAME': 'cltbld', 15:26:16 INFO - 'MAIL': '/var/mail/cltbld', 15:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:16 INFO - 'MOZ_NO_REMOTE': '1', 15:26:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:16 INFO - 'NO_EM_RESTART': '1', 15:26:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:16 INFO - 'PWD': '/builds/slave/test', 15:26:16 INFO - 'SHELL': '/bin/bash', 15:26:16 INFO - 'SHLVL': '1', 15:26:16 INFO - 'TERM': 'linux', 15:26:16 INFO - 'TMOUT': '86400', 15:26:16 INFO - 'USER': 'cltbld', 15:26:16 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502556599.908738-475162972', 15:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:16 INFO - '_': '/tools/buildbot/bin/python'} 15:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:26:16 INFO - Running setup.py (path:/tmp/pip-eWyiOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:26:16 INFO - Running setup.py (path:/tmp/pip-9PC0dd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:26:16 INFO - Running setup.py (path:/tmp/pip-qWfrE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:26:16 INFO - Running setup.py (path:/tmp/pip-dm9hB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:26:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:26:16 INFO - Running setup.py (path:/tmp/pip-LhwJRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:26:17 INFO - Running setup.py (path:/tmp/pip-PmfSrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:26:17 INFO - Running setup.py (path:/tmp/pip-wmk4Ek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:26:17 INFO - Running setup.py (path:/tmp/pip-6vr7ch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:26:17 INFO - Running setup.py (path:/tmp/pip-TRPLOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:26:17 INFO - Running setup.py (path:/tmp/pip-Z1FdwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:26:17 INFO - Running setup.py (path:/tmp/pip-GzCKCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:26:17 INFO - Running setup.py (path:/tmp/pip-fKDyP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:26:17 INFO - Running setup.py (path:/tmp/pip-QVkDro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:26:17 INFO - Running setup.py (path:/tmp/pip-4noRNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:26:17 INFO - Running setup.py (path:/tmp/pip-ztkuoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:26:17 INFO - Running setup.py (path:/tmp/pip-z_4zbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:26:17 INFO - Running setup.py (path:/tmp/pip-zaNXOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:26:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:26:17 INFO - Running setup.py install for manifestparser 15:26:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:26:17 INFO - Running setup.py install for mozcrash 15:26:18 INFO - Running setup.py install for mozdebug 15:26:18 INFO - Running setup.py install for mozdevice 15:26:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:26:18 INFO - Running setup.py install for mozfile 15:26:18 INFO - Running setup.py install for mozhttpd 15:26:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:26:18 INFO - Running setup.py install for mozinfo 15:26:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:26:18 INFO - Running setup.py install for mozInstall 15:26:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:26:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:26:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:26:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:26:18 INFO - Running setup.py install for mozleak 15:26:18 INFO - Running setup.py install for mozlog 15:26:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:26:18 INFO - Running setup.py install for moznetwork 15:26:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:26:19 INFO - Running setup.py install for mozprocess 15:26:19 INFO - Running setup.py install for mozprofile 15:26:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:26:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:26:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:26:19 INFO - Running setup.py install for mozrunner 15:26:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:26:19 INFO - Running setup.py install for mozscreenshot 15:26:19 INFO - Running setup.py install for moztest 15:26:19 INFO - Running setup.py install for mozversion 15:26:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:26:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:26:19 INFO - Cleaning up... 15:26:19 INFO - Return code: 0 15:26:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:26:19 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:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:19 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:26:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:19 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 0x11a61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1372a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1513b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1516f00>, '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 0x1511ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1512050>, '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-1502556599.908738-475162972', '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:26:19 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:26:19 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:26:19 INFO - Using env: {'DISPLAY': ':0', 15:26:19 INFO - 'HOME': '/home/cltbld', 15:26:19 INFO - 'LANG': 'en_US.UTF-8', 15:26:19 INFO - 'LANGUAGE': 'en_US:en', 15:26:19 INFO - 'LOGNAME': 'cltbld', 15:26:19 INFO - 'MAIL': '/var/mail/cltbld', 15:26:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:19 INFO - 'MOZ_NO_REMOTE': '1', 15:26:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:19 INFO - 'NO_EM_RESTART': '1', 15:26:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:19 INFO - 'PWD': '/builds/slave/test', 15:26:19 INFO - 'SHELL': '/bin/bash', 15:26:19 INFO - 'SHLVL': '1', 15:26:19 INFO - 'TERM': 'linux', 15:26:19 INFO - 'TMOUT': '86400', 15:26:19 INFO - 'USER': 'cltbld', 15:26:19 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502556599.908738-475162972', 15:26:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:19 INFO - '_': '/tools/buildbot/bin/python'} 15:26:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:26:19 INFO - Running setup.py (path:/tmp/pip-WISP95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:26:19 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:26:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:26:19 INFO - Running setup.py (path:/tmp/pip-ChYZR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:26:20 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:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:26:20 INFO - Running setup.py (path:/tmp/pip-NMg_9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:26:20 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:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:26:20 INFO - Running setup.py (path:/tmp/pip-PWz2l5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:26:20 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:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:26:20 INFO - Running setup.py (path:/tmp/pip-XzQu1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:26:20 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:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:26:20 INFO - Running setup.py (path:/tmp/pip-Pq2DWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:26:20 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:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:26:20 INFO - Running setup.py (path:/tmp/pip-PBJdyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:26:20 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:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:26:20 INFO - Running setup.py (path:/tmp/pip-X_byAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:26:20 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:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:26:20 INFO - Running setup.py (path:/tmp/pip-ftuKxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:26:20 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:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:26:20 INFO - Running setup.py (path:/tmp/pip-Xbhou3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:26:20 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:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:26:20 INFO - Running setup.py (path:/tmp/pip-O3CJaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:26:20 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:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:26:20 INFO - Running setup.py (path:/tmp/pip-QXcfQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:26:20 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:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:26:20 INFO - Running setup.py (path:/tmp/pip-E7LIe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:26:20 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:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:26:20 INFO - Running setup.py (path:/tmp/pip-1leM6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:26:20 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:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:26:20 INFO - Running setup.py (path:/tmp/pip-qF8_Hh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:26:20 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:26:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:26:20 INFO - Running setup.py (path:/tmp/pip-ICaAte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:26:21 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:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:26:21 INFO - Running setup.py (path:/tmp/pip-nF3Ke3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:26:21 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:26:21 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:26:21 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:26:21 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:26:21 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:26:21 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:26:21 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:26:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:26:21 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:26:21 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:26:21 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:26:21 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:26:22 INFO - Downloading blessings-1.6.tar.gz 15:26:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:26:22 INFO - Installing collected packages: blessings 15:26:22 INFO - Running setup.py install for blessings 15:26:22 INFO - Successfully installed blessings 15:26:22 INFO - Cleaning up... 15:26:22 INFO - Return code: 0 15:26:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:26:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:26:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:26:22 INFO - Reading from file tmpfile_stdout 15:26:22 INFO - Current package versions: 15:26:22 INFO - argparse == 1.2.1 15:26:22 INFO - blessings == 1.6 15:26:22 INFO - blobuploader == 1.2.4 15:26:22 INFO - docopt == 0.6.1 15:26:22 INFO - functools32 == 3.2.3-2 15:26:22 INFO - jsonschema == 2.5.1 15:26:22 INFO - manifestparser == 1.1 15:26:22 INFO - mozInstall == 1.13 15:26:22 INFO - mozcrash == 1.0 15:26:22 INFO - mozdebug == 0.1 15:26:22 INFO - mozdevice == 0.51 15:26:22 INFO - mozfile == 1.2 15:26:22 INFO - mozhttpd == 0.7 15:26:22 INFO - mozinfo == 0.10 15:26:22 INFO - mozleak == 0.1 15:26:22 INFO - mozlog == 3.5 15:26:22 INFO - moznetwork == 0.27 15:26:22 INFO - mozprocess == 0.25 15:26:22 INFO - mozprofile == 0.28 15:26:22 INFO - mozrunner == 6.13 15:26:22 INFO - mozscreenshot == 0.1 15:26:22 INFO - mozsystemmonitor == 0.3 15:26:22 INFO - moztest == 0.8 15:26:22 INFO - mozversion == 1.4 15:26:22 INFO - psutil == 3.1.1 15:26:22 INFO - requests == 1.2.3 15:26:22 INFO - wsgiref == 0.1.2 15:26:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:26:22 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:26:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:22 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:26:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:22 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 0x11a61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1372a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1513b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1516f00>, '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 0x1511ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1512050>, '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-1502556599.908738-475162972', '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:26:22 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:26:22 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:26:22 INFO - Using env: {'DISPLAY': ':0', 15:26:22 INFO - 'HOME': '/home/cltbld', 15:26:22 INFO - 'LANG': 'en_US.UTF-8', 15:26:22 INFO - 'LANGUAGE': 'en_US:en', 15:26:22 INFO - 'LOGNAME': 'cltbld', 15:26:22 INFO - 'MAIL': '/var/mail/cltbld', 15:26:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:22 INFO - 'MOZ_NO_REMOTE': '1', 15:26:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:22 INFO - 'NO_EM_RESTART': '1', 15:26:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:22 INFO - 'PWD': '/builds/slave/test', 15:26:22 INFO - 'SHELL': '/bin/bash', 15:26:22 INFO - 'SHLVL': '1', 15:26:22 INFO - 'TERM': 'linux', 15:26:22 INFO - 'TMOUT': '86400', 15:26:22 INFO - 'USER': 'cltbld', 15:26:22 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502556599.908738-475162972', 15:26:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:22 INFO - '_': '/tools/buildbot/bin/python'} 15:26:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:22 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:26:22 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:26:22 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:26:22 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:26:22 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:26:22 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:26:22 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:26:22 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:26:22 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:26:22 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:26:22 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:26:22 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:26:22 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:26:22 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:26:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:26:24 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:26:24 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:26:24 INFO - Installing collected packages: simplejson 15:26:24 INFO - Running setup.py install for simplejson 15:26:24 INFO - building 'simplejson._speedups' extension 15:26:24 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:26:24 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:26:25 INFO - Successfully installed simplejson 15:26:25 INFO - Cleaning up... 15:26:25 INFO - Return code: 0 15:26:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:26:25 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 0x11a61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1372a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1513b20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1516f00>, '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 0x1511ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1512050>, '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-1502556599.908738-475162972', '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:26:25 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:26:25 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:26:25 INFO - Using env: {'DISPLAY': ':0', 15:26:25 INFO - 'HOME': '/home/cltbld', 15:26:25 INFO - 'LANG': 'en_US.UTF-8', 15:26:25 INFO - 'LANGUAGE': 'en_US:en', 15:26:25 INFO - 'LOGNAME': 'cltbld', 15:26:25 INFO - 'MAIL': '/var/mail/cltbld', 15:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:25 INFO - 'MOZ_NO_REMOTE': '1', 15:26:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:25 INFO - 'NO_EM_RESTART': '1', 15:26:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:25 INFO - 'PWD': '/builds/slave/test', 15:26:25 INFO - 'SHELL': '/bin/bash', 15:26:25 INFO - 'SHLVL': '1', 15:26:25 INFO - 'TERM': 'linux', 15:26:25 INFO - 'TMOUT': '86400', 15:26:25 INFO - 'USER': 'cltbld', 15:26:25 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502556599.908738-475162972', 15:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:25 INFO - '_': '/tools/buildbot/bin/python'} 15:26:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:26:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:26:25 INFO - Cleaning up... 15:26:25 INFO - Return code: 0 15:26:25 INFO - Running post-action listener: _resource_record_post_action 15:26:25 INFO - Running post-action listener: _start_resource_monitoring 15:26:25 INFO - Starting resource monitoring. 15:26:25 INFO - [mozharness: 2017-08-12 22:26:25.397931Z] Finished create-virtualenv step (success) 15:26:25 INFO - [mozharness: 2017-08-12 22:26:25.398394Z] Running install step. 15:26:25 INFO - Running pre-action listener: _resource_record_pre_action 15:26:25 INFO - Running main action method: install 15:26:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:26:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:26:25 INFO - Reading from file tmpfile_stdout 15:26:25 INFO - Detecting whether we're running mozinstall >=1.0... 15:26:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:26:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:26:25 INFO - Reading from file tmpfile_stdout 15:26:25 INFO - Output received: 15:26:25 INFO - Usage: mozinstall [options] installer 15:26:25 INFO - Options: 15:26:25 INFO - -h, --help show this help message and exit 15:26:25 INFO - -d DEST, --destination=DEST 15:26:25 INFO - Directory to install application into. [default: 15:26:25 INFO - "/builds/slave/test"] 15:26:25 INFO - --app=APP Application being installed. [default: firefox] 15:26:25 INFO - mkdir: /builds/slave/test/build/application 15:26:25 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:26:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:26:43 INFO - Reading from file tmpfile_stdout 15:26:43 INFO - Output received: 15:26:43 INFO - /builds/slave/test/build/application/firefox/firefox 15:26:43 INFO - Running post-action listener: _resource_record_post_action 15:26:43 INFO - [mozharness: 2017-08-12 22:26:43.800622Z] Finished install step (success) 15:26:43 INFO - [mozharness: 2017-08-12 22:26:43.800876Z] Running setup-mitmproxy step. 15:26:43 INFO - Running pre-action listener: _resource_record_pre_action 15:26:43 INFO - Running main action method: setup_mitmproxy 15:26:43 INFO - Skipping: mitmproxy is not required 15:26:43 INFO - Running post-action listener: _resource_record_post_action 15:26:43 INFO - [mozharness: 2017-08-12 22:26:43.802084Z] Finished setup-mitmproxy step (success) 15:26:43 INFO - [mozharness: 2017-08-12 22:26:43.802306Z] Running run-tests step. 15:26:43 INFO - Running pre-action listener: _resource_record_pre_action 15:26:43 INFO - Running pre-action listener: _set_gcov_prefix 15:26:43 INFO - Running main action method: run_tests 15:26:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:26:43 INFO - Python 2.7.3 15:26:43 INFO - Return code: 0 15:26:43 INFO - grabbing minidump binary from tooltool 15:26:43 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:26:43 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 0x1516f00>, '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 0x1511ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1512050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:26:43 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:26:43 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:26:43 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:26:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:26:43 INFO - Return code: 0 15:26:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:26:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:26:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:26:43 INFO - ENV: RUST_BACKTRACE is now full 15:26:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:26:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:26:43 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/Yx2HVl5oTtyjs-iuTJgx8w/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:26:43 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/Yx2HVl5oTtyjs-iuTJgx8w/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:26:43 INFO - Using env: {'DISPLAY': ':0', 15:26:43 INFO - 'HOME': '/home/cltbld', 15:26:43 INFO - 'LANG': 'en_US.UTF-8', 15:26:43 INFO - 'LANGUAGE': 'en_US:en', 15:26:43 INFO - 'LOGNAME': 'cltbld', 15:26:43 INFO - 'MAIL': '/var/mail/cltbld', 15:26:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:26:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:26:43 INFO - 'MOZ_NO_REMOTE': '1', 15:26:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:26:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:26:43 INFO - 'NO_EM_RESTART': '1', 15:26:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:26:43 INFO - 'PWD': '/builds/slave/test', 15:26:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:26:43 INFO - 'RUST_BACKTRACE': 'full', 15:26:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:26:43 INFO - 'SHELL': '/bin/bash', 15:26:43 INFO - 'SHLVL': '1', 15:26:43 INFO - 'STYLO_FORCE_ENABLED': '1', 15:26:43 INFO - 'TERM': 'linux', 15:26:43 INFO - 'TMOUT': '86400', 15:26:43 INFO - 'USER': 'cltbld', 15:26:43 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1502556599.908738-475162972', 15:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:26:43 INFO - '_': '/tools/buildbot/bin/python'} 15:26:43 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/Yx2HVl5oTtyjs-iuTJgx8w/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:26:44 INFO - mozversion application_buildid: 20170812213510 15:26:44 INFO - mozversion application_changeset: 6062341662fcee683434ee71539bfa10964c022e 15:26:44 INFO - mozversion application_display_name: Nightly 15:26:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:26:44 INFO - mozversion application_name: Firefox 15:26:44 INFO - mozversion application_remotingname: firefox 15:26:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:26:44 INFO - mozversion application_vendor: Mozilla 15:26:44 INFO - mozversion application_version: 57.0a1 15:26:44 INFO - mozversion platform_buildid: 20170812213510 15:26:44 INFO - mozversion platform_changeset: 6062341662fcee683434ee71539bfa10964c022e 15:26:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:26:44 INFO - mozversion platform_version: 57.0a1 15:26:44 INFO - using testdate: 1502576804 15:26:44 INFO - actual date: 1502576804 15:26:44 INFO - starting webserver on 'localhost:37752' 15:26:44 INFO - SUITE-START | Running 2 tests 15:26:44 INFO - TEST-START | dromaeo_css 15:26:44 INFO - Initialising browser for dromaeo_css test... 15:26:44 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37752/getInfo.html -profile /tmp/tmpIIADqi/profile 15:26:44 INFO - TEST-INFO | started process 5479 (/builds/slave/test/build/application/firefox/firefox http://localhost:37752/getInfo.html) 15:26:50 INFO - TEST-INFO | 5479: exit 0 15:26:50 INFO - Browser initialized. 15:26:50 INFO - Running cycle 1/1 for dromaeo_css test... 15:26:50 INFO - TEST-INFO | started process 5715 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIIADqi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:26:51 INFO - PID 5715 | 15:26:51 INFO - PID 5715 | (/builds/slave/test/build/application/firefox/firefox:5770): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:26:51 INFO - PID 5715 | 15:27:02 INFO - PID 5715 | RSS: Main: 168321024 15:27:02 INFO - PID 5715 | 15:30:01 INFO - PID 5715 | Cycle 1(1): loaded http://localhost:37752/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37752/tests/dromaeo/cssquery-ext.html) 15:30:03 INFO - PID 5715 | RSS: Main: 175403008 15:30:03 INFO - PID 5715 | 15:33:05 INFO - PID 5715 | Cycle 1(1): loaded http://localhost:37752/tests/dromaeo/cssquery-ext.html (next: http://localhost:37752/tests/dromaeo/cssquery-jquery.html) 15:33:05 INFO - PID 5715 | RSS: Main: 171192320 15:33:05 INFO - PID 5715 | 15:36:07 INFO - PID 5715 | Cycle 1(1): loaded http://localhost:37752/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37752/tests/dromaeo/cssquery-mootools.html) 15:36:08 INFO - PID 5715 | RSS: Main: 174940160 15:36:08 INFO - PID 5715 | 15:39:10 INFO - PID 5715 | Cycle 1(1): loaded http://localhost:37752/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37752/tests/dromaeo/cssquery-prototype.html) 15:39:10 INFO - PID 5715 | RSS: Main: 173801472 15:39:10 INFO - PID 5715 | 15:42:14 INFO - PID 5715 | Cycle 1(1): loaded http://localhost:37752/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37752/tests/dromaeo/cssquery-yui.html) 15:42:14 INFO - PID 5715 | RSS: Main: 176652288 15:42:14 INFO - PID 5715 | 15:45:16 INFO - PID 5715 | Cycle 1(1): loaded http://localhost:37752/tests/dromaeo/cssquery-yui.html (next: http://localhost:37752/tests/dromaeo/cssquery-dojo.html) 15:45:16 INFO - PID 5715 | RSS: Main: 177446912 15:45:16 INFO - PID 5715 | 15:45:16 INFO - PID 5715 | __start_tp_report 15:45:16 INFO - PID 5715 | _x_x_mozilla_page_load 15:45:16 INFO - PID 5715 | _x_x_mozilla_page_load_details 15:45:16 INFO - PID 5715 | |i|pagename|runs| 15:45:16 INFO - PID 5715 | |0;dojo.html;3146.889858854939;3149.291409432877;3162.6679198684133;3168.4930411134224;3182.506711459723;6147.446729794317;6180.1656776335185;6264.342244064376;6276.686165691714;6311.431971122598;6201;6261.279952805428;6272.3100458949475;6285.9057114143325;6287.528435367354;5685.829425117253;5738.626989245696;5754.74103665336;5761.107028410603;5831.183634291203;5739.770409183649;5809.7676092956235;5819.476247137757;5834.358220595752;5867.207926929853;5549.694766787825;5659.688717120557;5748.396418376094;5749.223854779614;5842.649441033552;5618.2415373924405;5716.399778023331;5718.170865224519;5794.362620111784;5807.186993820868;5669.177969194485;5712.314522257356;5759.568032397545;5774.16274640179;5792.333881603628;6056.757729690807;6118.357572454873;6155.569110162291;6163.352847950945;6232.158658581079;6706.731730730765;6713.295104014057;6722.865542689167;6730.663466826688;6746.59520428771;6748.763793267212;6821.317868213188;6833.658317084176;6841.2132604750595;6878.140232470942;6164.907526387108;6165.969170154121;6220.440160385587;6261.78083767066;6322.956712142537;6900.413464855544;6910;6920.342567456084;6926.411255043377;6929.272426395257;6441.838954026093;6501.284858665637;6527.314631963575;6577.506686998494;6581.341865813476;6670.199576050808;6704.966475167593;6715.731370745116;6740.83147921296;6761.256261811197;6668.333166683293;6740.325967403319;6754.223264324567;6757.087793147961;6763.357481039392;6732.562383445061;6739.93260067393;6744.966275168593;6819.659017049128;6833.760818371333;5316.069687804618;5351.491608297206;5407.270018547446;5422.75597598109;5425.077736784756;4656.138614356355;4686.484486706459;4714.127886341038;4718.363020992191;4739.218029025241;5774.711264436845;5851.444112809277;5856.648601083949;5859.94140058594;5862.091375836752;6724.462043036643;6734.46124310054;6804.08144900442;6826.658667066627;6838.213605435439;5646.85882852932;5731.914021289684;5738.741756620962;5742.454466825645;5744.684042377626;5869;5961.493273071837;5964.463198312086;5972.940270597325;6002.759889604367;6409.327020662857;6588.472922166311;6595.538312318187;6597.80206593803;6607.405333520006;6721.966390168018;6744.887093629496;6752.493562982698;6852.657367131524;6855.725770969106;6286.18279623646;6321.683915804283;6366.681665916593;6403.775867844603;6405.295417504164;6648.335166483313;6664.433523150393;6669.699863506251;6686.364795344434;6704.229013663491;6751.56114852533;6755.628440435823;6763.5941843489545;6848.424862281603;6876.587404755731;6380.202474690664;6547.70535325921;6552.868942621216;6632.535722499379;6655.633940133145;6481.805545833632;6489.4808415325715;6490.513211509061;6495.805125846232;6509.251436084911;6367.395097465734;6367.554271200971;6387.489000880011;6401.135846660642;6410.935890641033;6585.374389433187;6605.339466053356;6633.9668301658185;6675.532712710258;6687.097241872285;6303.842403939938;6416.13382193398;6416.230052393743;6419.839504012437;6439.388258115471 15:45:16 INFO - PID 5715 | |1;ext.html;804.4851295171092;866.7139843851528;886.9423487473312;894.3873446689018;1152.4698638626232;28438.57342139867;30010.24974375639;39773.602263977315;40404.19191616166;40498.59501404985;41359.17281654365;41520.37719434211;41536;41815.16369672605;41829.37255941163;7145.678444469985;7485.401167906568;7600.809979750496;7607;7636.618169091551;7187.676554555058;7671.501352412075;7680.500767450098;7682.961585192066;7697.538147711127;7666.540007599534;7694.30751232389;7697.846043079135;7713.460057795956;7740.690372385126;7580.848383032336;7622.161562228151;7635.618219089051;7657.731979380751;7671.846563068735;7498.512596681198;7560.054993125859;7561.659725312375;7565.924340756577;7566.962165189138;37842;37900.620993789984;38008;38250;38450.61549384526;3568.750937171995;3579.1767893384667;3612.4942508048894;3614.7650402724003;3616.8553257869653;31165.532517012263;31188.220294492592;31203;31302.217444564067;31406.05781826549;4981.153203955337;5037.093323201822;5054.444011158769;5083.161278389061;5090.389153301628;197197;198785;198848.99999999854;199029;199635;47984;48362;48586;48608;48687.269690954665;50192.749036255314;50454;50628.240576391385;50657.24014139791;50749.23876141861;15467.5359739208;15807.762883556756;15842.52472425829;15846.920765396098;15849.841501585068;11258.549658013751;11529.884701153049;13005.674858128623;13020.283884386261;13025.869741302655;14115.153090814583;14180;14303.213323267111;14368.784468232985;14381.64045898861;5789.305283366023;5828.88342233153;5868.767558812697;5904.586678932519;5946.048632218824;998.7503124218945;1011.0950699124243;1012.9746756331151;1025.5077562769911;1029.1509504658686;22673.773262267496;23299.7670023301;23401.531969360516;23433.41416464602;23460.296191114296;1111.8332250162541;1117.474786850179;1118.647625997808;1120.8991190792706;1127.9943600281947;12000.279983201035;12506.562270320495;12537.498500060077;12600.306983116017;12624.87375126237;233092;234696;235176;235847;236200;53281.667958300575;53525.46474535283;53588.46411535913;53661.1950820738;53880.730596346766;1446.5877224990827;1472.0358165401678;1472.1387988027125;1475.1960181701;1482.866542011224;22677.886610566842;23016.654750178426;23165.42086447785;23256.76743232546;23505.882470587538;37539.62460375361;37599.06002349854;37614.62385376111;37907.62092379151;37927.43108853314;194243;195367;196058;197623;197806;33544.49683254802;33623;33786.49320260247;35136.47295290624;36057.27885442329;44747.32879006752;46148;46313;46597.53402466067;46737.53262467468;33302;33808.15479613029;33898;33948;34508.82745586256;33415.83292083524;33593.83203083969;33883.15292117717;34467;34676.82661586676;35977.280454391286;36184;36436.635633644386;36678.63321366859;36812.81593592122;32361.676383235867;32469;33107.834460827544;34500.82749586236;34537;24734.876325618257;24938.62592061084;25160.874195629636;25549;25587 15:45:16 INFO - PID 5715 | |2;jquery.html;569.3736889421637;577.1342985521718;578.4822583787513;593.1902952469877;594.2097010975402;22056.558868822613;22204.1118355266;22895.771042289594;22905.198318058872;22934;24962.500749985036;26497.470050598975;26538.6019209712;26609;26774.19677409676;6252.906206406896;6420.871582568369;6422.4862011039;6433.485321174295;6447.7098530566;6097.237845269341;6276.466500347476;6326.398992095744;6419.614823110605;6428.646424446653;6384.297727249998;6385.553011289212;6392.20097487814;6392.4886009119155;6395.584287021352;6367.07677386781;6376.936230637681;6377.457916077115;6378.968105159468;6383.425491705745;5941.851453713649;5946.940530594725;5953.166556682068;5957.225560677084;5969.910451343233;29737.70262297371;30249.395012099634;30345.696543034508;30858.382832343454;31461.528077078863;1980.8514361422951;2022.3730643500562;2029.482481967089;2030.4619275891753;2031.3499680102373;18745.34391296312;19130.80869191304;20183.39449816508;20185.697214541793;20200.69698954517;2396.412878844677;2412.915547955831;2450.2404254681105;2460.335709358481;2477.306354220821;143748;144643;147750.26124869415;152742.9999999989;154164;14701.632459188606;14788.528244549585;14909.850901490847;15375.615609609626;15628.609284767972;23917.16289929844;23997.640035399483;24000.51998960011;24007.759922400903;24159.51680966371;9507.334486585873;9872.40765554069;9890.307678462583;9894.208463322917;10104.898951010542;9117.908820911838;9180.908190918139;9270.397424167408;9274.489903055262;9409.670661526814;9422.293328000427;9476.336656434121;9503.619855205714;9624.230061595055;9645.469499177612;2829.915102546927;2847.402045570412;2871.196065101775;2883.68279489256;2976.583278341034;904.841652710773;913.4930113786779;953.4041224234853;986.3292960786733;990.6879333010079;19901.402957911287;19993.600127997357;20084;20146.071516054522;20569;1020;1026.8665073540392;1053.7892421515728;1055.2560444886337;1060.7719340341864;11330.546778128783;11349.54601815918;11391.430428478543;11442.656720298404;11542.711432214093;526879;533315;534672;540409;544271;125503;126960;129028;129567.35216324046;129814;1191.3149938785234;1206.457094307565;1222.4437880764408;1226.907981901361;1229.0904730499367;17797.644047118727;17844.19701113427;18099.72850407271;18194.181261842983;18410;59851;60481.39518604758;61473.692631536556;63928;68244.6587767058;78082.6095869517;81822;82499.5875020645;82598.17401825904;84137;21942.780572194075;22019.889900550395;22393.552128957002;22647.547049059256;22677.43306417353;24229;24279.51440971206;24406.389840254134;24473.265802025966;24935.62596561017;18859;19279.903600481906;19401.902990484956;19471.70792438143;20058.197672092952;16859.1570421476;17216.225269862636;17924.462266132035;18090.09549522545;18216.089195539906;19358.128884199963;19895.303664371677;20272.37820974348;20330.59338813245;20416;20683.27608533694;21731.674024889955;21749.130034798432;21850;21901.233456828933;18077.18652660607;18182;18184.45446636603;18204;19462.805371946666 15:45:16 INFO - PID 5715 | |3;mootools.html;1314.0998416084983;1340.577718018824;1366.8496465388812;1382.7511048011356;1386.9098508596946;14024.088434251766;14285.714285714279;14595.489157879476;14603.78094328586;14624.56126316211;14820.777688334683;15113.697726045473;15128;15136.243187840619;15163.848361516382;2735.411886444414;2736.7536921677047;2756.572731226663;2769.639946806912;2772.0575004498464;2662.7470390312888;2764.557670772677;2831.4761769072684;2831.6318878545762;2831.6602007759097;2674.3447855275385;2680.839149651027;2711.9864400678066;2714.1179116787025;2715;2673.4786716590274;2682.6646669166353;2706;2710.593410988348;2725.2641786717477;2497.7876880465183;2519.5842685956795;2528.582783840664;2615.2285075902655;2699.6895357033995;14216.502422415166;14239.928800356036;14362.569122926225;14366.71266574673;14369.64075898111;7307.561546307238;7602;7607.239276072349;7622.047244094489;7635.701930671799;5190.455002224788;5392.460753924615;5472.589555783293;5524.779008839641;5553.389127196046;1892.252560238698;1916.3005502991448;1927.7301177835113;1973.634877547644;1984.4939540417101;23870.283891483108;24198.879005605035;24358.512829743308;24860.129895453752;25017.62473562916;6372.4583410409705;6378.394052564999;6497.02544618311;6523.18460192476;6562.507811914125;16302.592435189215;16316.836831631532;16320.918395408184;16343.427980020642;16349.100799455906;7393.223711510322;7546.1321947976685;7554.471187016966;7566.205548417447;7569.545827250383;6881.380675739208;7026.086608740933;7051.43588512928;7107.147142342952;7122.71509139639;4989.750512474362;5162.5095615916425;5164.586833053347;5170.379554453414;5218.399884013311;1506.2995706996298;1515.2575238133236;1538.8153421589482;1546.5515000649912;1547.8684311833395;913.9314551408671;937.3906960475746;939.5067589515559;947.3794664494629;947.4457442396275;7502.549847009197;7511.9624401877645;7583.50707204041;7620.428467864932;7651.5409075455655;662.9933700663028;663.8041777675597;673.4376795375819;673.6901025528311;675.1931441926948;5072;5829.358770535237;5840.2115714378015;5871.853203669857;6027.547933904974;20316;20395.592088158155;20772.584548309253;20821.167153314;20858.37424877256;19505.707414388795;20186.495337616383;20436.182552698025;20482.692759608617;20868.895655521625;9316.953415233016;9756.658516951873;10281.126104281071;10371.844422333521;10594;8617.396782225183;8629.266512346521;8773.254273386834;8793.20861122502;8811.955940220258;17188.140592970052;17366.39217627377;17488.213030413917;17605.647887042443;17699;17560.648787024442;17613.20740566652;17622.823771762633;17659.735103973195;17709.557261068578;13096.279704615956;13336.666583335105;13346.532871349456;13359.26524041187;13390.665233369244;13037.348132593523;13218.537351192663;13327.267000315076;13351.265680387673;13532.661683457993;11036.448177591248;11195.384253866116;11199.496022678835;11366.488508016993;11489.425528723363;10717.410542420243;10726.51730672106;10760.946195268974;10836.67489975302;10996.780064398507;9711.708648740549;9744.902550974399;9797.118259356692;9846.753831154278;9945.751356216153;6672.666366681744;6765.763198288037;6824.112865327475;6830.385265326145;7027.980942763373;6976.372126508639;7039.8944015840825;7040.823979400556;7090.432765378654;7108.857822843411 15:45:16 INFO - PID 5715 | |4;prototype.html;506.97590866449747;517.813587108641;523.3170712220552;525.2384043137451;526.4472304080714;6212.769359932838;6226.879161750894;6229.719662615182;6231.3145553989025;6239.22009748782;6065.514758819295;6134.9079763803575;6140.526273694315;6235.25176978762;6240.656763877985;5108.489151084899;5129.410117836459;5147.1764517677175;5150.948490515094;5176.2235664650225;4792.952070479303;4965.3048573199785;5048.899022019557;5054.267131265964;5123.51326623972;4887.975560122212;4893.143699852511;4922.753862306889;4959.876003099934;4975.228839529878;4896;4909.3126962225315;4937.136001199811;4941.530554597308;4972.3038774571405;4476.4180656514445;4496.437945256843;4500.594946454835;4504.301833215857;4528.615067719248;5984.222051133368;6037.245344331958;6067.180930574405;6078.209832721762;6120.04158669226;5651.886962260773;5694.743736531866;5723.141528770676;5723.828285151452;5774.682392468412;6480.6435646039445;6490.7079181436475;6524.314946930552;6550;6629.3758029283135;4887.706737595756;4905.705657660516;4926.0394223126505;4966.602671786249;4975.4029516457895;6684.632345221011;6724.159480064992;6847.288177955511;6965.547239429421;6977.232504424509;5367.651102678314;5384.892302153935;5417.26866872975;5429.809956651498;5464.590155738336;5551.8889622207325;5596.300462442195;5610.298712660918;5615.157726341082;5616.522595579421;5950;5975.940240597625;5976.147394307778;5983.640981541122;6048.274207095177;6665.133532640726;6690.765823196165;6729.899051514231;6742.39318461331;6778.932855094663;1944.7471828662183;1958.2265005323102;1971.1031480676259;1980.2969945669367;1988.6122206169657;1369.8082268482221;1425.6222101143205;1452.244832686997;1456.2936975566843;1467.3543640798014;5659.745311460994;5660.745266463019;5663.263775709213;5682.54539636828;5737.627054241461;6583.44040756541;6600.669966501656;6621.457520226656;6760.459163266927;6785.558938668971;5638.943610563924;5688.951977288173;5724.685142317129;5731.340895796953;5731.8567035824435;3503;3527.6295988921306;3559.2525569630666;3600.5499312585926;3604.3151801157696;6498.415142637185;6517.9245424504425;6525.706343214567;6530.673466326664;6577.144971153815;6912.377885990285;6921.446284297244;6948.513604047769;6970.734511283853;6990.231074581894;6512.511561632897;6527.184101987252;6531.444827189742;6564.260470975195;6577.408033277028;6728.899066513908;6800.693968771318;6801.455883529405;6810.284920083518;6815.148106486689;6672.930451991944;6719.195775020884;6724.596524208563;6737.9326206737305;6751.088603038527;6516.218053833571;6536.614135840479;6544.116544266463;6556.442702370161;6577.6053436793945;5489.615726899079;5491.890162196813;5506.614536982373;5533.529649979797;5539;6486.2216534016225;6514.674266286572;6682.298358672269;6687.230968438692;6823.590584564942;6441.806745797634;6447.258565264867;6510.7721229756735;6564.9671751640935;6601.798127907865;6387.968060159669;6424.357564243725;6431.681505291311;6449.193850768654;6591.176102987127;6402.93597064042;6446.0975463434215;6457.192850893638;6546.312637173219;6563;6490;6497.361477572575;6533.967330163318;6542.383046781326;6608.173978252718;6282.717277722422;6282.780102696383;6303.558750887577;6311.400416960381;6377.713002914973 15:45:16 INFO - PID 5715 | |5;yui.html;909.4270609516004;911.1935936696024;928.7863791327994;929.8140371925608;934.2806039349699;18565;19308.71036934447;19313.99999999998;19327.323543675975;20881.477963050933;21692.89153554234;22576.88711556444;22653.660195097087;22687;22694.88652556739;968.2157452463492;987.1017374189489;989.7476143583376;990.2127808392345;992.5632721602508;1013.229945241618;1056.4136904018267;1056.587930707021;1057.7778666480049;1058.2169194795833;1112.7996960547098;1118.1893127482551;1119.306030261235;1119.322809700128;1123.915706322025;1111.3443068589531;1115.4924509348227;1118.552578968411;1118.7091356247433;1118.932864028157;816.4978538198965;818.4066551749993;818.5579786915118;818.6807145213371;818.7748369198458;19723.408297750946;19727.112279947436;19777;19872.205111795654;20134.496637584176;21211.893940530354;21298.467538311666;21632.567348653098;21679.45801354963;21939.451513712127;16172.11053392063;16272.18639068042;16356;16399.672006559922;16410.17949102552;3563.5367402237544;3585.4980302757376;3588.3899737044503;3590;3599.784012959231;128215;129189;129388;131316.3434182823;132307;16935.407260745815;17047.91476042612;17085.829141708426;17132.14339283031;17496.91251543734;30378.392432151235;30936.381272374427;31019.84490077535;31486.527702084488;31717.841410792797;7702.114256860533;8067.677292908218;8354.58227088643;8583.527905965231;8983.640654373752;4256.084941737541;4346.521882592912;4349.108432771274;4350.21696094706;4373.934390984138;5175.146100893386;5239.449857764956;5241.947580524222;5284.39229488606;5304.363476382859;1063.4044934836515;1087.9292845964987;1094.3105843318658;1104.3981030338325;1108.512254607986;664.5481072870494;676.5974245324024;683.5249501596442;687.88993760998;688.4079691465336;10903.727406814893;11776.70558236051;12184.20802647825;12395.814062789066;12458.563950261698;676.6684324680871;684.849333146707;696.5820507695342;700.8247938015496;709.6593635055202;5250.868728281824;5601.663900166003;5700.828975130753;5773.278340207474;5834.154047663065;102379.48810255901;106463;107235.46382268195;109204.03346921362;109876.4506177464;94528.52735736278;94724;97071;97790;98671;82010.58994704988;84151;84888.57555712182;87502.56248718589;87799;12203.816942746043;12270.570530031406;12358.196717213352;12599.87400125987;13192.340382981005;41268.79365603273;42613.14773704571;42647.147057058064;43102.78448607737;44059.77970110129;73435;75691;76377.61811190909;76918.6154069226;81033.59483202783;18314.908425457787;18514.814851851846;18529.166187521325;19280.132394042022;19310.420687379403;18698.325418645338;19093.713594296376;19468.707969380754;19499.415017549498;19582.90208548948;17717.46847594574;17816.376426824998;17835;17854.821451785316;17939.37212197567;18417.631647367245;18497.352592659732;18501.72247416263;18555.443336699922;18558.721619175452;18129.728054078936;18351.724724129417;18537.721934170728;18573.164207610418;18911.338103166323;12230.510779568718;12653.493860245488;13008.284544350152;13103.541376051793;13908.443662253396;14097.09689191987;14266.358013889607;14566;14625.634359141106;14630.561083167522 15:45:16 INFO - PID 5715 | __end_tp_report 15:45:16 INFO - PID 5715 | __start_cc_report 15:45:16 INFO - PID 5715 | _x_x_mozilla_cycle_collect,383 15:45:16 INFO - PID 5715 | __end_cc_report 15:45:16 INFO - PID 5715 | __startTimestamp1502577916910__endTimestamp 15:45:16 INFO - PID 5715 | 15:45:16 INFO - PID 5715 | ------- Summary: start ------- 15:45:16 INFO - PID 5715 | Number of tests: 6 15:45:16 INFO - PID 5715 | 15:45:16 INFO - PID 5715 | [#0] dojo.html Cycles:165 Average:6183.01 Median:6387.49 stddev:735.09 (11.5%) stddev-sans-first:697.70 15:45:16 INFO - PID 5715 | Values: 3146.9 3149.3 3162.7 3168.5 3182.5 6147.4 6180.2 6264.3 6276.7 6311.4 6201.0 6261.3 6272.3 6285.9 6287.5 5685.8 5738.6 5754.7 5761.1 5831.2 5739.8 5809.8 5819.5 5834.4 5867.2 5549.7 5659.7 5748.4 5749.2 5842.6 5618.2 5716.4 5718.2 5794.4 5807.2 5669.2 5712.3 5759.6 5774.2 5792.3 6056.8 6118.4 6155.6 6163.4 6232.2 6706.7 6713.3 6722.9 6730.7 6746.6 6748.8 6821.3 6833.7 6841.2 6878.1 6164.9 6166.0 6220.4 6261.8 6323.0 6900.4 6910.0 6920.3 6926.4 6929.3 6441.8 6501.3 6527.3 6577.5 6581.3 6670.2 6705.0 6715.7 6740.8 6761.3 6668.3 6740.3 6754.2 6757.1 6763.4 6732.6 6739.9 6745.0 6819.7 6833.8 5316.1 5351.5 5407.3 5422.8 5425.1 4656.1 4686.5 4714.1 4718.4 4739.2 5774.7 5851.4 5856.6 5859.9 5862.1 6724.5 6734.5 6804.1 6826.7 6838.2 5646.9 5731.9 5738.7 5742.5 5744.7 5869.0 5961.5 5964.5 5972.9 6002.8 6409.3 6588.5 6595.5 6597.8 6607.4 6722.0 6744.9 6752.5 6852.7 6855.7 6286.2 6321.7 6366.7 6403.8 6405.3 6648.3 6664.4 6669.7 6686.4 6704.2 6751.6 6755.6 6763.6 6848.4 6876.6 6380.2 6547.7 6552.9 6632.5 6655.6 6481.8 6489.5 6490.5 6495.8 6509.3 6367.4 6367.6 6387.5 6401.1 6410.9 6585.4 6605.3 6634.0 6675.5 6687.1 6303.8 6416.1 6416.2 6419.8 6439.4 15:45:16 INFO - PID 5715 | 15:45:16 INFO - PID 5715 | [#1] ext.html Cycles:180 Average:38018.70 Median:19352.61 stddev:54604.78 (282.2%) stddev-sans-first:54686.46 15:45:16 INFO - PID 5715 | Values: 804.5 866.7 886.9 894.4 1152.5 28438.6 30010.2 39773.6 40404.2 40498.6 41359.2 41520.4 41536.0 41815.2 41829.4 7145.7 7485.4 7600.8 7607.0 7636.6 7187.7 7671.5 7680.5 7683.0 7697.5 7666.5 7694.3 7697.8 7713.5 7740.7 7580.8 7622.2 7635.6 7657.7 7671.8 7498.5 7560.1 7561.7 7565.9 7567.0 37842.0 37900.6 38008.0 38250.0 38450.6 3568.8 3579.2 3612.5 3614.8 3616.9 31165.5 31188.2 31203.0 31302.2 31406.1 4981.2 5037.1 5054.4 5083.2 5090.4 197197.0 198785.0 198849.0 199029.0 199635.0 47984.0 48362.0 48586.0 48608.0 48687.3 50192.7 50454.0 50628.2 50657.2 50749.2 15467.5 15807.8 15842.5 15846.9 15849.8 11258.5 11529.9 13005.7 13020.3 13025.9 14115.2 14180.0 14303.2 14368.8 14381.6 5789.3 5828.9 5868.8 5904.6 5946.0 998.8 1011.1 1013.0 1025.5 1029.2 22673.8 23299.8 23401.5 23433.4 23460.3 1111.8 1117.5 1118.6 1120.9 1128.0 12000.3 12506.6 12537.5 12600.3 12624.9 233092.0 234696.0 235176.0 235847.0 236200.0 53281.7 53525.5 53588.5 53661.2 53880.7 1446.6 1472.0 1472.1 1475.2 1482.9 22677.9 23016.7 23165.4 23256.8 23505.9 37539.6 37599.1 37614.6 37907.6 37927.4 194243.0 195367.0 196058.0 197623.0 197806.0 33544.5 33623.0 33786.5 35136.5 36057.3 44747.3 46148.0 46313.0 46597.5 46737.5 33302.0 33808.2 33898.0 33948.0 34508.8 33415.8 33593.8 33883.2 34467.0 34676.8 35977.3 36184.0 36436.6 36678.6 36812.8 32361.7 32469.0 33107.8 34500.8 34537.0 24734.9 24938.6 25160.9 25549.0 25587.0 15:45:16 INFO - PID 5715 | 15:45:16 INFO - PID 5715 | [#2] jquery.html Cycles:180 Average:37785.01 Median:13210.34 stddev:90421.27 (684.5%) stddev-sans-first:90631.75 15:45:16 INFO - PID 5715 | Values: 569.4 577.1 578.5 593.2 594.2 22056.6 22204.1 22895.8 22905.2 22934.0 24962.5 26497.5 26538.6 26609.0 26774.2 6252.9 6420.9 6422.5 6433.5 6447.7 6097.2 6276.5 6326.4 6419.6 6428.6 6384.3 6385.6 6392.2 6392.5 6395.6 6367.1 6376.9 6377.5 6379.0 6383.4 5941.9 5946.9 5953.2 5957.2 5969.9 29737.7 30249.4 30345.7 30858.4 31461.5 1980.9 2022.4 2029.5 2030.5 2031.3 18745.3 19130.8 20183.4 20185.7 20200.7 2396.4 2412.9 2450.2 2460.3 2477.3 143748.0 144643.0 147750.3 152743.0 154164.0 14701.6 14788.5 14909.9 15375.6 15628.6 23917.2 23997.6 24000.5 24007.8 24159.5 9507.3 9872.4 9890.3 9894.2 10104.9 9117.9 9180.9 9270.4 9274.5 9409.7 9422.3 9476.3 9503.6 9624.2 9645.5 2829.9 2847.4 2871.2 2883.7 2976.6 904.8 913.5 953.4 986.3 990.7 19901.4 19993.6 20084.0 20146.1 20569.0 1020.0 1026.9 1053.8 1055.3 1060.8 11330.5 11349.5 11391.4 11442.7 11542.7 526879.0 533315.0 534672.0 540409.0 544271.0 125503.0 126960.0 129028.0 129567.4 129814.0 1191.3 1206.5 1222.4 1226.9 1229.1 17797.6 17844.2 18099.7 18194.2 18410.0 59851.0 60481.4 61473.7 63928.0 68244.7 78082.6 81822.0 82499.6 82598.2 84137.0 21942.8 22019.9 22393.6 22647.5 22677.4 24229.0 24279.5 24406.4 24473.3 24935.6 18859.0 19279.9 19401.9 19471.7 20058.2 16859.2 17216.2 17924.5 18090.1 18216.1 19358.1 19895.3 20272.4 20330.6 20416.0 20683.3 21731.7 21749.1 21850.0 21901.2 18077.2 18182.0 18184.5 18204.0 19462.8 15:45:16 INFO - PID 5715 | 15:45:16 INFO - PID 5715 | [#3] mootools.html Cycles:180 Average:9004.65 Median:2681.75 stddev:6264.54 (233.6%) stddev-sans-first:6255.46 15:45:16 INFO - PID 5715 | Values: 1314.1 1340.6 1366.8 1382.8 1386.9 14024.1 14285.7 14595.5 14603.8 14624.6 14820.8 15113.7 15128.0 15136.2 15163.8 2735.4 2736.8 2756.6 2769.6 2772.1 2662.7 2764.6 2831.5 2831.6 2831.7 2674.3 2680.8 2712.0 2714.1 2715.0 2673.5 2682.7 2706.0 2710.6 2725.3 2497.8 2519.6 2528.6 2615.2 2699.7 14216.5 14239.9 14362.6 14366.7 14369.6 7307.6 7602.0 7607.2 7622.0 7635.7 5190.5 5392.5 5472.6 5524.8 5553.4 1892.3 1916.3 1927.7 1973.6 1984.5 23870.3 24198.9 24358.5 24860.1 25017.6 6372.5 6378.4 6497.0 6523.2 6562.5 16302.6 16316.8 16320.9 16343.4 16349.1 7393.2 7546.1 7554.5 7566.2 7569.5 6881.4 7026.1 7051.4 7107.1 7122.7 4989.8 5162.5 5164.6 5170.4 5218.4 1506.3 1515.3 1538.8 1546.6 1547.9 913.9 937.4 939.5 947.4 947.4 7502.5 7512.0 7583.5 7620.4 7651.5 663.0 663.8 673.4 673.7 675.2 5072.0 5829.4 5840.2 5871.9 6027.5 20316.0 20395.6 20772.6 20821.2 20858.4 19505.7 20186.5 20436.2 20482.7 20868.9 9317.0 9756.7 10281.1 10371.8 10594.0 8617.4 8629.3 8773.3 8793.2 8812.0 17188.1 17366.4 17488.2 17605.6 17699.0 17560.6 17613.2 17622.8 17659.7 17709.6 13096.3 13336.7 13346.5 13359.3 13390.7 13037.3 13218.5 13327.3 13351.3 13532.7 11036.4 11195.4 11199.5 11366.5 11489.4 10717.4 10726.5 10760.9 10836.7 10996.8 9711.7 9744.9 9797.1 9846.8 9945.8 6672.7 6765.8 6824.1 6830.4 7028.0 6976.4 7039.9 7040.8 7090.4 7108.9 15:45:16 INFO - PID 5715 | 15:45:16 INFO - PID 5715 | [#4] prototype.html Cycles:180 Average:5576.51 Median:6127.47 stddev:1509.94 (24.6%) stddev-sans-first:1465.45 15:45:16 INFO - PID 5715 | Values: 507.0 517.8 523.3 525.2 526.4 6212.8 6226.9 6229.7 6231.3 6239.2 6065.5 6134.9 6140.5 6235.3 6240.7 5108.5 5129.4 5147.2 5150.9 5176.2 4793.0 4965.3 5048.9 5054.3 5123.5 4888.0 4893.1 4922.8 4959.9 4975.2 4896.0 4909.3 4937.1 4941.5 4972.3 4476.4 4496.4 4500.6 4504.3 4528.6 5984.2 6037.2 6067.2 6078.2 6120.0 5651.9 5694.7 5723.1 5723.8 5774.7 6480.6 6490.7 6524.3 6550.0 6629.4 4887.7 4905.7 4926.0 4966.6 4975.4 6684.6 6724.2 6847.3 6965.5 6977.2 5367.7 5384.9 5417.3 5429.8 5464.6 5551.9 5596.3 5610.3 5615.2 5616.5 5950.0 5975.9 5976.1 5983.6 6048.3 6665.1 6690.8 6729.9 6742.4 6778.9 1944.7 1958.2 1971.1 1980.3 1988.6 1369.8 1425.6 1452.2 1456.3 1467.4 5659.7 5660.7 5663.3 5682.5 5737.6 6583.4 6600.7 6621.5 6760.5 6785.6 5638.9 5689.0 5724.7 5731.3 5731.9 3503.0 3527.6 3559.3 3600.5 3604.3 6498.4 6517.9 6525.7 6530.7 6577.1 6912.4 6921.4 6948.5 6970.7 6990.2 6512.5 6527.2 6531.4 6564.3 6577.4 6728.9 6800.7 6801.5 6810.3 6815.1 6672.9 6719.2 6724.6 6737.9 6751.1 6516.2 6536.6 6544.1 6556.4 6577.6 5489.6 5491.9 5506.6 5533.5 5539.0 6486.2 6514.7 6682.3 6687.2 6823.6 6441.8 6447.3 6510.8 6565.0 6601.8 6388.0 6424.4 6431.7 6449.2 6591.2 6402.9 6446.1 6457.2 6546.3 6563.0 6490.0 6497.4 6534.0 6542.4 6608.2 6282.7 6282.8 6303.6 6311.4 6377.7 15:45:16 INFO - PID 5715 | 15:45:16 INFO - PID 5715 | [#5] yui.html Cycles:180 Average:24074.48 Median:19752.06 stddev:32443.92 (164.3%) stddev-sans-first:32488.31 15:45:16 INFO - PID 5715 | Values: 909.4 911.2 928.8 929.8 934.3 18565.0 19308.7 19314.0 19327.3 20881.5 21692.9 22576.9 22653.7 22687.0 22694.9 968.2 987.1 989.7 990.2 992.6 1013.2 1056.4 1056.6 1057.8 1058.2 1112.8 1118.2 1119.3 1119.3 1123.9 1111.3 1115.5 1118.6 1118.7 1118.9 816.5 818.4 818.6 818.7 818.8 19723.4 19727.1 19777.0 19872.2 20134.5 21211.9 21298.5 21632.6 21679.5 21939.5 16172.1 16272.2 16356.0 16399.7 16410.2 3563.5 3585.5 3588.4 3590.0 3599.8 128215.0 129189.0 129388.0 131316.3 132307.0 16935.4 17047.9 17085.8 17132.1 17496.9 30378.4 30936.4 31019.8 31486.5 31717.8 7702.1 8067.7 8354.6 8583.5 8983.6 4256.1 4346.5 4349.1 4350.2 4373.9 5175.1 5239.4 5241.9 5284.4 5304.4 1063.4 1087.9 1094.3 1104.4 1108.5 664.5 676.6 683.5 687.9 688.4 10903.7 11776.7 12184.2 12395.8 12458.6 676.7 684.8 696.6 700.8 709.7 5250.9 5601.7 5700.8 5773.3 5834.2 102379.5 106463.0 107235.5 109204.0 109876.5 94528.5 94724.0 97071.0 97790.0 98671.0 82010.6 84151.0 84888.6 87502.6 87799.0 12203.8 12270.6 12358.2 12599.9 13192.3 41268.8 42613.1 42647.1 43102.8 44059.8 73435.0 75691.0 76377.6 76918.6 81033.6 18314.9 18514.8 18529.2 19280.1 19310.4 18698.3 19093.7 19468.7 19499.4 19582.9 17717.5 17816.4 17835.0 17854.8 17939.4 18417.6 18497.4 18501.7 18555.4 18558.7 18129.7 18351.7 18537.7 18573.2 18911.3 12230.5 12653.5 13008.3 13103.5 13908.4 14097.1 14266.4 14566.0 14625.6 14630.6 15:45:16 INFO - PID 5715 | -------- Summary: end -------- 15:45:16 INFO - PID 5715 | 15:45:17 INFO - TEST-INFO | 5715: exit 0 15:45:17 INFO - TEST-OK | dromaeo_css | took 1113470ms 15:45:17 INFO - TEST-START | kraken 15:45:17 INFO - Initialising browser for kraken test... 15:45:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37752/getInfo.html -profile /tmp/tmpPIE_SC/profile 15:45:17 INFO - TEST-INFO | started process 6092 (/builds/slave/test/build/application/firefox/firefox http://localhost:37752/getInfo.html) 15:45:23 INFO - TEST-INFO | 6092: exit 0 15:45:24 INFO - Browser initialized. 15:45:24 INFO - Running cycle 1/1 for kraken test... 15:45:24 INFO - TEST-INFO | started process 6325 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPIE_SC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:45:25 INFO - PID 6325 | 15:45:25 INFO - PID 6325 | (/builds/slave/test/build/application/firefox/firefox:6381): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:45:25 INFO - PID 6325 | 15:45:35 INFO - PID 6325 | RSS: Main: 167911424 15:45:35 INFO - PID 6325 | 15:45:38 INFO - PID 6325 | Cycle 1(1): loaded http://localhost:37752/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37752/tests/kraken/driver.html?testName=audio-beat-detection) 15:45:39 INFO - PID 6325 | RSS: Main: 174809088 15:45:39 INFO - PID 6325 | 15:45:41 INFO - PID 6325 | Cycle 1(1): loaded http://localhost:37752/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37752/tests/kraken/driver.html?testName=audio-dft) 15:45:42 INFO - PID 6325 | RSS: Main: 174858240 15:45:42 INFO - PID 6325 | 15:45:45 INFO - PID 6325 | Cycle 1(1): loaded http://localhost:37752/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37752/tests/kraken/driver.html?testName=audio-fft) 15:45:46 INFO - PID 6325 | RSS: Main: 170000384 15:45:46 INFO - PID 6325 | 15:45:48 INFO - PID 6325 | Cycle 1(1): loaded http://localhost:37752/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37752/tests/kraken/driver.html?testName=audio-oscillator) 15:45:48 INFO - PID 6325 | RSS: Main: 170332160 15:45:48 INFO - PID 6325 | 15:45:51 INFO - PID 6325 | Cycle 1(1): loaded http://localhost:37752/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37752/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:45:51 INFO - PID 6325 | RSS: Main: 170618880 15:45:51 INFO - PID 6325 | 15:45:54 INFO - PID 6325 | Cycle 1(1): loaded http://localhost:37752/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37752/tests/kraken/driver.html?testName=imaging-darkroom) 15:45:55 INFO - PID 6325 | RSS: Main: 173461504 15:45:55 INFO - PID 6325 | 15:45:59 INFO - PID 6325 | Cycle 1(1): loaded http://localhost:37752/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37752/tests/kraken/driver.html?testName=imaging-desaturate) 15:45:59 INFO - PID 6325 | RSS: Main: 173178880 15:45:59 INFO - PID 6325 | 15:46:02 INFO - PID 6325 | Cycle 1(1): loaded http://localhost:37752/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37752/tests/kraken/driver.html?testName=json-parse-financial) 15:46:03 INFO - PID 6325 | RSS: Main: 173682688 15:46:03 INFO - PID 6325 | 15:46:04 INFO - PID 6325 | Cycle 1(1): loaded http://localhost:37752/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37752/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:46:05 INFO - PID 6325 | RSS: Main: 173543424 15:46:05 INFO - PID 6325 | 15:46:07 INFO - PID 6325 | Cycle 1(1): loaded http://localhost:37752/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37752/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:46:08 INFO - PID 6325 | RSS: Main: 173568000 15:46:08 INFO - PID 6325 | 15:46:10 INFO - PID 6325 | Cycle 1(1): loaded http://localhost:37752/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37752/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:46:10 INFO - PID 6325 | RSS: Main: 173465600 15:46:10 INFO - PID 6325 | 15:46:13 INFO - PID 6325 | Cycle 1(1): loaded http://localhost:37752/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37752/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:46:14 INFO - PID 6325 | RSS: Main: 174211072 15:46:14 INFO - PID 6325 | 15:46:17 INFO - PID 6325 | Cycle 1(1): loaded http://localhost:37752/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37752/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:46:18 INFO - PID 6325 | RSS: Main: 174088192 15:46:18 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | Cycle 1(1): loaded http://localhost:37752/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37752/tests/kraken/driver.html?testName=ai-astar) 15:46:20 INFO - PID 6325 | RSS: Main: 176029696 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | __start_tp_report 15:46:20 INFO - PID 6325 | _x_x_mozilla_page_load 15:46:20 INFO - PID 6325 | _x_x_mozilla_page_load_details 15:46:20 INFO - PID 6325 | |i|pagename|runs| 15:46:20 INFO - PID 6325 | |0;ai-astar;101;108;105;108;110;114;112;97;109;108 15:46:20 INFO - PID 6325 | |1;audio-beat-detection;146;122;146;171;120;153;147;119;176;116 15:46:20 INFO - PID 6325 | |2;audio-dft;162;164;162;163;163;166;163;177;163;165 15:46:20 INFO - PID 6325 | |3;audio-fft;80;79;76;171;76;78;75;83;159;76 15:46:20 INFO - PID 6325 | |4;audio-oscillator;79;79;126;82;79;79;127;78;80;129 15:46:20 INFO - PID 6325 | |5;imaging-gaussian-blur;114;113;120;114;114;116;114;117;120;117 15:46:20 INFO - PID 6325 | |6;imaging-darkroom;152;153;151;150;151;154;151;155;155;156 15:46:20 INFO - PID 6325 | |7;imaging-desaturate;99;98;99;95;95;95;94;95;95;101 15:46:20 INFO - PID 6325 | |8;json-parse-financial;60;54;53;58;58;52;53;58;54;53 15:46:20 INFO - PID 6325 | |9;json-stringify-tinderbox;55;54;54;58;55;59;55;60;54;60 15:46:20 INFO - PID 6325 | |10;stanford-crypto-aes;78;80;76;81;78;77;83;83;77;78 15:46:20 INFO - PID 6325 | |11;stanford-crypto-ccm;127;133;124;133;133;130;130;138;127;180 15:46:20 INFO - PID 6325 | |12;stanford-crypto-pbkdf2;197;174;188;175;176;191;181;185;184;178 15:46:20 INFO - PID 6325 | |13;stanford-crypto-sha256-iterative;72;67;73;67;68;69;68;76;69;66 15:46:20 INFO - PID 6325 | __end_tp_report 15:46:20 INFO - PID 6325 | __start_cc_report 15:46:20 INFO - PID 6325 | _x_x_mozilla_cycle_collect,980 15:46:20 INFO - PID 6325 | __end_cc_report 15:46:20 INFO - PID 6325 | __startTimestamp1502577980388__endTimestamp 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | ------- Summary: start ------- 15:46:20 INFO - PID 6325 | Number of tests: 14 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | [#0] ai-astar Cycles:10 Average:107.20 Median:109.50 stddev:5.05 (4.6%) stddev-sans-first:4.83 15:46:20 INFO - PID 6325 | Values: 101.0 108.0 105.0 108.0 110.0 114.0 112.0 97.0 109.0 108.0 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | [#1] audio-beat-detection Cycles:10 Average:141.60 Median:146.50 stddev:21.76 (14.9%) stddev-sans-first:23.02 15:46:20 INFO - PID 6325 | Values: 146.0 122.0 146.0 171.0 120.0 153.0 147.0 119.0 176.0 116.0 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | [#2] audio-dft Cycles:10 Average:164.80 Median:163.50 stddev:4.47 (2.7%) stddev-sans-first:4.62 15:46:20 INFO - PID 6325 | Values: 162.0 164.0 162.0 163.0 163.0 166.0 163.0 177.0 163.0 165.0 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | [#3] audio-fft Cycles:10 Average:95.30 Median:77.00 stddev:36.92 (47.9%) stddev-sans-first:38.74 15:46:20 INFO - PID 6325 | Values: 80.0 79.0 76.0 171.0 76.0 78.0 75.0 83.0 159.0 76.0 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:79.00 stddev:23.17 (29.3%) stddev-sans-first:23.95 15:46:20 INFO - PID 6325 | Values: 79.0 79.0 126.0 82.0 79.0 79.0 127.0 78.0 80.0 129.0 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | [#5] imaging-gaussian-blur Cycles:10 Average:115.90 Median:116.50 stddev:2.56 (2.2%) stddev-sans-first:2.62 15:46:20 INFO - PID 6325 | Values: 114.0 113.0 120.0 114.0 114.0 116.0 114.0 117.0 120.0 117.0 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | [#6] imaging-darkroom Cycles:10 Average:152.80 Median:153.50 stddev:2.10 (1.4%) stddev-sans-first:2.20 15:46:20 INFO - PID 6325 | Values: 152.0 153.0 151.0 150.0 151.0 154.0 151.0 155.0 155.0 156.0 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | [#7] imaging-desaturate Cycles:10 Average:96.60 Median:95.00 stddev:2.41 (2.5%) stddev-sans-first:2.40 15:46:20 INFO - PID 6325 | Values: 99.0 98.0 99.0 95.0 95.0 95.0 94.0 95.0 95.0 101.0 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.00 stddev:2.87 (5.1%) stddev-sans-first:2.49 15:46:20 INFO - PID 6325 | Values: 60.0 54.0 53.0 58.0 58.0 52.0 53.0 58.0 54.0 53.0 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | [#9] json-stringify-tinderbox Cycles:10 Average:56.40 Median:56.50 stddev:2.55 (4.5%) stddev-sans-first:2.65 15:46:20 INFO - PID 6325 | Values: 55.0 54.0 54.0 58.0 55.0 59.0 55.0 60.0 54.0 60.0 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | [#10] stanford-crypto-aes Cycles:10 Average:79.10 Median:79.00 stddev:2.51 (3.2%) stddev-sans-first:2.64 15:46:20 INFO - PID 6325 | Values: 78.0 80.0 76.0 81.0 78.0 77.0 83.0 83.0 77.0 78.0 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | [#11] stanford-crypto-ccm Cycles:10 Average:135.50 Median:133.00 stddev:16.13 (12.1%) stddev-sans-first:16.82 15:46:20 INFO - PID 6325 | Values: 127.0 133.0 124.0 133.0 133.0 130.0 130.0 138.0 127.0 180.0 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.90 Median:184.50 stddev:7.55 (4.1%) stddev-sans-first:6.04 15:46:20 INFO - PID 6325 | Values: 197.0 174.0 188.0 175.0 176.0 191.0 181.0 185.0 184.0 178.0 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:69.00 stddev:3.17 (4.6%) stddev-sans-first:3.23 15:46:20 INFO - PID 6325 | Values: 72.0 67.0 73.0 67.0 68.0 69.0 68.0 76.0 69.0 66.0 15:46:20 INFO - PID 6325 | -------- Summary: end -------- 15:46:20 INFO - PID 6325 | 15:46:20 INFO - PID 6325 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:46:21 INFO - TEST-INFO | 6325: exit 0 15:46:21 INFO - TEST-OK | kraken | took 63455ms 15:46:21 INFO - SUITE-END | took 1176s 15:46:21 INFO - Completed test suite (00:19:37) 15:46:21 INFO - javascript benchmark 15:46:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8626.268396077698, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3146.889858854939, 3149.291409432877, 3162.6679198684133, 3168.4930411134224, 3182.506711459723, 6147.446729794317, 6180.1656776335185, 6264.342244064376, 6276.686165691714, 6311.431971122598, 6201.0, 6261.279952805428, 6272.3100458949475, 6285.9057114143325, 6287.528435367354, 5685.829425117253, 5738.626989245696, 5754.74103665336, 5761.107028410603, 5831.183634291203, 5739.770409183649, 5809.7676092956235, 5819.476247137757, 5834.358220595752, 5867.207926929853, 5549.694766787825, 5659.688717120557, 5748.396418376094, 5749.223854779614, 5842.649441033552, 5618.2415373924405, 5716.399778023331, 5718.170865224519, 5794.362620111784, 5807.186993820868, 5669.177969194485, 5712.314522257356, 5759.568032397545, 5774.16274640179, 5792.333881603628, 6056.757729690807, 6118.357572454873, 6155.569110162291, 6163.352847950945, 6232.158658581079, 6706.731730730765, 6713.295104014057, 6722.865542689167, 6730.663466826688, 6746.59520428771, 6748.763793267212, 6821.317868213188, 6833.658317084176, 6841.2132604750595, 6878.140232470942, 6164.907526387108, 6165.969170154121, 6220.440160385587, 6261.78083767066, 6322.956712142537, 6900.413464855544, 6910.0, 6920.342567456084, 6926.411255043377, 6929.272426395257, 6441.838954026093, 6501.284858665637, 6527.314631963575, 6577.506686998494, 6581.341865813476, 6670.199576050808, 6704.966475167593, 6715.731370745116, 6740.83147921296, 6761.256261811197, 6668.333166683293, 6740.325967403319, 6754.223264324567, 6757.087793147961, 6763.357481039392, 6732.562383445061, 6739.93260067393, 6744.966275168593, 6819.659017049128, 6833.760818371333, 5316.069687804618, 5351.491608297206, 5407.270018547446, 5422.75597598109, 5425.077736784756, 4656.138614356355, 4686.484486706459, 4714.127886341038, 4718.363020992191, 4739.218029025241, 5774.711264436845, 5851.444112809277, 5856.648601083949, 5859.94140058594, 5862.091375836752, 6724.462043036643, 6734.46124310054, 6804.08144900442, 6826.658667066627, 6838.213605435439, 5646.85882852932, 5731.914021289684, 5738.741756620962, 5742.454466825645, 5744.684042377626, 5869.0, 5961.493273071837, 5964.463198312086, 5972.940270597325, 6002.759889604367, 6409.327020662857, 6588.472922166311, 6595.538312318187, 6597.80206593803, 6607.405333520006, 6721.966390168018, 6744.887093629496, 6752.493562982698, 6852.657367131524, 6855.725770969106, 6286.18279623646, 6321.683915804283, 6366.681665916593, 6403.775867844603, 6405.295417504164, 6648.335166483313, 6664.433523150393, 6669.699863506251, 6686.364795344434, 6704.229013663491, 6751.56114852533, 6755.628440435823, 6763.5941843489545, 6848.424862281603, 6876.587404755731, 6380.202474690664, 6547.70535325921, 6552.868942621216, 6632.535722499379, 6655.633940133145, 6481.805545833632, 6489.4808415325715, 6490.513211509061, 6495.805125846232, 6509.251436084911, 6367.395097465734, 6367.554271200971, 6387.489000880011, 6401.135846660642, 6410.935890641033, 6585.374389433187, 6605.339466053356, 6633.9668301658185, 6675.532712710258, 6687.097241872285, 6303.842403939938, 6416.13382193398, 6416.230052393743, 6419.839504012437, 6439.388258115471], "value": 6127.543325381438, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.4851295171092, 866.7139843851528, 886.9423487473312, 894.3873446689018, 1152.4698638626232, 28438.57342139867, 30010.24974375639, 39773.602263977315, 40404.19191616166, 40498.59501404985, 41359.17281654365, 41520.37719434211, 41536.0, 41815.16369672605, 41829.37255941163, 7145.678444469985, 7485.401167906568, 7600.809979750496, 7607.0, 7636.618169091551, 7187.676554555058, 7671.501352412075, 7680.500767450098, 7682.961585192066, 7697.538147711127, 7666.540007599534, 7694.30751232389, 7697.846043079135, 7713.460057795956, 7740.690372385126, 7580.848383032336, 7622.161562228151, 7635.618219089051, 7657.731979380751, 7671.846563068735, 7498.512596681198, 7560.054993125859, 7561.659725312375, 7565.924340756577, 7566.962165189138, 37842.0, 37900.620993789984, 38008.0, 38250.0, 38450.61549384526, 3568.750937171995, 3579.1767893384667, 3612.4942508048894, 3614.7650402724003, 3616.8553257869653, 31165.532517012263, 31188.220294492592, 31203.0, 31302.217444564067, 31406.05781826549, 4981.153203955337, 5037.093323201822, 5054.444011158769, 5083.161278389061, 5090.389153301628, 197197.0, 198785.0, 198848.99999999854, 199029.0, 199635.0, 47984.0, 48362.0, 48586.0, 48608.0, 48687.269690954665, 50192.749036255314, 50454.0, 50628.240576391385, 50657.24014139791, 50749.23876141861, 15467.5359739208, 15807.762883556756, 15842.52472425829, 15846.920765396098, 15849.841501585068, 11258.549658013751, 11529.884701153049, 13005.674858128623, 13020.283884386261, 13025.869741302655, 14115.153090814583, 14180.0, 14303.213323267111, 14368.784468232985, 14381.64045898861, 5789.305283366023, 5828.88342233153, 5868.767558812697, 5904.586678932519, 5946.048632218824, 998.7503124218945, 1011.0950699124243, 1012.9746756331151, 1025.5077562769911, 1029.1509504658686, 22673.773262267496, 23299.7670023301, 23401.531969360516, 23433.41416464602, 23460.296191114296, 1111.8332250162541, 1117.474786850179, 1118.647625997808, 1120.8991190792706, 1127.9943600281947, 12000.279983201035, 12506.562270320495, 12537.498500060077, 12600.306983116017, 12624.87375126237, 233092.0, 234696.0, 235176.0, 235847.0, 236200.0, 53281.667958300575, 53525.46474535283, 53588.46411535913, 53661.1950820738, 53880.730596346766, 1446.5877224990827, 1472.0358165401678, 1472.1387988027125, 1475.1960181701, 1482.866542011224, 22677.886610566842, 23016.654750178426, 23165.42086447785, 23256.76743232546, 23505.882470587538, 37539.62460375361, 37599.06002349854, 37614.62385376111, 37907.62092379151, 37927.43108853314, 194243.0, 195367.0, 196058.0, 197623.0, 197806.0, 33544.49683254802, 33623.0, 33786.49320260247, 35136.47295290624, 36057.27885442329, 44747.32879006752, 46148.0, 46313.0, 46597.53402466067, 46737.53262467468, 33302.0, 33808.15479613029, 33898.0, 33948.0, 34508.82745586256, 33415.83292083524, 33593.83203083969, 33883.15292117717, 34467.0, 34676.82661586676, 35977.280454391286, 36184.0, 36436.635633644386, 36678.63321366859, 36812.81593592122, 32361.676383235867, 32469.0, 33107.834460827544, 34500.82749586236, 34537.0, 24734.876325618257, 24938.62592061084, 25160.874195629636, 25549.0, 25587.0], "value": 17270.60783114615, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.3736889421637, 577.1342985521718, 578.4822583787513, 593.1902952469877, 594.2097010975402, 22056.558868822613, 22204.1118355266, 22895.771042289594, 22905.198318058872, 22934.0, 24962.500749985036, 26497.470050598975, 26538.6019209712, 26609.0, 26774.19677409676, 6252.906206406896, 6420.871582568369, 6422.4862011039, 6433.485321174295, 6447.7098530566, 6097.237845269341, 6276.466500347476, 6326.398992095744, 6419.614823110605, 6428.646424446653, 6384.297727249998, 6385.553011289212, 6392.20097487814, 6392.4886009119155, 6395.584287021352, 6367.07677386781, 6376.936230637681, 6377.457916077115, 6378.968105159468, 6383.425491705745, 5941.851453713649, 5946.940530594725, 5953.166556682068, 5957.225560677084, 5969.910451343233, 29737.70262297371, 30249.395012099634, 30345.696543034508, 30858.382832343454, 31461.528077078863, 1980.8514361422951, 2022.3730643500562, 2029.482481967089, 2030.4619275891753, 2031.3499680102373, 18745.34391296312, 19130.80869191304, 20183.39449816508, 20185.697214541793, 20200.69698954517, 2396.412878844677, 2412.915547955831, 2450.2404254681105, 2460.335709358481, 2477.306354220821, 143748.0, 144643.0, 147750.26124869415, 152742.9999999989, 154164.0, 14701.632459188606, 14788.528244549585, 14909.850901490847, 15375.615609609626, 15628.609284767972, 23917.16289929844, 23997.640035399483, 24000.51998960011, 24007.759922400903, 24159.51680966371, 9507.334486585873, 9872.40765554069, 9890.307678462583, 9894.208463322917, 10104.898951010542, 9117.908820911838, 9180.908190918139, 9270.397424167408, 9274.489903055262, 9409.670661526814, 9422.293328000427, 9476.336656434121, 9503.619855205714, 9624.230061595055, 9645.469499177612, 2829.915102546927, 2847.402045570412, 2871.196065101775, 2883.68279489256, 2976.583278341034, 904.841652710773, 913.4930113786779, 953.4041224234853, 986.3292960786733, 990.6879333010079, 19901.402957911287, 19993.600127997357, 20084.0, 20146.071516054522, 20569.0, 1020.0, 1026.8665073540392, 1053.7892421515728, 1055.2560444886337, 1060.7719340341864, 11330.546778128783, 11349.54601815918, 11391.430428478543, 11442.656720298404, 11542.711432214093, 526879.0, 533315.0, 534672.0, 540409.0, 544271.0, 125503.0, 126960.0, 129028.0, 129567.35216324046, 129814.0, 1191.3149938785234, 1206.457094307565, 1222.4437880764408, 1226.907981901361, 1229.0904730499367, 17797.644047118727, 17844.19701113427, 18099.72850407271, 18194.181261842983, 18410.0, 59851.0, 60481.39518604758, 61473.692631536556, 63928.0, 68244.6587767058, 78082.6095869517, 81822.0, 82499.5875020645, 82598.17401825904, 84137.0, 21942.780572194075, 22019.889900550395, 22393.552128957002, 22647.547049059256, 22677.43306417353, 24229.0, 24279.51440971206, 24406.389840254134, 24473.265802025966, 24935.62596561017, 18859.0, 19279.903600481906, 19401.902990484956, 19471.70792438143, 20058.197672092952, 16859.1570421476, 17216.225269862636, 17924.462266132035, 18090.09549522545, 18216.089195539906, 19358.128884199963, 19895.303664371677, 20272.37820974348, 20330.59338813245, 20416.0, 20683.27608533694, 21731.674024889955, 21749.130034798432, 21850.0, 21901.233456828933, 18077.18652660607, 18182.0, 18184.45446636603, 18204.0, 19462.805371946666], "value": 12552.274816366853, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1314.0998416084983, 1340.577718018824, 1366.8496465388812, 1382.7511048011356, 1386.9098508596946, 14024.088434251766, 14285.714285714279, 14595.489157879476, 14603.78094328586, 14624.56126316211, 14820.777688334683, 15113.697726045473, 15128.0, 15136.243187840619, 15163.848361516382, 2735.411886444414, 2736.7536921677047, 2756.572731226663, 2769.639946806912, 2772.0575004498464, 2662.7470390312888, 2764.557670772677, 2831.4761769072684, 2831.6318878545762, 2831.6602007759097, 2674.3447855275385, 2680.839149651027, 2711.9864400678066, 2714.1179116787025, 2715.0, 2673.4786716590274, 2682.6646669166353, 2706.0, 2710.593410988348, 2725.2641786717477, 2497.7876880465183, 2519.5842685956795, 2528.582783840664, 2615.2285075902655, 2699.6895357033995, 14216.502422415166, 14239.928800356036, 14362.569122926225, 14366.71266574673, 14369.64075898111, 7307.561546307238, 7602.0, 7607.239276072349, 7622.047244094489, 7635.701930671799, 5190.455002224788, 5392.460753924615, 5472.589555783293, 5524.779008839641, 5553.389127196046, 1892.252560238698, 1916.3005502991448, 1927.7301177835113, 1973.634877547644, 1984.4939540417101, 23870.283891483108, 24198.879005605035, 24358.512829743308, 24860.129895453752, 25017.62473562916, 6372.4583410409705, 6378.394052564999, 6497.02544618311, 6523.18460192476, 6562.507811914125, 16302.592435189215, 16316.836831631532, 16320.918395408184, 16343.427980020642, 16349.100799455906, 7393.223711510322, 7546.1321947976685, 7554.471187016966, 7566.205548417447, 7569.545827250383, 6881.380675739208, 7026.086608740933, 7051.43588512928, 7107.147142342952, 7122.71509139639, 4989.750512474362, 5162.5095615916425, 5164.586833053347, 5170.379554453414, 5218.399884013311, 1506.2995706996298, 1515.2575238133236, 1538.8153421589482, 1546.5515000649912, 1547.8684311833395, 913.9314551408671, 937.3906960475746, 939.5067589515559, 947.3794664494629, 947.4457442396275, 7502.549847009197, 7511.9624401877645, 7583.50707204041, 7620.428467864932, 7651.5409075455655, 662.9933700663028, 663.8041777675597, 673.4376795375819, 673.6901025528311, 675.1931441926948, 5072.0, 5829.358770535237, 5840.2115714378015, 5871.853203669857, 6027.547933904974, 20316.0, 20395.592088158155, 20772.584548309253, 20821.167153314, 20858.37424877256, 19505.707414388795, 20186.495337616383, 20436.182552698025, 20482.692759608617, 20868.895655521625, 9316.953415233016, 9756.658516951873, 10281.126104281071, 10371.844422333521, 10594.0, 8617.396782225183, 8629.266512346521, 8773.254273386834, 8793.20861122502, 8811.955940220258, 17188.140592970052, 17366.39217627377, 17488.213030413917, 17605.647887042443, 17699.0, 17560.648787024442, 17613.20740566652, 17622.823771762633, 17659.735103973195, 17709.557261068578, 13096.279704615956, 13336.666583335105, 13346.532871349456, 13359.26524041187, 13390.665233369244, 13037.348132593523, 13218.537351192663, 13327.267000315076, 13351.265680387673, 13532.661683457993, 11036.448177591248, 11195.384253866116, 11199.496022678835, 11366.488508016993, 11489.425528723363, 10717.410542420243, 10726.51730672106, 10760.946195268974, 10836.67489975302, 10996.780064398507, 9711.708648740549, 9744.902550974399, 9797.118259356692, 9846.753831154278, 9945.751356216153, 6672.666366681744, 6765.763198288037, 6824.112865327475, 6830.385265326145, 7027.980942763373, 6976.372126508639, 7039.8944015840825, 7040.823979400556, 7090.432765378654, 7108.857822843411], "value": 6478.665026381317, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.97590866449747, 517.813587108641, 523.3170712220552, 525.2384043137451, 526.4472304080714, 6212.769359932838, 6226.879161750894, 6229.719662615182, 6231.3145553989025, 6239.22009748782, 6065.514758819295, 6134.9079763803575, 6140.526273694315, 6235.25176978762, 6240.656763877985, 5108.489151084899, 5129.410117836459, 5147.1764517677175, 5150.948490515094, 5176.2235664650225, 4792.952070479303, 4965.3048573199785, 5048.899022019557, 5054.267131265964, 5123.51326623972, 4887.975560122212, 4893.143699852511, 4922.753862306889, 4959.876003099934, 4975.228839529878, 4896.0, 4909.3126962225315, 4937.136001199811, 4941.530554597308, 4972.3038774571405, 4476.4180656514445, 4496.437945256843, 4500.594946454835, 4504.301833215857, 4528.615067719248, 5984.222051133368, 6037.245344331958, 6067.180930574405, 6078.209832721762, 6120.04158669226, 5651.886962260773, 5694.743736531866, 5723.141528770676, 5723.828285151452, 5774.682392468412, 6480.6435646039445, 6490.7079181436475, 6524.314946930552, 6550.0, 6629.3758029283135, 4887.706737595756, 4905.705657660516, 4926.0394223126505, 4966.602671786249, 4975.4029516457895, 6684.632345221011, 6724.159480064992, 6847.288177955511, 6965.547239429421, 6977.232504424509, 5367.651102678314, 5384.892302153935, 5417.26866872975, 5429.809956651498, 5464.590155738336, 5551.8889622207325, 5596.300462442195, 5610.298712660918, 5615.157726341082, 5616.522595579421, 5950.0, 5975.940240597625, 5976.147394307778, 5983.640981541122, 6048.274207095177, 6665.133532640726, 6690.765823196165, 6729.899051514231, 6742.39318461331, 6778.932855094663, 1944.7471828662183, 1958.2265005323102, 1971.1031480676259, 1980.2969945669367, 1988.6122206169657, 1369.8082268482221, 1425.6222101143205, 1452.244832686997, 1456.2936975566843, 1467.3543640798014, 5659.745311460994, 5660.745266463019, 5663.263775709213, 5682.54539636828, 5737.627054241461, 6583.44040756541, 6600.669966501656, 6621.457520226656, 6760.459163266927, 6785.558938668971, 5638.943610563924, 5688.951977288173, 5724.685142317129, 5731.340895796953, 5731.8567035824435, 3503.0, 3527.6295988921306, 3559.2525569630666, 3600.5499312585926, 3604.3151801157696, 6498.415142637185, 6517.9245424504425, 6525.706343214567, 6530.673466326664, 6577.144971153815, 6912.377885990285, 6921.446284297244, 6948.513604047769, 6970.734511283853, 6990.231074581894, 6512.511561632897, 6527.184101987252, 6531.444827189742, 6564.260470975195, 6577.408033277028, 6728.899066513908, 6800.693968771318, 6801.455883529405, 6810.284920083518, 6815.148106486689, 6672.930451991944, 6719.195775020884, 6724.596524208563, 6737.9326206737305, 6751.088603038527, 6516.218053833571, 6536.614135840479, 6544.116544266463, 6556.442702370161, 6577.6053436793945, 5489.615726899079, 5491.890162196813, 5506.614536982373, 5533.529649979797, 5539.0, 6486.2216534016225, 6514.674266286572, 6682.298358672269, 6687.230968438692, 6823.590584564942, 6441.806745797634, 6447.258565264867, 6510.7721229756735, 6564.9671751640935, 6601.798127907865, 6387.968060159669, 6424.357564243725, 6431.681505291311, 6449.193850768654, 6591.176102987127, 6402.93597064042, 6446.0975463434215, 6457.192850893638, 6546.312637173219, 6563.0, 6490.0, 6497.361477572575, 6533.967330163318, 6542.383046781326, 6608.173978252718, 6282.717277722422, 6282.780102696383, 6303.558750887577, 6311.400416960381, 6377.713002914973], "value": 5147.501610275839, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [909.4270609516004, 911.1935936696024, 928.7863791327994, 929.8140371925608, 934.2806039349699, 18565.0, 19308.71036934447, 19313.99999999998, 19327.323543675975, 20881.477963050933, 21692.89153554234, 22576.88711556444, 22653.660195097087, 22687.0, 22694.88652556739, 968.2157452463492, 987.1017374189489, 989.7476143583376, 990.2127808392345, 992.5632721602508, 1013.229945241618, 1056.4136904018267, 1056.587930707021, 1057.7778666480049, 1058.2169194795833, 1112.7996960547098, 1118.1893127482551, 1119.306030261235, 1119.322809700128, 1123.915706322025, 1111.3443068589531, 1115.4924509348227, 1118.552578968411, 1118.7091356247433, 1118.932864028157, 816.4978538198965, 818.4066551749993, 818.5579786915118, 818.6807145213371, 818.7748369198458, 19723.408297750946, 19727.112279947436, 19777.0, 19872.205111795654, 20134.496637584176, 21211.893940530354, 21298.467538311666, 21632.567348653098, 21679.45801354963, 21939.451513712127, 16172.11053392063, 16272.18639068042, 16356.0, 16399.672006559922, 16410.17949102552, 3563.5367402237544, 3585.4980302757376, 3588.3899737044503, 3590.0, 3599.784012959231, 128215.0, 129189.0, 129388.0, 131316.3434182823, 132307.0, 16935.407260745815, 17047.91476042612, 17085.829141708426, 17132.14339283031, 17496.91251543734, 30378.392432151235, 30936.381272374427, 31019.84490077535, 31486.527702084488, 31717.841410792797, 7702.114256860533, 8067.677292908218, 8354.58227088643, 8583.527905965231, 8983.640654373752, 4256.084941737541, 4346.521882592912, 4349.108432771274, 4350.21696094706, 4373.934390984138, 5175.146100893386, 5239.449857764956, 5241.947580524222, 5284.39229488606, 5304.363476382859, 1063.4044934836515, 1087.9292845964987, 1094.3105843318658, 1104.3981030338325, 1108.512254607986, 664.5481072870494, 676.5974245324024, 683.5249501596442, 687.88993760998, 688.4079691465336, 10903.727406814893, 11776.70558236051, 12184.20802647825, 12395.814062789066, 12458.563950261698, 676.6684324680871, 684.849333146707, 696.5820507695342, 700.8247938015496, 709.6593635055202, 5250.868728281824, 5601.663900166003, 5700.828975130753, 5773.278340207474, 5834.154047663065, 102379.48810255901, 106463.0, 107235.46382268195, 109204.03346921362, 109876.4506177464, 94528.52735736278, 94724.0, 97071.0, 97790.0, 98671.0, 82010.58994704988, 84151.0, 84888.57555712182, 87502.56248718589, 87799.0, 12203.816942746043, 12270.570530031406, 12358.196717213352, 12599.87400125987, 13192.340382981005, 41268.79365603273, 42613.14773704571, 42647.147057058064, 43102.78448607737, 44059.77970110129, 73435.0, 75691.0, 76377.61811190909, 76918.6154069226, 81033.59483202783, 18314.908425457787, 18514.814851851846, 18529.166187521325, 19280.132394042022, 19310.420687379403, 18698.325418645338, 19093.713594296376, 19468.707969380754, 19499.415017549498, 19582.90208548948, 17717.46847594574, 17816.376426824998, 17835.0, 17854.821451785316, 17939.37212197567, 18417.631647367245, 18497.352592659732, 18501.72247416263, 18555.443336699922, 18558.721619175452, 18129.728054078936, 18351.724724129417, 18537.721934170728, 18573.164207610418, 18911.338103166323, 12230.510779568718, 12653.493860245488, 13008.284544350152, 13103.541376051793, 13908.443662253396, 14097.09689191987, 14266.358013889607, 14566.0, 14625.634359141106, 14630.561083167522], "value": 9300.636890569816, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1546.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 108.0, 105.0, 108.0, 110.0, 114.0, 112.0, 97.0, 109.0, 108.0], "value": 107.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 122.0, 146.0, 171.0, 120.0, 153.0, 147.0, 119.0, 176.0, 116.0], "value": 141.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 162.0, 163.0, 163.0, 166.0, 163.0, 177.0, 163.0, 165.0], "value": 164.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 76.0, 171.0, 76.0, 78.0, 75.0, 83.0, 159.0, 76.0], "value": 95.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 126.0, 82.0, 79.0, 79.0, 127.0, 78.0, 80.0, 129.0], "value": 93.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 120.0, 114.0, 114.0, 116.0, 114.0, 117.0, 120.0, 117.0], "value": 115.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 151.0, 150.0, 151.0, 154.0, 151.0, 155.0, 155.0, 156.0], "value": 152.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 99.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 101.0], "value": 96.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 54.0, 53.0, 58.0, 58.0, 52.0, 53.0, 58.0, 54.0, 53.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 58.0, 55.0, 59.0, 55.0, 60.0, 54.0, 60.0], "value": 56.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 76.0, 81.0, 78.0, 77.0, 83.0, 83.0, 77.0, 78.0], "value": 79.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 133.0, 124.0, 133.0, 133.0, 130.0, 130.0, 138.0, 127.0, 180.0], "value": 135.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 174.0, 188.0, 175.0, 176.0, 191.0, 181.0, 185.0, 184.0, 178.0], "value": 182.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 67.0, 73.0, 67.0, 68.0, 69.0, 68.0, 76.0, 69.0, 66.0], "value": 69.5, "unit": "score"}]}]} 15:46:21 INFO - Return code: 0 15:46:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:46:21 INFO - # TBPL SUCCESS # 15:46:21 INFO - Running post-action listener: _package_coverage_data 15:46:21 INFO - Running post-action listener: _resource_record_post_action 15:46:21 INFO - [mozharness: 2017-08-12 22:46:21.679780Z] Finished run-tests step (success) 15:46:21 INFO - Running post-run listener: _resource_record_post_run 15:46:21 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 626688; Write bytes: 440160256; Read time: 336; Write time: 491648 15:46:21 INFO - TinderboxPrint: CPU usage
13.2% 15:46:21 INFO - TinderboxPrint: I/O read bytes / time
626,688 / 336 15:46:21 INFO - TinderboxPrint: I/O write bytes / time
440,160,256 / 491,648 15:46:21 INFO - TinderboxPrint: CPU idle
8,279.0 (86.8%) 15:46:21 INFO - TinderboxPrint: CPU user
1,203.5 (12.6%) 15:46:21 INFO - TinderboxPrint: Swap in / out
0 / 0 15:46:21 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 114700288; Read time: 0; Write time: 165520 15:46:21 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:46:21 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 622592; Write bytes: 324337664; Read time: 320; Write time: 326092 15:46:22 INFO - Running post-run listener: _upload_blobber_files 15:46:22 INFO - Blob upload gear active. 15:46:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:46:22 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:46:22 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:46:22 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:46:22 INFO - (blobuploader) - INFO - Open directory for files ... 15:46:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:46:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:46:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:46:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:23 INFO - (blobuploader) - INFO - Done attempting. 15:46:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:46:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:46:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:46:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:24 INFO - (blobuploader) - INFO - Done attempting. 15:46:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:46:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:46:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:46:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:30 INFO - (blobuploader) - INFO - Done attempting. 15:46:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:46:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:46:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:46:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:46:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:46:34 INFO - (blobuploader) - INFO - Done attempting. 15:46:34 INFO - (blobuploader) - INFO - Iteration through files over. 15:46:34 INFO - Return code: 0 15:46:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:46:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:46:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eed42fad8513381ba5f205eebbc3c61dd4a00e2a1d267436560228447cd38ad536defd864f6575cb27db21a9e789ff25e533ccc9b257ed367c5dc5a96b33fb62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1a1988cd9da8a5f94efbdbce3ebf61a391301bb25bbbd39ad6a8f38d77261362168ee7a31e12c31ccff3f4346f210aeb6b5429a657c74b03685d1b5bb9a50e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea917ec1ee9de9c8b2e6e87fbca043b4edd31766990678a0a9af0f3139607cbab0085f106b0a3b757ce96cac9d3617bf8d88243d75af1f85d3d2ec4fa2a8047f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3cc7f619bc3c464de934ffd21568cc92ba9b8f3d225cb629cdba5a3b994e04b2bff020bcf31dd071f9a7569a497df1e93c6b90164e1a29e16b73d45de5622f84"} 15:46:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:46:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:46:34 INFO - Contents: 15:46:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eed42fad8513381ba5f205eebbc3c61dd4a00e2a1d267436560228447cd38ad536defd864f6575cb27db21a9e789ff25e533ccc9b257ed367c5dc5a96b33fb62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1a1988cd9da8a5f94efbdbce3ebf61a391301bb25bbbd39ad6a8f38d77261362168ee7a31e12c31ccff3f4346f210aeb6b5429a657c74b03685d1b5bb9a50e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea917ec1ee9de9c8b2e6e87fbca043b4edd31766990678a0a9af0f3139607cbab0085f106b0a3b757ce96cac9d3617bf8d88243d75af1f85d3d2ec4fa2a8047f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3cc7f619bc3c464de934ffd21568cc92ba9b8f3d225cb629cdba5a3b994e04b2bff020bcf31dd071f9a7569a497df1e93c6b90164e1a29e16b73d45de5622f84"} 15:46:34 INFO - Running post-run listener: copy_logs_to_upload_dir 15:46:34 INFO - Copying logs to upload dir... 15:46:34 INFO - mkdir: /builds/slave/test/build/upload/logs 15:46:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.569355 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-08-12 15:46:34.139287) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-12 15:46:34.143593) ========= 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-1502556599.908738-475162972 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/eed42fad8513381ba5f205eebbc3c61dd4a00e2a1d267436560228447cd38ad536defd864f6575cb27db21a9e789ff25e533ccc9b257ed367c5dc5a96b33fb62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1a1988cd9da8a5f94efbdbce3ebf61a391301bb25bbbd39ad6a8f38d77261362168ee7a31e12c31ccff3f4346f210aeb6b5429a657c74b03685d1b5bb9a50e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea917ec1ee9de9c8b2e6e87fbca043b4edd31766990678a0a9af0f3139607cbab0085f106b0a3b757ce96cac9d3617bf8d88243d75af1f85d3d2ec4fa2a8047f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3cc7f619bc3c464de934ffd21568cc92ba9b8f3d225cb629cdba5a3b994e04b2bff020bcf31dd071f9a7569a497df1e93c6b90164e1a29e16b73d45de5622f84"} build_url:https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011214 build_url: 'https://queue.taskcluster.net/v1/task/Yx2HVl5oTtyjs-iuTJgx8w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eed42fad8513381ba5f205eebbc3c61dd4a00e2a1d267436560228447cd38ad536defd864f6575cb27db21a9e789ff25e533ccc9b257ed367c5dc5a96b33fb62", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1a1988cd9da8a5f94efbdbce3ebf61a391301bb25bbbd39ad6a8f38d77261362168ee7a31e12c31ccff3f4346f210aeb6b5429a657c74b03685d1b5bb9a50e8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ea917ec1ee9de9c8b2e6e87fbca043b4edd31766990678a0a9af0f3139607cbab0085f106b0a3b757ce96cac9d3617bf8d88243d75af1f85d3d2ec4fa2a8047f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3cc7f619bc3c464de934ffd21568cc92ba9b8f3d225cb629cdba5a3b994e04b2bff020bcf31dd071f9a7569a497df1e93c6b90164e1a29e16b73d45de5622f84"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-12 15:46:34.171161) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 15:46:34.171477) ========= 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-1502556599.908738-475162972 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004395 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-12 15:46:34.253037) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-12 15:46:34.256388) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-12 15:46:34.256673) ========= ========= Total master_lag: 0.24 =========